Re: [SR-Users] Dialog - timeout for dlg with CallID

Hello, After a couple of days trying to run kamailio in debug mode 3 finally I was able to catch one call with this issue. Please see below the Debug Log for the ACK: Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} <core> [db_query.c:72]: db_do_submit_query(): submitted query: insert into `dialog_vars` (`hash_entry`,`hash_id`,`dialog_key`,`dialog_value` ) values (273,1534,'start_time','1597217857.509'), result 0 Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_db_handler.c:834]: update_dialog_dbinfo_unsafe(): updated 13 vars for dlg [273:1534] Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} db_cluster [dbcl_api.c:483]: db_cluster_use_table(): use table (dialog) - cluster [kamailio] Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} <core> [db_query.c:72]: db_do_submit_query(): submitted query: insert into `dialog` (`hash_entry`,`hash_id`,`callid`,`from_uri`,`from_tag`,`to_uri`,`to_tag`,`caller_sock`,`callee_sock`,`start_time`,`state`,`timeout`,`caller_cseq`,`callee_cseq`,`caller_contact`,`callee_contact`,`caller_route_set`,`callee_route_set`,`sflags`,`toroute_name`,`req_uri`,`xdata`,`iflags` ) values (273,1534,'5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>','sip:+987654321@192.168.50.139 <mailto:sip%3A%2B987654321@192.168.50.139>','as5a6d564e','sip:+1234567890@192.168.89.110 <mailto:sip%3A%2B1234567890@192.168.89.110>','05e27170-ffe5-4e90-b785-9a2fffa0194d','udp:192.168.50.12:5060 <http://192.168.50.12:5060>','udp:192.168.180.16:5060 <http://192.168.180.16:5060>',1597217858,3,1538026468,'103','0','sip:+987654321@192.168.50.139:5060 <http://sip:+987654321@192.168.50.139:5060>','sip:192.168.82.230:5060 <http://192.168.82.230:5060>',NULL,'<sip:192.168.82.250:5060;lr>',0,NULL,'sip:+1234567890@192.168.89.110 <mailto:sip%3A%2B1234567890@192.168.89.110>',NULL,0), result 0 Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_timer.c:110]: insert_dialog_timer_unsafe(): inserting 0x7f769a594418 for 59202190 Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_hash.c:1045]: dlg_ref_helper(): ref op on 0x7f769a5943b8 with 1 from dlg_handlers.c:557 Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_hash.c:1049]: dlg_ref_helper(): ref dlg 0x7f769a5943b8 with 1 -> 3 Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_hash.c:1065]: dlg_unref_helper(): unref op on 0x7f769a5943b8 with 1 from dlg_hash.c:1083 Aug 12 08:37:38 kamailio.localhost kamailio[28296]: DEBUG: {INVITE (2) | Seq=103 | Source IP=192.168.89.110 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <103> <INVITE> Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} sl [sl_funcs.c:421]: sl_filter_ACK(): too late to be a local ACK! Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_var.c:276]: print_lists(): ua=AGENT (flags 1) Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_var.c:276]: print_lists(): ua=AGENT (flags 1) Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_var.c:276]: print_lists(): dest_ip=192.168.89.110 (flags 1) Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_var.c:276]: print_lists(): dest_ip=192.168.89.110 (flags 1) Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} rr [loose.c:90]: is_preloaded(): is_preloaded: No Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_hash.c:1065]: dlg_unref_helper(): unref op on 0x7f769a5c8b48 with 1 from dlg_hash.c:1083 Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_var.c:276]: print_lists(): log=1 (flags 3) Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} dialog [dlg_var.c:276]: print_lists(): called_number=0123456789 (flags 1) Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} tm [t_lookup.c:1330]: t_newtran(): msg (0x7f76bf204b68) id=5441/28307 global id=5440/28307 T start=(nil) Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} <core> [db_query.c:72]: db_do_submit_query(): submitted query: insert into `acc` (`method`,`from_tag`,`to_tag`,`callid`,`sip_code`,`sip_reason`,`time`,`time_attr`,`time_exten`,`src_user`,`src_domain`,`src_ip`,`dst_ouser`,`dst_user`,`dst_domain`,`cseq` ) values ('ACK','as5a6d564e','05e27170-ffe5-4e90-b785-9a2fffa0194d','5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>','200','OK','2020-08-12 08:37:38',1597217858,642167,'+987654321','192.168.50.139','192.168.50.139','+0123456789','','192.168.82.230','103'), result 0 Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} acc [acc_extra.h:64]: free_strar_mem(): Freeing memory, type is 2, message_index 9, index i 0 Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} acc [acc_extra.h:64]: free_strar_mem(): Freeing memory, type is 2, message_index 9, index i 1 Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} acc [acc_extra.h:64]: free_strar_mem(): Freeing memory, type is 2, message_index 9, index i 2 Aug 12 08:37:38 kamailio.localhost kamailio[28307]: DEBUG: {ACK (1) | Seq=103 | Source IP=192.168.50.139 (udp) | Call ID=5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>} <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=05e27170-ffe5-4e90-b785-9a2fffa0194d Aug 12 08:37:42 kamailio.localhost kamailio[28324]: DEBUG: dialog [dlg_hash.c:361]: destroy_dlg(): removed timer for dlg 0x7f769a5c8b48 [273:1533] with clid '5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>' and tags 'as5a6d564e' '' Aug 12 08:37:42 kamailio.localhost kamailio[28324]: DEBUG: dialog [dlg_db_handler.c:710]: remove_dialog_from_db(): trying to remove dialog [5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>], update_flag is 640 Aug 12 08:37:42 kamailio.localhost kamailio[28324]: DEBUG: dialog [dlg_db_handler.c:736]: remove_dialog_from_db(): callid was 5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060> Aug 12 08:40:08 kamailio.localhost kamailio[28323]: WARNING: dialog [dlg_handlers.c:1652]: dlg_ontimeout(): dlg timeout - callid: '5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>' tags: 'as5a6d564e' '05e27170-ffe5-4e90-b785-9a2fffa0194d' ostate: 3 Aug 12 08:40:08 kamailio.localhost kamailio[28323]: DEBUG: <core> [db_query.c:72]: db_do_submit_query(): submitted query: insert into `acc_cdrs` (`start_time`,`end_time`,`duration`,`account_id`,`aleg_uuid`,`bleg_uuid`,`caller_number`,`called_number`,`sip_code`,`sip_reason`,`sip_user_agent`,`kamailio_server`,`billsec`,`src_ip`,`dest_ip`,`direction` ) values ('2020-08-12 08:37:37','2020-08-12 08:37:37',0.000000  ,'101','5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>','29176259973485-74271626511770@192.168.1.228 <mailto:29176259973485-74271626511770@192.168.1.228>','987654321','0123456789',NULL,NULL,'AGENT','SRV',NULL,'192.168.50.139','192.168.89.110','OUTBOUND'), result 0 Aug 12 08:40:08 kamailio.localhost kamailio[28323]: DEBUG: dialog [dlg_hash.c:361]: destroy_dlg(): removed timer for dlg 0x7f769a5943b8 [273:1534] with clid '5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>' and tags 'as5a6d564e' '05e27170-ffe5-4e90-b785-9a2fffa0194d' Aug 12 08:40:08 kamailio.localhost kamailio[28323]: DEBUG: dialog [dlg_db_handler.c:710]: remove_dialog_from_db(): trying to remove dialog [5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060>], update_flag is 1542 Aug 12 08:40:08 kamailio.localhost kamailio[28323]: DEBUG: dialog [dlg_db_handler.c:736]: remove_dialog_from_db(): callid was 5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060 <http://5d905a9857b4136c42fc52242077ef58@192.168.50.139:5060> What I noticed strange here, is that SL module reporting: *sl_filter_ACK(): too late to be a local ACK! * * * I'm not sure that this is the core of the problem, but maybe you can spot something here. This is sl_module configuration, in case if you'll need it: # ---- sl params ---- modparam("sl", "default_code", 505) modparam("sl", "default_reason", "Server Error") Thanks. On Thu, 30 Jul 2020 at 07:22, Daniel-Constantin Mierla &lt;miconda(a)gmail.com <mailto:miconda@gmail.com>> wrote: Hello, run with debug=3 in kamailio.cfg and send here all the log messages printed by kamailio when processing the ACK. Cheers, Daniel On 29.07.20 18:48, Ilie Soltanici wrote: > Hello, > > I changed the configuration > file to call dlg_manage() for > all methods: > > if (loose_route()) { >     dlg_manage(); > >     if (is_method("BYE")) { >       setflag(FLAG_ACC); >       setflag(FLAG_ACCMISSED); >       $dlg_var(billsec) = > $DLG_lifetime; >     } > >     route(RELAY); >     exit; >   }; > > but, unfortunately this is not > helping either. I'm not sure > that there is a buggy UA > messing RR because this is > happening with 4 Providers > randomly. > What else I noticed is that > this is happening mostly with > ISP's using authorization, > with other ISPs using IP as > whitelisted source I didn't > notice anything like that. > Could this be related somehow? > Thanks. > > > On Wed, 29 Jul 2020 at 17:19, > Sergiu Pojoga > &lt;pojogas(a)gmail.com > <mailto:pojogas@gmail.com>> wrote: > > Correct me if I'm wrong, > but not all within-dialogs > are 'calls', for e.g. > NOTIFY with a preset Route > after SUBSCRIBE, wondering > how dlg_manage() would > treat those, would it > count them as 'calls'? > > Not to mention that docs > mention "dlg_manage() > - this makes sense only > for initial requests) > > On Wed, Jul 29, 2020 at > 10:55 AM M S > &lt;shaheryarkh(a)gmail.com > <mailto:shaheryarkh@gmail.com>> > wrote: > > Please put dlg_manage > immediately after > loose_route condition, > e.g. > > if (loose_route) { >     dlg_manage(); > ... > } > > There is no harm in > calling it for every > SIP method (not just > BYE or ACK) within > dialog. It will help > for re-invites (call > hold / unhold events) > etc. from same buggy > UAs as well. > > Thank you. > > > On Wed, Jul 29, 2020 > at 3:21 PM Ilie > Soltanici > &lt;iliusha.md(a)gmail.com > <mailto:iliusha.md@gmail.com>> > wrote: > > Hello, > > I've tried what > you recommended, > but without > success so far. > > This is the > configuration block: > > if (!has_totag()) > return; > > if > (is_method("ACK")) { >    dlg_manage(); >   } > >   if (loose_route()) { >     if > (is_method("BYE")) { >       dlg_manage(); >       > setflag(FLAG_ACC); >       > setflag(FLAG_ACCMISSED); >       > $dlg_var(billsec) > = $DLG_lifetime; >     } >     route(RELAY); >     exit; >   }; > >   if > (is_method("ACK") ) { >     if ( > t_check_trans() ) { >       route(RELAY); >       exit; >     } else { >       > sl_send_reply("606", > "Not Acceptable"); >       exit; >     } >   } > > Here is the sip trace: > https://pastebin.com/Aen2GCjm > <https://pastebin.com/Aen2GCjm> > > > And that's the > error I'm getting > in the Kamailio > log file: > > WARNING: dialog > [dlg_handlers.c:1652]: > dlg_ontimeout(): > dlg timeout - > callid: ' > 0555141d7d3hsag78sgce830391f9348@10.1.50.240:5060 > <http://0555141d7d3hsag78sgce830391f9348@10.1.50.240:5060>' > tags: 'as52c10007' > 'Uv7HS0jX65ctF' > ostate: 3 > > Any other ideas? > > On Tue, 28 Jul > 2020 at 15:44, > Daniel-Constantin > Mierla > &lt;miconda(a)gmail.com > <mailto:miconda@gmail.com>> > wrote: > > Hello, > > I see the > in-dialog ACK > in the trace, > try with > dlg_manage() > explicitly > used for it. > > The warning > messages are > not when > processing the > ack, but on > dialog > timeout, if > you do not get > some other > errors like > 90-120 seconds > before, when > the ack was > routed, then > it was no > processing > error for it. > > Cheers, > Daniel > > On 28.07.20 > 12:31, Ilie > Soltanici wrote: >> Hello, >> >> In the >> Kamailio logs >> I can see >> only those >> errors: >> >> WARNING: >> dialog >> [dlg_handlers.c:1652]: >> dlg_ontimeout(): >> dlg timeout - >> callid: >> '45b0130e14a692b95134696d2fc5f2a9' >> tags: >> 'as31fb1118' >> '12UDcK9S8630r' >> ostate: 3 >> WARNING: acc >> [acc_cdr.c:230]: >> db_write_cdr(): >> fallback to >> dlg_only >> search >> because of >> message >> doesn't exist >> >> Here you can >> see the full >> sip trace: >> https://pastebin.com/Q4eqcGJj >> <https://pastebin.com/Q4eqcGJj> >> >> The First >> Invite it's >> from the >> Asterisk >> server >> (192.168.0.140), >> and the >> second one - >> it's from >> Kamailio >> (192.168.1.16), >> 192.168.2.0/24 >> <http://192.168.2.0/24> >> - it's an >> ISP. What 's >> strange for >> me here is >> that Kamailio >> it's still >> adding >> rr_param to >> the RR Header >> even if it's >> disabled in >> the module >> configuration, >> why would >> that be? >> >> This is the >> module >> configuration: >> modparam("dialog", "enable_stats", 1) >> modparam("dialog", "rr_param", "did") >> modparam("dialog", "dlg_match_mode", 2) >> modparam("dialog", "default_timeout", 10800) >> modparam("dialog", "early_timeout", 180) >> modparam("dialog", "noack_timeout", 90) >> modparam("dialog", "track_cseq_updates", 1) >> modparam("dialog", "dlg_extra_hdrs", "Hint: Inactivity Timeout\r\n") >> >> modparam("dialog", "detect_spirals", 1) >> modparam("dialog", "db_mode", 1) >> >> I will try to >> run >> dlg_manage >> for ACK >> within the >> dialog and >> see if it works. >> Thank you. >> >> >> On Tue, 28 >> Jul 2020 at >> 09:26, >> Daniel-Constantin >> Mierla >> &lt;miconda(a)gmail.com >> <mailto:miconda@gmail.com>> >> wrote: >> >> Hello, >> >> that >> confirms >> my >> guessing >> -- the >> ack was >> not >> handled >> in the >> dialog >> context, >> the state >> 3 is >> defined >> inside >> dlg_hash.h >> by: >> >> #define >> DLG_STATE_CONFIRMED_NA >> 3 /*!< >> confirmed >> dialog >> without a >> ACK yet */ >> >> Do you >> see any >> error >> messages >> in the >> logs when >> handling >> the ACK >> in the >> config? >> Did you >> grab the >> pcap with >> sip >> traffic >> for such >> a call? >> >> Try also >> to >> explicitly >> execute >> dlg_manage() >> for ACK >> within >> dialog. >> >> Cheers, >> Daniel >> >> On >> 28.07.20 >> 10:05, >> Ilie >> Soltanici >> wrote: >>> Hello, >>> >>> I >>> re-compiled >>> Kamailio >>> from the >>> Master >>> branch >>> and I'm >>> getting >>> the old >>> state: 3 >>> >>> *dlg_ontimeout(): >>> dlg >>> timeout >>> - >>> callid: >>> '225ce4fc79d78c0f5477d02d02f3feea' >>> tags: >>> 'as3f0a58cf' >>> 'a9eb002d-c544-47f7-84ec-1c4e690cd0b4' >>> ostate: 3* >>> >>> [ilie.soltanici@dev >>> ~]$ >>> /usr/local/sbin/kamailio >>> -V >>> version: >>> kamailio >>> 5.3.5 >>> (x86_64/linux) >>> ff2f8c >>> >>> Thanks >>> >>> On Mon, >>> 27 Jul >>> 2020 at >>> 08:56, >>> Daniel-Constantin >>> Mierla >>> &lt;miconda(a)gmail.com >>> <mailto:miconda@gmail.com>> >>> wrote: >>> >>> Hello, >>> >>> this >>> sounds >>> like >>> the >>> ACK >>> is  >>> not >>> matched >>> for >>> dialog >>> processing >>> and >>> the >>> early_timeout >>> is >>> firing. >>> I >>> just >>> pushed >>> a >>> commit >>> to >>> dialog >>> module >>> to >>> print >>> the >>> old >>> state >>> when >>> the >>> timeout >>> callback >>> function >>> is >>> executed, >>> maybe >>> you >>> can >>> test >>> with >>> it >>> -- >>> it >>> is >>> in >>> branch >>> 5.3: >>> >>>   - >>> https://github.com/kamailio/kamailio/commit/ff2f8c4e63b4fefa7dc5b10835505c3… >>> <https://github.com/kamailio/kamailio/commit/ff2f8c4e63b4fefa7dc5b10835505c3c4ae84388> >>> >>> Otherwise, >>> maybe >>> call >>> dlg_manage() >>> for >>> ACK, >>> although >>> the >>> loose_route() >>> callback >>> should >>> be >>> executed >>> and >>> ACK >>> handled >>> for >>> dialog >>> processing. >>> >>> Cheers, >>> Daniel >>> >>> On >>> 24.07.20 >>> 12:46, >>> Ilie >>> Soltanici >>> wrote: >>>> Hello, >>>> >>>> I'm >>>> trying >>>> to >>>> get >>>> CDR >>>> working >>>> in >>>> Kamailio >>>> by >>>> using >>>> the >>>> acc >>>> and >>>> dialog >>>> modules. >>>> Everything >>>> seemed >>>> to >>>> be >>>> working >>>> fine >>>> - >>>> until >>>> i >>>> noticed >>>> that >>>> for >>>> some >>>> of >>>> the >>>> calls >>>> the >>>> call >>>> duration >>>> is >>>> 0, >>>> even >>>> if >>>> that >>>> call >>>> has >>>> been >>>> successfully >>>> established >>>> and >>>> duration >>>> was >>>> for >>>> about >>>> a >>>> few >>>> minutes. >>>> In >>>> the >>>> Kamailio >>>> logs >>>> I'm >>>> getting >>>> such >>>> errors: >>>> >>>> WARNING: >>>> dialog >>>> [dlg_handlers.c:1649]: >>>> dlg_ontimeout(): >>>> timeout >>>> for >>>> dlg >>>> with >>>> CallID >>>> '304bad142b50bb3a7a117816439ea3d5' >>>> and >>>> tags >>>> 'as3adde5c7' >>>> '7d28152f-e0e3-4bcf-9d5c-21c3723b95c5' >>>> WARNING: >>>> acc >>>> [acc_cdr.c:230]: >>>> db_write_cdr(): >>>> fallback >>>> to >>>> dlg_only >>>> search >>>> because >>>> of >>>> message >>>> doesn't >>>> exist. >>>> >>>> This >>>> error >>>> I'm >>>> getting >>>> at >>>> about >>>> 2 >>>> min >>>> after >>>> the >>>> ACK >>>> message >>>> for >>>> 200 >>>> OK. >>>> I'm >>>> not >>>> sure >>>> that >>>> this >>>> is >>>> related >>>> to >>>> the >>>> dialog >>>> timeout, >>>> but >>>> below >>>> you >>>> can >>>> see >>>> the >>>> related >>>> configuration >>>> for >>>> the >>>> dialog >>>> module: >>>> >>>> modparam("dialog", "default_timeout", 10800) # 3 hours >>>> modparam("dialog", "early_timeout", 180) >>>> modparam("dialog", "noack_timeout", 90) >>>> >>>> Unfortunately, >>>> I'm >>>> not >>>> able >>>> to >>>> reproduce >>>> this >>>> issue, >>>> as >>>> that's >>>> happening >>>> randomly >>>> and >>>> just >>>> a >>>> few >>>> times >>>> per >>>> day. >>>> On >>>> the >>>> SIP >>>> Level >>>> i >>>> didn't >>>> notice >>>> any >>>> strange >>>> issues. >>>> >>>> >>>> Any >>>> ideas >>>> why >>>> is >>>> that >>>> happening? >>>> Thank >>>> you. >>>> >>>> _______________________________________________ >>>> Kamailio (SER) - Users Mailing List >>>> sr-users(a)lists.kamailio.org <mailto:sr-users@lists.kamailio.org> >>>> https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users> >>> >>> -- >>> Daniel-Constantin Mierla -- www.asipto.com <http://www.asipto.com> >>> www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> >>> Funding: https://www.paypal.me/dcmierla <https://www.paypal.me/dcmierla> >>> >> -- >> Daniel-Constantin Mierla -- www.asipto.com <http://www.asipto.com> >> www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> >> Funding: https://www.paypal.me/dcmierla <https://www.paypal.me/dcmierla> >> > -- > Daniel-Constantin Mierla -- www.asipto.com <http://www.asipto.com> > www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> > Funding: https://www.paypal.me/dcmierla <https://www.paypal.me/dcmierla> > > _______________________________________________ > Kamailio (SER) - > Users Mailing List > sr-users(a)lists.kamailio.org > <mailto:sr-users@lists.kamailio.org> > https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users > <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users> > > _______________________________________________ > Kamailio (SER) - Users > Mailing List > sr-users(a)lists.kamailio.org > <mailto:sr-users@lists.kamailio.org> > https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users > <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users> > > _______________________________________________ > Kamailio (SER) - Users > Mailing List > sr-users(a)lists.kamailio.org > <mailto:sr-users@lists.kamailio.org> > https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users > <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users> > > > _______________________________________________ > Kamailio (SER) - Users Mailing List > sr-users(a)lists.kamailio.org <mailto:sr-users@lists.kamailio.org> > https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users> -- Daniel-Constantin Mierla -- www.asipto.com <http://www.asipto.com> www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> Funding: https://www.paypal.me/dcmierla <https://www.paypal.me/dcmierla>