Hello Daniel,

Please find in attached file the debug 3 log.
Failed call with Call-ID: 4-21712@10.25.153.150.
The problem occurred with all modes of "dlg_match_mode "
The same load test in 3.3 executed without failures.

Thank you,

Julia.

On Thu, Jan 8, 2015 at 2:06 AM, Daniel-Constantin Mierla <miconda@gmail.com> wrote:
Hello,

can you send all the log messages with debug=3? You selected some in your previous post, but are not enough to get the entire picture of what messages, transactions and dialogs are processed. You can make a tgz and send it to me directly if the size is too big.

Cheers,
Daniel


On 16/12/14 16:01, Julia Boudniatsky wrote:
Hello Daniel,
Thank you for your reply.

We set module dialog with:
modparam("dialog", "detect_spirals", 1)
and
modparam("dialog", "dlg_match_mode", 1)

I analyze log and found that 
"parse_to_param(): DEBUG: add_param: tag=" is missing in case of wrong dialog allocation

correct dialog
===========
dialog::  hash=1561:6164
        state:: 1
        ref_count:: 1
        timestart:: 0
        timeout:: 0
        callid:: 8-12006@10.25.153.150
        from_uri:: sip:039988222@10.25.153.150;user=phone
        from_tag:: 8
        caller_contact:: sip:10.25.153.150:5062
        caller_cseq:: 1
        caller_route_set:: 
        caller_bind_addr:: udp:10.25.153.150:5060
        callee_bind_addr:: 
        to_uri:: sip:D39539988200@10.25.153.150;user=phone
        to_tag:: 
        callee_contact:: 
        callee_cseq:: 
        callee_route_set:: 

dialog detected in 301
================
dialog::  hash=3838:6551
        state:: 5
        ref_count:: 1
        timestart:: 1418731671
        timeout:: 0
        callid:: 7-12006@10.25.153.150
        from_uri:: sip:039988222@10.25.153.150;user=phone
        from_tag:: 7
        caller_contact:: sip:10.25.153.150:5062
        caller_cseq:: 1
        caller_route_set:: 
        caller_bind_addr:: udp:10.25.153.150:5060
        callee_bind_addr:: udp:10.25.153.150:5060
        to_uri:: sip:D39539988200@10.25.153.150;user=phone
        to_tag:: 177
        callee_contact:: sip:10.25.153.150:5061;transport=UDP
        callee_cseq:: 0

Log debug3

receive1  INVITE
===========

Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin//kamailio[5546]: DEBUG: dialog [dlg_hash.c:720]: internal_get_dlg(): no dialog callid='8-12006@10.25.153.150' found
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5546]: DEBUG: dialog [dlg_hash.c:751]: get_dlg(): no dialog callid='8-12006@10.25.153.150' found
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5546]: DEBUG: dialog [dlg_handlers.c:1083]: dlg_get_msg_dialog(): dlg with callid '8-12006@10.25.153.150' not found

dlg_manage();
==========
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5546]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fb318d34fc8 with 1 -> 2
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5546]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=6164 found on entry 1561

send INVITE to Server 1
================
receive from Server 1 (spirals) INVITE
================
different "To"  user - case of number translation

Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5541]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fb318d34fc8 with 1 -> 2
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5541]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=6164 found on entry 1561
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5541]: DEBUG: dialog [dlg_profile.c:427]: set_current_dialog(): setting current dialog [1561:6164]

send INVITE  to Server 2
================
"To" = "To" in INVITE to Server 1

Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5543]: DEBUG: dialog [dlg_hash.c:711]: internal_get_dlg(): ref dlg 0x7fb318d34fc8 with 1 -> 2
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5543]: DEBUG: dialog [dlg_hash.c:714]: internal_get_dlg(): dialog callid='8-12006@10.25.153.150' found
 on entry 1561, dir=1

100 from Server 1
============
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5541]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fb318d34fc8 with 1 -> 3
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5541]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=6164 found on entry 1561

