<div class="gmail_quote"><br><div><div></div><div class="h5"><br><div class="gmail_quote"><div class="gmail_quote">Hi All,<br><br>In an INVITE transaction, no ACK from A party and B party continue retransmission of 200 OK. I have observed that kamailio is not passing the retransmitted 200 OK message from B party to the Application(script) after 3 seconds. Instead kamailio directly sending to A party without passing to the script.<br>
<br><div><span><font face="Arial" size="2"><div>I see in the logs
that kamailio is cleaning up the transaction after 3 seconds..look for the
string "DEBUG: t_lookup_request: no transaction found" before this log it is
cleaning up the timer in the log "DEBUG: cleanup_uac_timers: RETR/FR timers
reset"<br><br>What does it mean by that?? <br>How can we make the kamailio to pass the response to the application? <br><br></div>Please let me know your comments.. Here is the flow. I cannot send the complete logs which is quite big. so i grep'd using the stirng "tcp_read_req: content-length|get_hdr_field|t_reply_matching|cleanup_uac_timers|RR11" from the complete log.. <br>
</font></span></div><div><br>A kamailo B<br>
<br>----INVITE-->- --------------------INVITE<br><br>180 Ringing--<---------------180 Ringing<br>200 OK-----------<---------------200 OK 0<br>200 OK-----------<---------------200 OK 500ms<br>200 OK-----------<---------------200 OK 1s<br>
200 OK-----------<---------------200 OK 2s<br><br>< From here it is not passing to the application- script><br><br>200 OK-----------<---------------200 OK 4s<br>200 OK-----------<---------------200 OK 4s<br>
<br><br><br></div> 28 102:2011-06-13T19:47:37+01:00 [debug] sipserver: [26177] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [50]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
36 95:2011-06-13T19:47:37+01:00 [debug] sipserver: [26177] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 267 86:2011-06-13T19:47:37+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 0<br>
282 102:2011-06-13T19:47:37+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
284 95:2011-06-13T19:47:37+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 285 89:2011-06-13T19:47:37+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
286 90:2011-06-13T19:47:38+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1018]: DEBUG: t_reply_matching: reply matched (T=0xb0f62e50)!<br> 292 96:2011-06-13T19:47:38+01:00 [info] sipserver: [26269] INFO: <script>: CI=<a href="mailto:1-8028@192.168.243.150" target="_blank">1-8028@192.168.243.150</a> -RR11 - Entry M=INVITE RURI=<null> From=<a href="mailto:sip%3A5616990103@192.168.243.150" target="_blank">sip:5616990103@192.168.243.150</a> To=<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a> RD= <null> IP=192.168.243.161 Code:180 Reason=Ringing CT=<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a> <sip:<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a>@192.168.243.161:5060;transport=tcp><br>
331 86:2011-06-13T19:47:39+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 346 102:2011-06-13T19:47:39+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
348 95:2011-06-13T19:47:39+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 349 89:2011-06-13T19:47:39+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
350 90:2011-06-13T19:47:39+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1018]: DEBUG: t_reply_matching: reply matched (T=0xb0f62e50)!<br> 356 96:2011-06-13T19:47:39+01:00 [info] sipserver: [26269] INFO: <script>: CI=<a href="mailto:1-8028@192.168.243.150" target="_blank">1-8028@192.168.243.150</a> -RR11 - Entry M=INVITE RURI=<null> From=<a href="mailto:sip%3A5616990103@192.168.243.150" target="_blank">sip:5616990103@192.168.243.150</a> To=<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a> RD= <null> IP=192.168.243.161 Code:200 Reason=OK CT=<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a> <sip:<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a>@192.168.243.161:5060;transport=tcp><br>
398 89:2011-06-13T19:47:39+01:00 [debug] sipserver: [26269] DEBUG: tm [t_reply.c:1467]: DEBUG: cleanup_uac_timers: RETR/FR timers reset<br> 406 86:2011-06-13T19:47:40+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br>
421 102:2011-06-13T19:47:40+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
423 95:2011-06-13T19:47:40+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 424 89:2011-06-13T19:47:40+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
425 90:2011-06-13T19:47:40+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1018]: DEBUG: t_reply_matching: reply matched (T=0xb0f62e50)!<br> 431 96:2011-06-13T19:47:40+01:00 [info] sipserver: [26269] INFO: <script>: CI=<a href="mailto:1-8028@192.168.243.150" target="_blank">1-8028@192.168.243.150</a> -RR11 - Entry M=INVITE RURI=<null> From=<a href="mailto:sip%3A5616990103@192.168.243.150" target="_blank">sip:5616990103@192.168.243.150</a> To=<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a> RD= <null> IP=192.168.243.161 Code:200 Reason=OK CT=<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a> <sip:<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a>@192.168.243.161:5060;transport=tcp><br>
481 86:2011-06-13T19:47:41+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 496 102:2011-06-13T19:47:41+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
498 95:2011-06-13T19:47:41+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 499 89:2011-06-13T19:47:41+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
500 90:2011-06-13T19:47:41+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1018]: DEBUG: t_reply_matching: reply matched (T=0xb0f62e50)!<br> 506 96:2011-06-13T19:47:41+01:00 [info] sipserver: [26269] INFO: <script>: CI=<a href="mailto:1-8028@192.168.243.150" target="_blank">1-8028@192.168.243.150</a> -RR11 - Entry M=INVITE RURI=<null> From=<a href="mailto:sip%3A5616990103@192.168.243.150" target="_blank">sip:5616990103@192.168.243.150</a> To=<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a> RD= <null> IP=192.168.243.161 Code:200 Reason=OK CT=<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a> <sip:<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a>@192.168.243.161:5060;transport=tcp><br>
556 86:2011-06-13T19:47:43+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 571 102:2011-06-13T19:47:43+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
573 95:2011-06-13T19:47:43+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 574 89:2011-06-13T19:47:43+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
575 90:2011-06-13T19:47:43+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1018]: DEBUG: t_reply_matching: reply matched (T=0xb0f62e50)!<br> 581 96:2011-06-13T19:47:43+01:00 [info] sipserver: [26269] INFO: <script>: CI=<a href="mailto:1-8028@192.168.243.150" target="_blank">1-8028@192.168.243.150</a> -RR11 - Entry M=INVITE RURI=<null> From=<a href="mailto:sip%3A5616990103@192.168.243.150" target="_blank">sip:5616990103@192.168.243.150</a> To=<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a> RD= <null> IP=192.168.243.161 Code:200 Reason=OK CT=<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a> <sip:<a href="tel:5616990102" value="+15616990102" target="_blank">5616990102</a>@192.168.243.161:5060;transport=tcp><br>
631 86:2011-06-13T19:47:47+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 646 102:2011-06-13T19:47:47+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
648 95:2011-06-13T19:47:47+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 649 89:2011-06-13T19:47:47+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
650 90:2011-06-13T19:47:47+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1052]: DEBUG: t_reply_matching: no matching transaction exists<br> 651 90:2011-06-13T19:47:47+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1055]: DEBUG: t_reply_matching: failure to match a transaction<br>
693 86:2011-06-13T19:47:51+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 708 102:2011-06-13T19:47:51+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
710 95:2011-06-13T19:47:51+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 711 89:2011-06-13T19:47:51+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
712 90:2011-06-13T19:47:51+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1052]: DEBUG: t_reply_matching: no matching transaction exists<br> 713 90:2011-06-13T19:47:51+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1055]: DEBUG: t_reply_matching: failure to match a transaction<br>
755 86:2011-06-13T19:47:55+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 770 102:2011-06-13T19:47:55+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
772 95:2011-06-13T19:47:55+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 773 89:2011-06-13T19:47:55+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
774 90:2011-06-13T19:47:55+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1052]: DEBUG: t_reply_matching: no matching transaction exists<br> 775 90:2011-06-13T19:47:55+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1055]: DEBUG: t_reply_matching: failure to match a transaction<br>
817 86:2011-06-13T19:47:59+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 832 102:2011-06-13T19:47:59+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
834 95:2011-06-13T19:47:59+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 835 89:2011-06-13T19:47:59+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
836 90:2011-06-13T19:47:59+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1052]: DEBUG: t_reply_matching: no matching transaction exists<br> 837 90:2011-06-13T19:47:59+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1055]: DEBUG: t_reply_matching: failure to match a transaction<br>
879 86:2011-06-13T19:48:03+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 894 102:2011-06-13T19:48:03+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
896 95:2011-06-13T19:48:03+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 897 89:2011-06-13T19:48:03+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
898 90:2011-06-13T19:48:03+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1052]: DEBUG: t_reply_matching: no matching transaction exists<br> 899 90:2011-06-13T19:48:03+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1055]: DEBUG: t_reply_matching: failure to match a transaction<br>
941 86:2011-06-13T19:48:07+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 956 102:2011-06-13T19:48:07+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
958 95:2011-06-13T19:48:07+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 959 89:2011-06-13T19:48:07+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
960 90:2011-06-13T19:48:07+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1052]: DEBUG: t_reply_matching: no matching transaction exists<br> 961 90:2011-06-13T19:48:07+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1055]: DEBUG: t_reply_matching: failure to match a transaction<br>
1003 86:2011-06-13T19:48:11+01:00 [debug] sipserver: [26269] DEBUG: <core> [tcp_read.c:933]: tcp_read_req: content-length= 191<br> 1018 102:2011-06-13T19:48:11+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:185]: DEBUG: get_hdr_field: <To> [65]; uri=[<a href="http://sip:5616990102@192.168.243.151:5060" target="_blank">sip:5616990102@192.168.243.151:5060</a>] <br>
1020 95:2011-06-13T19:48:11+01:00 [debug] sipserver: [26269] DEBUG: <core> [parser/msg_parser.c:165]: get_hdr_field: cseq <CSeq>: <1> <INVITE><br> 1021 89:2011-06-13T19:48:11+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 5084 label 418362480 branch 0<br>
1022 90:2011-06-13T19:48:11+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1052]: DEBUG: t_reply_matching: no matching transaction exists<br> 1023 90:2011-06-13T19:48:11+01:00 [debug] sipserver: [26269] DEBUG: tm [t_lookup.c:1055]: DEBUG: t_reply_matching: failure to match a transaction<br>
<br><br><br>
<br><br>
</div><br>
</div><br>
</div></div></div><br>