Hi *,<br><br>Here is the trace. One thing I have noticed is that the 180 Ringing being returned from the Snom phone (behind the NAT) contains the contact header still with the private IP (192.168.1.100). Theoretically though I don't see how this can affect the transaction handling - maybe you can shed some light.<br>
<br> <br>********------------------------------------- ROUTE(5) -----------------------------------********<br><br>********------------------------------------ PROXY-AUTH -----------------------------------********<br>
<snip><br>********---------------------------------- PROXY-AUTH DONE --------------------------------********<br><br>********---------------------------------- ROUTE(5) DONE --------------------------------********<br>
<br><br>********---------------------------------- ROUTE(3) -----------------------------------********<br><br>********--------------------------------- RECORD-ROUTE ---------------------------------********<br>********------------------------------- RECORD-ROUTE DONE ------------------------------********<br>
<br>********------------------------------- ROUTE(3) DONE --------------------------------********<br><br><br>********---------------------------------- ROUTE(4) -----------------------------------********<br><br>********------------------------------------ INVITE ------------------------------------********<br>
Jan 30 11:31:34 [11258] INFO:dialog:dlg_created_cb: dialog created or destroyed<br>Jan 30 11:31:34 [11258] INFO:dialog:dlg_created_cb: CALLID IS [3c268b5bbf2d-mtnq42l2osup]------------------------------------------------------------------------------------------------------<br>
********--------------------------------- INVITE DONE ----------------------------------********<br><br>********------------------------------- ROUTE(4) DONE --------------------------------********<br><br><br>********------------------------------------- ROUTE(6) --------------------------------------------********<br>
<br>********-------------------------------------- USRLOC ----------------------------------------------********<br><snip><br>********-------------------------------------- USRLOC DONE -----------------------------------------********<br>
<br>********------------------------------------ ROUTE(6) DONE ---------------------------------------********<br><br><br>********---------------------------------- ROUTE(1) ------------------------------------********<br>
<br>********--------------------------------- INVITE ---------------------------------********<br>********---------------------------------- T_RELAY -------------------------------------********<br>[INVITE] BRANCH-ROUTE[2] New branch at [<a href="mailto:sip%3AS001e310003ee@41.2.192.203">sip:S001e310003ee@41.2.192.203</a>] (RURI=[<a href="mailto:sip%3A0870000005@abcd.com">sip:0870000005@abcd.com</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
<br>********------------------------------- ROUTE(1) DONE -----------------------------------********<br>[INVITE] INCOMING-REPLY[2] (RURI=[<a href="mailto:sip%3A0870000005@abcd.com">sip:0870000005@abcd.com</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
[INVITE] INCOMING-REPLY[2] DLG_TIMEOUT=[43199] (RURI=[<a href="mailto:sip%3A0870000005@abcd.com">sip:0870000005@abcd.com</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
Jan 30 11:31:34 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>statistics: [tm_rld_rpls] : [1]<br>Jan 30 11:31:34 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>Jan 30 11:31:34 [11258] INFO:dialog:dlg_onreply: new_state = DLG_STATE_EARLY.<br>
Jan 30 11:31:34 [11258] INFO:dialog:dlg_onreply: old_state!=DLG_STATE_EARLY.<br>Jan 30 11:31:34 [11258] INFO:dialog:dlg_onreply: Checking for RINGING [180] PARAM-CODE [180].<br><snip><br>********---------------------------------- ROUTE(2) -----------------------------------********<br>
<br>********------------------------------------ TO-TAG -------------------------------------********<br>********---------------------------------- LOOSE-ROUTE ---------------------------------********<br>********---------------------------------- TO-TAG DONE ----------------------------------********<br>
<br>********-------------------------------- ROUTE(2) DONE --------------------------------********<br><br><br>********---------------------------------- ROUTE(1) ------------------------------------********<br><br>********---------------------------------- T_RELAY -------------------------------------********<br>
********------------------------------- ROUTE(1) DONE -----------------------------------********<br><br>[PRACK] INCOMING-REPLY[2] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
[PRACK] INCOMING-REPLY[2] DLG_TIMEOUT=[43199] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
Jan 30 11:31:35 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>statistics: [tm_rld_rpls] : [1]<br>Jan 30 11:31:35 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>Jan 30 11:31:35 [11258] INFO:dialog:dlg_onreply: new_state = DLG_STATE_EARLY.<br>
statistics: [rcv_reqs] : [1]<br>********---------------------------------- ROUTE(2) -----------------------------------********<br><br>********------------------------------------ TO-TAG -------------------------------------********<br>
********---------------------------------- LOOSE-ROUTE ---------------------------------********<br>********---------------------------------- TO-TAG DONE ----------------------------------********<br><br>********-------------------------------- ROUTE(2) DONE --------------------------------********<br>
<br><br>********---------------------------------- ROUTE(1) ------------------------------------********<br><br>********---------------------------------- T_RELAY -------------------------------------********<br>[PRACK] INCOMING-REPLY[2] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
[PRACK] INCOMING-REPLY[2] DLG_TIMEOUT=[43199] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
Jan 30 11:31:36 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>statistics: [tm_rld_rpls] : [1]<br>Jan 30 11:31:36 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>Jan 30 11:31:36 [11258] INFO:dialog:dlg_onreply: new_state = DLG_STATE_EARLY.<br>
statistics: [rcv_reqs] : [1]<br>********---------------------------------- ROUTE(2) -----------------------------------********<br><br>********------------------------------------ TO-TAG -------------------------------------********<br>
********---------------------------------- LOOSE-ROUTE ---------------------------------********<br>********---------------------------------- TO-TAG DONE ----------------------------------********<br><br>********-------------------------------- ROUTE(2) DONE --------------------------------********<br>
<br><br>********---------------------------------- ROUTE(1) ------------------------------------********<br><br>********---------------------------------- T_RELAY -------------------------------------********<br>[PRACK] INCOMING-REPLY[2] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
[PRACK] INCOMING-REPLY[2] DLG_TIMEOUT=[43199] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
Jan 30 11:31:38 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>statistics: [tm_rld_rpls] : [1]<br>Jan 30 11:31:38 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>Jan 30 11:31:38 [11258] INFO:dialog:dlg_onreply: new_state = DLG_STATE_EARLY.<br>
statistics: [rcv_reqs] : [1]<br>********---------------------------------- ROUTE(2) -----------------------------------********<br><br>********------------------------------------ TO-TAG -------------------------------------********<br>
********---------------------------------- LOOSE-ROUTE ---------------------------------********<br>********---------------------------------- TO-TAG DONE ----------------------------------********<br><br>********-------------------------------- ROUTE(2) DONE --------------------------------********<br>
<br><br>********---------------------------------- ROUTE(1) ------------------------------------********<br><br>********---------------------------------- T_RELAY -------------------------------------********<br>[PRACK] INCOMING-REPLY[2] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
[PRACK] INCOMING-REPLY[2] DLG_TIMEOUT=[43199] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
Jan 30 11:31:42 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>statistics: [tm_rld_rpls] : [1]<br>Jan 30 11:31:42 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>Jan 30 11:31:42 [11258] INFO:dialog:dlg_onreply: new_state = DLG_STATE_EARLY.<br>
statistics: [rcv_reqs] : [1]<br>*******---------------------------------- ROUTE(2) -----------------------------------********<br><br>********------------------------------------ TO-TAG -------------------------------------********<br>
********---------------------------------- LOOSE-ROUTE ---------------------------------********<br>********---------------------------------- TO-TAG DONE ----------------------------------********<br><br>********-------------------------------- ROUTE(2) DONE --------------------------------********<br>
<br><br>********---------------------------------- ROUTE(1) ------------------------------------********<br><br>********---------------------------------- T_RELAY -------------------------------------********<br>[PRACK] INCOMING-REPLY[2] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
[PRACK] INCOMING-REPLY[2] DLG_TIMEOUT=[43199] (RURI=[<a href="http://sip:S001e310003ee@192.168.1.100:5060">sip:S001e310003ee@192.168.1.100:5060</a>] F=[<a href="mailto:sip%3AS000413292efd@abcd.com">sip:S000413292efd@abcd.com</a>] T=[sip:0870000005*<a href="mailto:47942@abcd.com">47942@abcd.com</a>;user=phone] IP=[192.168.2.174] ID=[3c268b5bbf2d-mtnq42l2osup]) <br>
Jan 30 11:31:42 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>statistics: [tm_trans_2xx] : [1]<br>statistics: [tm_rld_rpls] : [1]<br>Jan 30 11:31:42 [11258] INFO:dialog:dlg_onreply: dlg_onreply called<br>Jan 30 11:31:42 [11258] INFO:dialog:dlg_onreply: new_state==DLG_STATE_CONFIRMED_NA && old_state!=DLG_STATE_CONFIRMED_NA && old_state!=DLG_STATE_CONFIRMED.<br>
Jan 30 11:31:42 [11258] INFO:dialog:dlg_update_cb: ######################dialog updated##############################<br>Jan 30 11:31:42 [11258] INFO:dialog:dlg_update_cb: Confirmed DID fbda14b0 session and source ip is [<a href="http://sip:S000413292efd@192.168.2.174:5060">sip:S000413292efd@192.168.2.174:5060</a>] and dst ip is [<a href="http://sip:S001e310003ee@41.2.192.203:5060">sip:S001e310003ee@41.2.192.203:5060</a>] and callid is [3c268b5bbf2d-mtnq42l2osup]<br>
*******---------------------------------- ROUTE(2) -----------------------------------********<br><br>********------------------------------------ TO-TAG -------------------------------------********<br>********---------------------------------- LOOSE-ROUTE ---------------------------------********<br>
********---------------------------------- TO-TAG DONE ----------------------------------********<br><br>********-------------------------------- ROUTE(2) DONE --------------------------------********<br><br><br>********---------------------------------- ROUTE(1) ------------------------------------********<br>
<br>********---------------------------------- T_RELAY -------------------------------------********<br>********------------------------------- ROUTE(1) DONE -----------------------------------********<br><br><div class="gmail_quote">
<blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;"><div><div class="Wj3C7c"><br>
<br>
On 01/30/2009 09:00 AM, Jason Penton wrote:<br>
<blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;">
Hi *,<br>
<br>
What is the logic behind the transation matching in Kamailio. I am finding a problem whereby a call setup transaction (INVITE, 180 Ringing, PRACK, 200OK, ACK). between 2 Snom phones works in normal mode. However, when one phone is behind a NAT with STUN support, everything works perfectly (i.e. call setup, 2-way audio, etc), BUT the tm module doesnt match the transaction between the original invite and the subsequent messages (180, 200ok, etc). the impact here is that the fr_inv_timer eventually fires even though the call has been answered. Note: this only happens when the call is destined for the device behind the NAT.<br>
<br>
Any ideas.<br>
<br>
Perhaps an explanation on how the tm module does transaction manager.<br>
<br>
RFC3261 states that transaction matching is done based on the via branch parameter.<br>
</blockquote></div></div>
it is the way tm does it. Can you paste the sip trace of such situation? It will help to troubleshoot.<br>
<br>
Cheers,<br>
Daniel<br><font color="#888888">
<br>
-- <br>
Daniel-Constantin Mierla<br>
<a href="http://www.asipto.com" target="_blank">http://www.asipto.com</a><br>
<br>
</font></blockquote></div><br>