[SR-Users] Spurious dialog timeouts

[Pranathi Venkatayogi]

Any clue on this?

Or to work around this, can someone shed light on how to differentiate when “Dialog:end” is called due to actual call drop - Bye vs some timeout.
I need to do some special logic only on call drop. 

Relevant snippet from the logs are:

7719 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=490 a=16 n=if
7720 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=481 a=24 n=is_known_dlg
7721 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=482 a=63 n=assign
7722 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=489 a=16 n=if
7723 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=485 a=26 n=xlog
7724 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: INFO: <script>: Translation: Dialog ended for sip:agent2 at translation.sms-test.cyracom.com. Marking free
7725 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=486 a=63 n=assign
7726 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=487 a=63 n=assign
7727 Feb 3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: WARNING: dialog [dlg_handlers.c:1515]: dlg_ontimeout(): timeout for dlg with CallID '1478097bad2847cfaa60a768d76044b0' and tags '57315e78d6514251876bd5a9e32347d2' '00a1eac150f04e2592527372412f059e'


------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
-----Original Message-----
From: Pranathi Venkatayogi 
Sent: Friday, February 03, 2017 2:29 PM
To: 'Kamailio (SER) - Users Mailing List' <sr-users at lists.sip-router.org>
Subject: Spurious dialog timeouts

Hi,
   I have two kamailio proxies between the customer and agent. Cust -> proxy1 -> proxy2 -> agent.
   The dialogs are successfully established and remain undeleted within the customer / agent client.
   However in  Proxy2 I am seeing spurious dialog timeout. I am attaching kamailio log trace below. 
   Also attached is the siptrace on the agent/customer side. 

   Question: How is this dlg_ontimeout() generated despite dialog being successfully established and undelete? How to avoid it.

Kamailio (proxy2) log:

7719 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=490 a=16 n=if
 7720 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=481 a=24 n=is_known_dlg
 7721 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=482 a=63 n=assign
 7722 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=489 a=16 n=if
 7723 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=485 a=26 n=xlog
 7724 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: INFO: <script>: Translation: Dialog ended for sip:agent2 at translation.sms-test.cyracom.com. Marking free
 7725 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=486 a=63 n=assign
 7726 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=487 a=63 n=assign
 7727 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: WARNING: dialog [dlg_handlers.c:1515]: dlg_ontimeout(): timeout for dlg with CallID '1478097bad2847cfaa60a768d76044b0' and tags '57315e78d6514251876bd5a9e32347d2' '00a1eac150f04e2592527372412f059e'
 7728 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=490 a=16 n=if
 7729 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=481 a=24 n=is_known_dlg
 7730 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=482 a=63 n=assign
 7731 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=489 a=16 n=if
 7732 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=485 a=26 n=xlog
 7733 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: INFO: <script>: Translation: Dialog ended for sip:agent5 at translation.sms-test.cyracom.com. Marking free
 7734 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=486 a=63 n=assign
 7735 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: exec: *** cfgtrace:local_route=[dialog:end] c=[//etc/kamailio//routing.cfg] l=487 a=63 n=assign
 7736 Feb  3 21:41:20 labsip00 /usr/sbin/kamailio[11323]: WARNING: dialog [dlg_handlers.c:1515]: dlg_ontimeout(): timeout for dlg with CallID '04d924dc160d4e0c9366d4699f579793' and tags '9b107772c82148c0a09182dc86804c51' '44b0a200438a42f8a6ac931ed72f795a'
   
Thanks for your input,
Pranathi