<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 3.2//EN">
<HTML>
<HEAD>
<META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=us-ascii">
<META NAME="Generator" CONTENT="MS Exchange Server version 6.5.7651.11">
<TITLE>ACK for 487 'Request Terminated' fails</TITLE>
</HEAD>
<BODY>
<!-- Converted from text/rtf format -->

<P><FONT FACE="Times New Roman">Hello,<BR>
<BR>
I am using OpenSER v1.0.<BR>
<BR>
I have a problem with the following SIP scenario with openser:<BR>
<BR>
- A-Party invites B-Party to a call session. While B-Party is 180 'Ringing', A-Party CANCELs the call session that it first initiated<BR>
</FONT>

<BR><FONT FACE="Times New Roman">ACK messages for the 487 'Request Terminated' seems to fail to close the corresponding transaction as 487 continue to be sent multiple times until timeout. </FONT></P>

<P><FONT FACE="Times New Roman">The message trace shown below is part of the message trace produced for the above scenario displaying message frames in sequence F89 to F137. OpenSER is running on all proxies. </FONT></P>

<P><FONT FACE="Times New Roman">Initially everything works as expected: INVITEs, 100 'Trying's and 180 'Ringing' messages are routed correctly from A to B and from B to A via the intermediate proxies (this is not shown below). While still 'Ringing', A-Party CANCEL's the call (sends a CANCEL message) message: F107 in trace below. This also initally routes OK. </FONT></P>
<BR>

<P><FONT FACE="Times New Roman">But I expect the call flow for this scenario to stop at message: F130 once the 487 'Request Terminated' has routed its way back from the B-Party to the A-Party and a final 'ACK' is sent by the A-Party to the previous proxy: Proxy-1.<BR>
<BR>
But what actually occurs is that OpenSER running on Proxy-1 automatically resends a 487 'Request Terminated' at message F131 identical to the '487' it first sent at message F121. This in turn prompts further ACKs and 487s until it finally times out at message F186 (not shown below).<BR>
<BR>
I have also included part of the OpenSER message trace set at log level 9 which corresponds to ACK messages F130 and F132 received at proxy-A which do not appear to close the transaction of the preceding 487 'Request Terminated' messages - identical 487 'Request Terminate' messages are later resent from Proxy-1. </FONT></P>

<P><FONT FACE="Times New Roman">Can you help me to:<BR>
<BR>
- identify and resolve the problem of multiple resends of 487 'Request Terminated' messages by Proxy-1.&nbsp;<BR>
</FONT>

<BR><FONT FACE="Times New Roman">Best regards,<BR>
Andrew Augustin<BR>
</FONT>

<BR><FONT SIZE=2 FACE="Arial">-------</FONT>
</P>

<P><FONT SIZE=2 FACE="Courier New">A-Party&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; Proxy-1&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; Proxy-2&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; Proxy-3&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; B-Party</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10.1.195.134 10.1.195.134 127.0.0.1:50 127.0.0.1:50 10.1.195.208</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&gt;F89 INVITE&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&lt;us 100 F90&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F91 INVITE (sdp)-------&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;important to us 100 F92&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;INVITE F93&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F94 100 tr&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F95 INVITE&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;us 100 F96&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;-------(sdp) INVITE F97&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F98 100 trying -- your &gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F99 INVITE (sdp)--------------------&gt;|</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;-------------------- Trying 100 F100&lt;|</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;------------------- Ringing 180 F101&lt;|</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F102 180 Ringing ------&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;g 180 F103&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F104 180 R&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;------ Ringing 180 F105&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&lt;g 180 F106&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&lt;g 180 F106&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&gt;F107 CANCE&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F108 CANCEL -----------&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&lt;g 200 F109&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;ANCEL F110&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;---- canceling 200 F111&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F112 CANCE&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F113 200 c&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;----------- CANCEL F114&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;g 200 F115&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F116 CANCEL ------------------------&gt;|</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F117 200 canceling ----&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;-------- Request Terminated 487 F118&lt;|</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;------------------------ OK 200 F119&lt;|</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F120 ACK ---------------------------&gt;|</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F121 487 Request Termin&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;-------------- ACK F122&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;d 487 F123&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F124 ACK -&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F125 487 R&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;- ACK F126&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;est Terminated 487 F127&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F128 ACK --------------&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&lt;d 487 F129&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&gt;F130 ACK -&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F131 487 Request Termin&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;-------------- ACK F132&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&lt;d 487 F133&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&gt;F134 ACK -&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&gt;F135 487 Request Termin&gt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&lt;d 487 F136&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>

<BR><FONT SIZE=2 FACE="Courier New">|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |&lt;-------------- ACK F137&lt;|&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; |</FONT>
</P>

<P><FONT SIZE=2 FACE="Courier New">Part of OpenSER log file log-level 9 corresponding to ACK messages F130 and F132 from above trace:-</FONT>
</P>