100 from Server 2
============
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5544]: DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
!!!!!   Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5544]: DEBUG: <core> [parser/parse_addr_spec.c:176]: parse_to_param(): DEBUG: add_param: tag=8
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5544]: DEBUG: <core> [parser/parse_addr_spec.c:898]: parse_addr_spec(): end of header reached, state=29
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5544]: DEBUG: dialog [dlg_hash.c:711]: internal_get_dlg(): ref dlg 0x7fb318d34fc8 with 1 -> 2
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5544]: DEBUG: dialog [dlg_hash.c:714]: internal_get_dlg(): dialog callid='8-12006@10.25.153.150' found
 on entry 1561, dir=1

301 from Server 2
=============

Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: <core> [receive.c:152]: receive_msg(): After parse_msg...
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=645 global id=644 T start=0xffffffffffffffff
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 13904 label 0 branch 0
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fb318dcfae8)!
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=645 global id=645 T end=0x7fb318dcfae8
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: tm [t_reply.c:2206]: reply_received(): DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=6551 found on entry 3838
Dec 16 14:07:49 KAMnet-JAF-TST-01 /usr/local/sbin/kamailio[5540]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7fb318d989c0 with 1 -> 2

BR,

Julia


On Tue, Dec 16, 2014 at 1:03 PM, Daniel-Constantin Mierla <miconda@gmail.com> wrote:
Hello,

looking again at your diagram, it seems that the Call-Id is the same, but there is a different dialog structure.

If you dump the dialogs with kamctl, can you see the two structure listed? Are they with same dialog attributes (From/To/...)?

Dialog module has a parameter for detecting spirals, have you set it to some value?

Cheers,
Daniel

On 10/12/14 13:39, Julia Boudniatsky wrote:
Hello Daniel,

I have tried from latest git branch 4.1 (I see your new patches for dialog )
But the problem steel occur.

Thank you,
Julia
 

On Wed, Dec 10, 2014 at 12:21 PM, Daniel-Constantin Mierla <miconda@gmail.com> wrote:
Hello,

can you try with latest git branch 4.1 and report the results? I just pushed a patch that should fix it.

Cheers,
Daniel


On 09/12/14 19:06, Julia Boudniatsky wrote:

After kamailio upgrade from v3.3 to v4.1.7

in production ~5% of calls with spirals dialog are failed with wrong dialog variables in on-reply/failure routes.

 

The problem has been reproduced in the LAB (load test generated by SIPP).

Please see the attached file with debug3 log.

Scenario:

                                     Proxy                      Location

 Kamailio P1                  Nbr translation                  Redirect

     | INVITE                         |                              |

     | dlg 0x7f8f8501aba0             |                              |

     | Call-ID:(5-8286@10.25.153.150) |                              |

     |------------------------------->|                              |

     | 100 Trying                     |                              |

     | dlg 0x7f8f8501aba0             |                              |

     | Call-ID:(5-8286@10.25.153.150) |                              |

     |<-------------------------------|                              |

     |                                |                              |

     | INVITE                         |                              |

     | dlg 0x7f8f8501aba0             |                              |

     | Call-ID:(5-8286@10.25.153.150) |                              |

     |<-------------------------------|                              |

     | 100 Trying                     |                              |

     | dlg 0x7f8f8501aba0             |                              |

     | Call-ID:(5-8286@10.25.153.150) |                              |

     |------------------------------->|                              |

     |                                |                              |

     | INVITE                         |                              |

     | dlg 0x7f8f8501aba0             |                              |

     | Call-ID:(5-8286@10.25.153.150)                                |    

     |-------------------------------------------------------------->|

     |                             100 Trying                        |

     |                                 dlg 0x7f8f8504cda0 !!!!!!!!   |

     |                                 Call-ID:(5-8286@10.25.153.150)|

     |<--------------------------------------------------------------|

     |                             301 Moved Permanently             |

     |                                 dlg 0x7f8f8504cda0 !!!!!!!!   |

     |                                 Call-ID:(5-8286@10.25.153.150)|

     |<--------------------------------------------------------------|                                  

     |                                                               |

 

Thank you,

Julia.



_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users@lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users


-- 
Daniel-Constantin Mierla
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda

_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users@lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users



-- 
Daniel-Constantin Mierla
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda

-- 
Daniel-Constantin Mierla
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda