[SR-Users] Wrong dialog selected in on_reply/failure route in case of spirals.
Daniel-Constantin Mierla
miconda at gmail.com
Wed Jan 14 15:07:35 CET 2015
Hello Julia,
ok, I will look over the logs very soon.
As a short note for the future, make the archives zip or tgz, being more
common formats that can be unarchived everywhere without installing
additional app, being handy to open even on small devices. Now it is ok
with rar, being at the desk.
Cheers,
Daniel
On 14/01/15 14:43, Julia Boudniatsky wrote:
> Hello Daniel,
>
> Please find in attached file the debug 3 log.
> Failed call with Call-ID: 4-21712 at 10.25.153.150
> <mailto: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 <mailto: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 <mailto:8-12006 at 10.25.153.150>
>> from_uri:: sip:039988222 at 10.25.153.150
>> <mailto:sip%3A039988222 at 10.25.153.150>;user=phone
>> from_tag:: 8
>> caller_contact:: sip:10.25.153.150:5062
>> <http://10.25.153.150:5062>
>> caller_cseq:: 1
>> caller_route_set::
>> caller_bind_addr:: udp:10.25.153.150:5060
>> <http://10.25.153.150:5060>
>> callee_bind_addr::
>> to_uri:: sip:D39539988200 at 10.25.153.150
>> <mailto:sip%3AD39539988200 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 <mailto:7-12006 at 10.25.153.150>
>> from_uri:: sip:039988222 at 10.25.153.150
>> <mailto:sip%3A039988222 at 10.25.153.150>;user=phone
>> from_tag:: 7
>> caller_contact:: sip:10.25.153.150:5062
>> <http://10.25.153.150:5062>
>> caller_cseq:: 1
>> caller_route_set::
>> caller_bind_addr:: udp:10.25.153.150:5060
>> <http://10.25.153.150:5060>
>> callee_bind_addr:: udp:10.25.153.150:5060
>> <http://10.25.153.150:5060>
>> to_uri:: sip:D39539988200 at 10.25.153.150
>> <mailto:sip%3AD39539988200 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
>> <mailto: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 <mailto: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
>> <mailto: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 <mailto: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 <mailto: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 <mailto: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 <mailto: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
>>>> <mailto:5-8286 at 10.25.153.150>)
>>>> | |
>>>>
>>>>
>>>> |------------------------------->|
>>>> |
>>>>
>>>> | 100 Trying
>>>> | |
>>>>
>>>> | dlg 0x7f8f8501aba0
>>>> | |
>>>>
>>>> | Call-ID:(5-8286 at 10.25.153.150
>>>> <mailto:5-8286 at 10.25.153.150>) |
>>>> |
>>>>
>>>>
>>>> |<-------------------------------|
>>>> |
>>>>
>>>> |
>>>> | |
>>>>
>>>> | INVITE
>>>> | |
>>>>
>>>> | dlg 0x7f8f8501aba0
>>>> | |
>>>>
>>>> | Call-ID:(5-8286 at 10.25.153.150
>>>> <mailto:5-8286 at 10.25.153.150>)
>>>> | |
>>>>
>>>>
>>>> |<-------------------------------|
>>>> |
>>>>
>>>> | 100 Trying
>>>> | |
>>>>
>>>> | dlg 0x7f8f8501aba0
>>>> | |
>>>>
>>>> | Call-ID:(5-8286 at 10.25.153.150
>>>> <mailto:5-8286 at 10.25.153.150>)
>>>> | |
>>>>
>>>>
>>>> |------------------------------->|
>>>> |
>>>>
>>>> |
>>>> | |
>>>>
>>>> | INVITE
>>>> | |
>>>>
>>>> | dlg 0x7f8f8501aba0
>>>> | |
>>>>
>>>> | Call-ID:(5-8286 at 10.25.153.150
>>>> <mailto:5-8286 at 10.25.153.150>)
>>>> |
>>>>
>>>>
>>>> |-------------------------------------------------------------->|
>>>>
>>>> | 100
>>>> Trying |
>>>>
>>>> | dlg
>>>> 0x7f8f8504cda0 !!!!!!!! |
>>>>
>>>> |
>>>> Call-ID:(5-8286 at 10.25.153.150
>>>> <mailto:5-8286 at 10.25.153.150>)|
>>>>
>>>>
>>>> |<--------------------------------------------------------------|
>>>>
>>>> | 301 Moved
>>>> Permanently |
>>>>
>>>> | dlg
>>>> 0x7f8f8504cda0 !!!!!!!! |
>>>>
>>>> |
>>>> Call-ID:(5-8286 at 10.25.153.150
>>>> <mailto:5-8286 at 10.25.153.150>)|
>>>>
>>>>
>>>> |<--------------------------------------------------------------|
>>>>
>>>>
>>>>
>>>> |
>>>> |
>>>>
>>>>
>>>>
>>>> Thank you,
>>>>
>>>> Julia.
>>>>
>>>>
>>>>
>>>> _______________________________________________
>>>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
>>>> sr-users at lists.sip-router.org <mailto:sr-users at lists.sip-router.org>
>>>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>>
>>> --
>>> Daniel-Constantin Mierla
>>> http://twitter.com/#!/miconda <http://twitter.com/#%21/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
>>> <mailto:sr-users at lists.sip-router.org>
>>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>>
>>>
>>
>> --
>> Daniel-Constantin Mierla
>> http://twitter.com/#!/miconda <http://twitter.com/#%21/miconda> - http://www.linkedin.com/in/miconda
>>
>
> --
> Daniel-Constantin Mierla
> http://twitter.com/#!/miconda <http://twitter.com/#%21/miconda> - http://www.linkedin.com/in/miconda
>
>
--
Daniel-Constantin Mierla
http://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/23650553/attachment.html>
More information about the sr-users
mailing list