[SR-Users] Wrong dialog selected in on_reply/failure route in case of spirals.
Julia Boudniatsky
juliabo at gmail.com
Wed Jan 14 14:43:23 CET 2015
Hello Daniel,
Please find in attached file the debug 3 log.
Failed call with Call-ID: 4-21712 at 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 at 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 at 10.25.153.150
> from_uri:: sip:039988222 at 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 at 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 at 10.25.153.150
> from_uri:: sip:039988222 at 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 at 10.25.153.150;user=phone
> to_tag:: 177
> callee_contact:: sip:10.25.153.150:5061;transport=UDP
> callee_cseq:: 0
> callee_route_set::
> <sip:10.25.154.104;lr;ftag=7;vst=AAAAAHQAAAwADw8MAHIBAG4DBQADAB0fBAYeCwAADUVIEAFYAlUfBgpuZQ-->
> ,
> <sip:10.25.153.150;lr;ftag=7;vst=AAAAAHQAAAAADw8HDnYFCG4DBQADAB0fBAYeCwAADUVIEAFYAlUfBgpuZQ-->
>
> 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 10.25.153.150) | |
>>>
>>> |------------------------------->| |
>>>
>>> | 100 Trying | |
>>>
>>> | dlg 0x7f8f8501aba0 | |
>>>
>>> | Call-ID:(5-8286 at 10.25.153.150) | |
>>>
>>> |<-------------------------------| |
>>>
>>> | | |
>>>
>>> | INVITE | |
>>>
>>> | dlg 0x7f8f8501aba0 | |
>>>
>>> | Call-ID:(5-8286 at 10.25.153.150) | |
>>>
>>> |<-------------------------------| |
>>>
>>> | 100 Trying | |
>>>
>>> | dlg 0x7f8f8501aba0 | |
>>>
>>> | Call-ID:(5-8286 at 10.25.153.150) | |
>>>
>>> |------------------------------->| |
>>>
>>> | | |
>>>
>>> | INVITE | |
>>>
>>> | dlg 0x7f8f8501aba0 | |
>>>
>>> | Call-ID:(5-8286 at 10.25.153.150)
>>> |
>>>
>>> |-------------------------------------------------------------->|
>>>
>>> | 100 Trying |
>>>
>>> | dlg 0x7f8f8504cda0 !!!!!!!! |
>>>
>>> | Call-ID:(5-8286 at 10.25.153.150)|
>>>
>>> |<--------------------------------------------------------------|
>>>
>>> | 301 Moved Permanently |
>>>
>>> | dlg 0x7f8f8504cda0 !!!!!!!! |
>>>
>>> | Call-ID:(5-8286 at 10.25.153.150)|
>>>
>>>
>>> |<--------------------------------------------------------------|
>>>
>>>
>>> | |
>>>
>>>
>>>
>>> Thank you,
>>>
>>> Julia.
>>>
>>>
>>> _______________________________________________
>>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing listsr-users at lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>>
>>>
>>> --
>>> Daniel-Constantin Mierlahttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>>>
>>>
>>> _______________________________________________
>>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
>>> sr-users at lists.sip-router.org
>>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>>
>>>
>>
>> --
>> Daniel-Constantin Mierlahttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>>
>>
> --
> Daniel-Constantin Mierlahttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20150114/9b277b5b/attachment.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: wrong_dlg_var.rar
Type: application/rar
Size: 101689 bytes
Desc: not available
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20150114/9b277b5b/attachment.rar>
More information about the sr-users
mailing list