[OpenSIPS-Users] dialog replication

[Pasan Meemaduma]

Hi Guys,
Its me again :). I'm using dialog replication in opensips 2.3.3 and it appears it doesn't work after a node goes down and come back online. The recovered nodedoesn't seems to receiving dialog info via binary interface.
I have node1 and node2 with dialog replication on. everything works fine and if I shutdown node1 and bring it back online after a whilenode2 doesn't send new call dialog info via binary interface. 

I have attach the debug trace from both nodes, if you need anything else let me know. I'm also using an anycast ip as the service ip.

on node2 for new call
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:build_new_dlg: new dialog 0x7f188afd3bf8 (c=ktQ0Pkdwz50qGYjED6Brpw..,f=sip:XXXXXXXXX at somedomain;transport=UDP,t=sip:+YYYYYYYYYYY at somedomain;transport=UDP,ft=2b161508) on hash 2317
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:init_leg_info: route_set , contact sip:XXXXXXXXX at 192.168.27.11:56419;transport=UDP, cseq 2 and bind_addr udp:10.3.3.1:5060
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:dlg_add_leg_info: set leg 0 for 0x7f188afd3bf8: tag=<2b161508> rcseq=<0>
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:link_dlg: ref dlg 0x7f188afd3bf8 with 3 -> 3 in h_entry 0x7f188afc3828 - 2317
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:new_dlg_val: inserting <exe-callid>=<45ad4c76-1abe-11e8-9410-831894b67d0c>
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:dlg_onreq: t hash_index = 47425, t label = 2069082013
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:dlg_update_contact: Updated dialog 0x7f188afd3bf8 contact to <sip:XXXXXXXXX at 192.168.27.11:56419;transport=UDP>
Feb 26 17:28:35 voip2-sip23b /usr/sbin/opensips[4703]: DBG:dialog:unref_dlg: unref dlg 0x7f188afd3bf8 with 1 -> 2 in entry 0x7f188afc3828
Feb 26 17:28:52 voip2-sip23b /usr/sbin/opensips[4702]: DBG:dialog:ref_dlg: ref dlg 0x7f188afd3bf8 with 1 -> 3
Feb 26 17:28:52 voip2-sip23b /usr/sbin/opensips[4702]: DBG:dialog:ref_dlg: ref dlg 0x7f188afd3bf8 with 1 -> 4
Feb 26 17:28:52 voip2-sip23b /usr/sbin/opensips[4702]: DBG:dialog:unref_dlg: unref dlg 0x7f188afd3bf8 with 1 -> 3 in entry 0x7f188afc3828
Feb 26 17:28:56 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:unref_dlg: unref dlg 0x7f188afd3bf8 with 1 -> 2 in entry 0x7f188afc3828
Feb 26 17:29:05 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:next_state_dlg: dialog 0x7f188afd3bf8 changed from state 1 to state 5, due event 4
Feb 26 17:29:05 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:dlg_onreply: dialog 0x7f188afd3bf8 failed (negative reply)
Feb 26 17:29:05 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:unref_dlg: unref dlg 0x7f188afd3bf8 with 1 -> 1 in entry 0x7f188afc3828
Feb 26 17:29:11 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:next_state_dlg: dialog 0x7f188afd3bf8 changed from state 5 to state 5, due event 1
Feb 26 17:29:11 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:unref_dlg: unref dlg 0x7f188afd3bf8 with 1 -> 0 in entry 0x7f188afc3828
Feb 26 17:29:11 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:unref_dlg: ref <=0 for dialog 0x7f188afd3bf8
Feb 26 17:29:11 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:destroy_dlg: destroying dialog 0x7f188afd3bf8
Feb 26 17:29:11 voip2-sip23b /usr/sbin/opensips[4711]: DBG:dialog:destroy_dlg: dlg expired or not in list - dlg 0x7f188afd3bf8 [2317:677053409] with clid 'ktQ0Pkdwz50qGYjED6Brpw..' and tags '2b161508' 'NULL'


on node1 

Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:dlg_onroute: route param is 'd09.1e30b582' (len=12)
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:lookup_dlg: no dialog id=677053409 found on entry 2317
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:dlg_onroute: unable to find dialog for BYE with route param 'd09.1e30b582'
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15822]: DBG:dialog:dlg_onroute: route param is 'd09.1e30b582' (len=12)
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15822]: DBG:dialog:lookup_dlg: no dialog id=677053409 found on entry 2317
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15822]: DBG:dialog:dlg_onroute: unable to find dialog for BYE with route param 'd09.1e30b582'
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15822]: DBG:dialog:dlg_onroute: route param is 'd09.1e30b582' (len=12)
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15822]: DBG:dialog:lookup_dlg: no dialog id=677053409 found on entry 2317
Feb 26 17:28:41 voip2-sip23a /usr/sbin/opensips[15822]: DBG:dialog:dlg_onroute: unable to find dialog for BYE with route param 'd09.1e30b582'
Feb 26 17:28:42 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:dlg_onroute: route param is 'd09.1e30b582' (len=12)
Feb 26 17:28:42 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:lookup_dlg: no dialog id=677053409 found on entry 2317
Feb 26 17:28:42 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:dlg_onroute: unable to find dialog for BYE with route param 'd09.1e30b582'
Feb 26 17:28:42 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:dlg_onroute: route param is 'd09.1e30b582' (len=12)
Feb 26 17:28:42 voip2-sip23a /usr/sbin/opensips[15820]: DBG:dialog:lookup_dlg: no dialog id=677053409 found on entry 2317


If I purposely take down node1 again and try to make a call I can see that it indicates the failure to replicate dialog info on the cluster as below,
Feb 26 17:27:01 voip2-sip23b /usr/sbin/opensips[4701]: ERROR:dialog:replicate_dialog_updated: All destinations in cluster: 1 are down or probing
Feb 26 17:27:01 voip2-sip23b /usr/sbin/opensips[4701]: ERROR:dialog:replicate_dialog_updated: Failed to replicate updated dialog


-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20180226/42670c62/attachment-0001.html>