Hi Daniel,
Yes we do lookup(location) for the BYE message. We traced down the way BYE message flows and tried all possible route blocks for lookup(location) but nothing changed.
When we check the AOR for the user after websocket reconnection we see the new valid connection only but lookup(location) does not update the dialog to use that one, still tries to send to old socket.
Here is the debug log:
May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=444 a=24 n=sip_trace May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=445 a=39 n=setflag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=448 a=5 n=route May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=627 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=621 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=626 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=621 a=24 n=pike_check_req May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=633 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=638 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=633 a=25 n=mf_process_maxfwd_header May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=643 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=638 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=647 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=643 a=26 n=sanity_check May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=451 a=5 n=route May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=852 a=55 n=force_rport May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=863 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=853 a=25 n=nat_uac_test May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=859 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=854 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=858 a=24 n=set_contact_alias May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=860 a=39 n=setflag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=863 a=2 n=return May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=462 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=454 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=462 a=5 n=route May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=654 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=651 a=24 n=has_totag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=672 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=654 a=24 n=loose_route May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=655 a=5 n=route May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DLGURI] c=[/usr/local/etc/kamailio/kamailio.cfg] l=924 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DLGURI] c=[/usr/local/etc/kamailio/kamailio.cfg] l=920 a=24 n=isdsturiset May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DLGURI] c=[/usr/local/etc/kamailio/kamailio.cfg] l=921 a=24 n=handle_ruri_alias May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[DLGURI] c=[/usr/local/etc/kamailio/kamailio.cfg] l=924 a=2 n=return May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=668 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=656 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=657 a=39 n=setflag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=658 a=39 n=setflag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=668 a=5 n=route May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=589 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=586 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=588 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=587 a=25 n=t_is_set May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=587 a=25 n=t_on_branch May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=592 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=589 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=597 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=592 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=602 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=597 a=25 n=is_method May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=598 a=39 n=setflag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=599 a=24 n=dlg_manage May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=605 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=602 a=24 n=t_relay May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1021 a=25 n=xdbg May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[MANAGE_BRANCH] c=[/usr/local/etc/kamailio/kamailio.cfg] l=1022 a=5 n=route May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=896 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=886 a=24 n=is_request May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=892 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=887 a=24 n=has_totag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=891 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=888 a=25 n=check_route_param May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=889 a=25 n=setbflag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=900 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=896 a=41 n=isflagset May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=907 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=900 a=24 n=is_request May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=906 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=901 a=24 n=has_totag May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=914 a=16 n=if May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=907 a=24 n=is_reply May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:branch_route=[NATMANAGE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=914 a=2 n=return May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: WARNING: <core> [msg_translator.c:2761]: via_builder(): TCP/TLS connection (id: 0) for WebSocket could not be found May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: ERROR: <core> [msg_translator.c:1979]: build_req_buf_from_sip_req(): could not create Via header May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: ERROR: tm [t_fwd.c:462]: prepare_new_uac(): could not build request May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: ERROR: tm [t_fwd.c:1723]: t_forward_nonack(): ERROR: t_forward_nonack: failure to add branches May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=603 a=24 n=sl_reply_error May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: ERROR: sl [sl_funcs.c:363]: sl_reply_error(): ERROR: sl_reply_error used: No error (2/SL) May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: exec: *** cfgtrace:request_route=[RELAY] c=[/usr/local/etc/kamailio/kamailio.cfg] l=605 a=2 n=exit May 18 14:01:47 registrar-kenan /usr/local/sbin/kamailio[27976]: NOTICE: acc [acc.c:317]: acc_log_request(): ACC: transaction answered: timestamp=1526652107;method=BYE;from_tag=as1cce13f8;to_ tag=fl4kaj6oin;call_id=9f4p1pp6a6d55r6tf663;code=500;reason=Server Internal Error;src_user=05392111479;src_domain=registrarkenan.alo-tech.com ;src_ip=192.158.28.164;dst_ouser=114408;dst_user=ipkkm7vs;dst_domain= ujmd89iahcfm.invalid
Thanks.
Idris
On Fri, May 18, 2018 at 3:55 PM, Daniel-Constantin Mierla <miconda@gmail.com
wrote:
Hello,
are you doing lookup(location) for the BYE that is attempted to be routed on the old connection?
Cheers, Daniel
On 17.05.18 17:45, Idris AVCI wrote:
Hi,
We use kamailio as a backend for JSSIP based WebRTC UAs. We use SIP over Weboscket as transport and everythings work fine generally.
Some websocket connections to kamailio drop during an active call and JSSIP reconnects immediately. New websocket connection has a different port than the dropped one but kamailio tries to send to old websocket port which is not active anymore.
We use GRUU and configured all the related parameters in registrar, dialog, tm, websocket, tls, xhttp and userloc modules both for versions 4.4 and 5.1 but couldn't find a solution yet.
For a typical call below are the ordered events:
- UA register to kamailio over wss (Code: WS1)
- Kamailio send an INVITE to the UA (Code: Call1)
- UA answer and media starts
- UA drops WS1 to kamailio and reconnect (Code: WS2)
- UA has an active websocket (WS2) to kamailio
- Kamailio tries to send BYE message of Call1 to WS1 which is no longer
active and complains that websocket could not be found 7. Kamailio send an INVTE to the UA (Code: Call2) over WS2 8. UA answer and media starts 9. Kamailio send BYE message of Call2 to UA over WS2 and everything work as expected (No websocket drop)
Error log:
WARNING: <core> [msg_translator.c:2761]: via_builder(): TCP/TLS connection (id: 0) for WebSocket could not be found
I think we need a mechanism that will update the dialog information of Call1 to use WS2 after reconnection.
Any help greatly appreciated.
Thanks.
Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com