<P><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: add_to_tail_of_timer[4]: 0xb60e0090</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: add_to_tail_of_timer[0]: 0xb60e00a0</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG:tm:relay_reply: sent buf=0x828b9c0: SIP/2.0 4..., shmem=0xb60e1530: SIP/2.0 4</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: cleanup_uac_timers: RETR/FR timers reset</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG:destroy_avp_list: destroying list (nil)</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) receive_msg: cleaning up</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) SIP Request:</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430)&nbsp; method:&nbsp; &lt;ACK&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430)&nbsp; uri:&nbsp;&nbsp;&nbsp;&nbsp; &lt;sip:testuser_b@generaltests.genericdomain&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430)&nbsp; version: &lt;SIP/2.0&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: flags=2</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) Found param type 232, &lt;branch&gt; = &lt;z9hG4bK1BCF7778&gt;; state=16</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) end of header reached, state=5</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: Via found, flags=2</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: this is the first via</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) After parse_msg...</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) preparing to run routing scripts...</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG : sl_filter_ACK: to late to be a local ACK!</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: flags=100</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) get_hdr_field: cseq &lt;CSeq&gt;: &lt;640&gt; &lt;ACK&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: add_param: tag=1F5CE0A1</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG:parse_to:end of header reached, state=29</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: get_hdr_field: &lt;To&gt; [83]; uri=[sip:testuser_b@generaltests.genericdomain] </FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: to body [&quot;testuser_b&quot; &lt;sip:testuser_b@generaltests.genericdomain&gt;]</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: get_hdr_body : content_length=0</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) found end of header</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: is_maxfwd_present: max_forwards header not found!</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: add_param: tag=1AE725BF</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG:parse_to:end of header reached, state=29</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: flags=200</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) find_first_route: No Route headers found</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) loose_route: There is no Route HF</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: t_check: msg id=12 global id=11 T start=0xffffffff</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: flags=ffffffffffffffff</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: flags=78</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) t_lookup_request: start searching: hash=18675, isACK=1</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: RFC3261 transaction matched, tid=1BCF7778</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: t_lookup_request: transaction found (T=0xb60dffe0)</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG: t_check: msg id=12 global id=12 T end=0xb60dffe0</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) parse_headers: flags=40</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) Warning: sl_send_reply: I won't send a reply for ACK!!</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) ERROR: Error while sending reply</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) DEBUG:destroy_avp_list: destroying list (nil)</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;4(12430) receive_msg: cleaning up</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: timer routine:1,tl=0xb60e1d44 next=0xb60e010c</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: timer routine:1,tl=0xb60e010c next=(nil)</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: timer routine:4,tl=0xb60e1cc8 next=0xb60e0090</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: retransmission_handler : reply resending (t=0xb60e1c18, SIP/2.0 4 ... )</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436)&nbsp; 3(12429) SIP Request:</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429)&nbsp; method:&nbsp; &lt;ACK&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429)&nbsp; uri:&nbsp;&nbsp;&nbsp;&nbsp; &lt;sip:testuser_b@generaltests.genericdomain&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">DEBUG:tm:t_retransmit_reply: buf=0x642a80: SIP/2.0 4..., shmem=0xb60e3f70: SIP/2.0 4</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: add_to_tail_of_timer[5]: 0xb60e1cc8</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: retransmission_handler : done</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: timer routine:4,tl=0xb60e0090 next=0xb60e5f34</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: retransmission_handler : reply resending (t=0xb60dffe0, SIP/2.0 4 ... )</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429)&nbsp; version: &lt;SIP/2.0&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) parse_headers: flags=2</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) Found param type 232, &lt;branch&gt; = &lt;z9hG4bK3f84.a2d5c811.0&gt;; state=16</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) end of header reached, state=5</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) parse_headers: Via found, flags=2</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) parse_headers: this is the first via</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) After parse_msg...</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) preparing to run routing scripts...</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG : sl_filter_ACK: to late to be a local ACK!</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436)&nbsp; 3(12429) parse_headers: flags=100</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: add_param: tag=1F5CE0A1</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG:parse_to:end of header reached, state=29</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: get_hdr_field: &lt;To&gt; [83]; uri=[sip:testuser_b@generaltests.genericdomain] </FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG:tm:t_retransmit_reply: buf=0x642a80: SIP/2.0 4..., shmem=0xb60e1530: SIP/2.0 4</FONT>

<BR><FONT SIZE=2 FACE="Courier New">DEBUG: to body [&quot;testuser_b&quot; &lt;sip:testuser_b@generaltests.genericdomain&gt;]</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) get_hdr_field: cseq &lt;CSeq&gt;: &lt;640&gt; &lt;ACK&gt;</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: get_hdr_body : content_length=0</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) found end of header</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: is_maxfwd_present: max_forwards header not found!</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: add_param: tag=1AE725BF</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG:parse_to:end of header reached, state=29</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) parse_headers: flags=200</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) find_first_route: No Route headers found</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) loose_route: There is no Route HF</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: t_check: msg id=14 global id=13 T start=0xffffffff</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) parse_headers: flags=ffffffffffffffff</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) parse_headers: flags=78</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) t_lookup_request: start searching: hash=18675, isACK=1</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: RFC3261 transaction matched, tid=3f84.a2d5c811.0</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: t_lookup_request: transaction found (T=0xb60e1c18)</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) DEBUG: t_check: msg id=14 global id=14 T end=0xb60e1c18</FONT>

<BR><FONT SIZE=2 FACE="Courier New">&nbsp;3(12429) parse_headers: flags=40</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: add_to_tail_of_timer[5]: 0xb60e0090</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: retransmission_handler : done</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: timer routine:4,tl=0xb60e5f34 next=0xb60e44a4</FONT>

<BR><FONT SIZE=2 FACE="Courier New">10(12436) DEBUG: timer routine:4,tl=0xb60e44a4 next=(nil)</FONT>
</P>
<BR>

</BODY>
</HTML>