From tpeirce-lists@acrovoice.ca Wed Mar 30 06:14:26 2022
From: Trevor Peirce <tpeirce-lists@acrovoice.ca>
To: sr-users@lists.kamailio.org
Subject: [SR-Users] Asterisk, unable to find dialog for ACK with route param
 and call-id
Date: Tue, 29 Mar 2022 21:14:22 -0700
Message-ID: <c118e7f6-a568-cee0-6681-2fc2aadffbc2@acrovoice.ca>
MIME-Version: 1.0
Content-Type: multipart/mixed; boundary="===============0905184179=="

--===============0905184179==
Content-Type: text/plain; charset="utf-8"
Content-Transfer-Encoding: quoted-printable

Hello,

I'm having some trouble with what I think is the dialog module.

I've got Kamailio 5.5.4 running well for some time.=C2=A0 Recently I added=20
Asterisk to the mix and have come across an issue.

Call to Kamailio.=C2=A0 Kamailio sends it to Asterisk.=C2=A0 Asterisk may dec=
ide=20
to "forward" the call.=C2=A0 To do this it will place a new outbound call=20
through Kamailio.=C2=A0 From the user perspective, this works absolutely fine=
=20
(invite, ringing, ok, bye).=C2=A0 The acc module handles it fine also.

However dialog does not, and the events for ok and bye do not fire on=20
the original call.=C2=A0 They do fire for the forwarded call.

I've added some logging output to try to isolate the problem, but I'm no=20
further ahead.=C2=A0 Here's what I have.=C2=A0 Any suggestions?

** Call initiated **

INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D100=20
ru=3D<null> tu=3Dsip:destination(a)endpoint DLG_status=3D<null> dlg-h-id=3D33=
60
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D100=20
ru=3D<null> tu=3Dsip:fwddest(a)0.0.0.1:5060 DLG_status=3D<null> dlg-h-id=3D12=
036
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D183=20
ru=3D<null> tu=3Dsip:fwddest(a)0.0.0.1:5060 DLG_status=3D<null> dlg-h-id=3D12=
036
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D183=20
ru=3D<null> tu=3Dsip:destination(a)endpoint DLG_status=3D<null> dlg-h-id=3D33=
60
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D200=20
ru=3D<null> tu=3Dsip:fwddest(a)0.0.0.1:5060 DLG_status=3D<null> dlg-h-id=3D12=
036
CRITICAL: dialog [dlg_timer.c:200]: update_dlg_timer(): Trying to update=20
a bogus dlg tl=3D0x7fa0494006e8 tl->next=3D(nil) tl->prev=3D(nil)
ERROR: dialog [dlg_hash.c:1329]: update_dlg_timeout(): failed to update=20
dialog lifetime
CRITICAL: dialog [dlg_timer.c:200]: update_dlg_timer(): Trying to update=20
a bogus dlg tl=3D0x7fa0494006e8 tl->next=3D(nil) tl->prev=3D(nil)
ERROR: dialog [dlg_hash.c:1329]: update_dlg_timeout(): failed to update=20
dialog lifetime
ERROR: dialog [dlg_profile.c:1028]: dlg_set_timeout_by_profile(): Unable=20
to set timeout on 3306:12036
NOTICE: acc [acc.c:270]: acc_log_request(): ACC: transaction answered:=20
timestamp=3D1648611824;time_unix=3D1648611824.346;method=3DINVITE;from_tag=3D=
as292200a1;to_tag=3Das75834dc4;call_id=3D7cef233547dca0ac7882ce5249b3b54c;cod=
e=3D200;reason=3DOK
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D200=20
ru=3D<null> tu=3Dsip:destination(a)endpoint DLG_status=3D<null> dlg-h-id=3D<n=
ull>
NOTICE: acc [acc.c:270]: acc_log_request(): ACC: transaction answered:=20
timestamp=3D1648611824;time_unix=3D1648611824.354;method=3DINVITE;from_tag=3D=
as730369e7;to_tag=3Das3b06b3e2;call_id=3D53bad4f0034b61a5745163d82daef402;cod=
e=3D200;reason=3DOK
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D200=20
ru=3D<null> tu=3Dsip:destination(a)endpoint DLG_status=3D<null> dlg-h-id=3D<n=
ull>
WARNING: dialog [dlg_handlers.c:1328]: dlg_onroute(): unable to find=20
dialog for ACK with route param '161.02d' [353:3360] and call-id=20
'53bad4f0034b61a5745163d82daef402'
WARNING: dialog [dlg_handlers.c:1328]: dlg_onroute(): unable to find=20
dialog for ACK with route param '161.02d' [353:3360] and call-id=20
'53bad4f0034b61a5745163d82daef402'

** The call is answered and endpoints can talk to each other **
** Now the original caller hangs up **

WARNING: dialog [dlg_handlers.c:1328]: dlg_onroute(): unable to find=20
dialog for BYE with route param '161.02d' [353:3360] and call-id=20
'53bad4f0034b61a5745163d82daef402'
NOTICE: acc [acc.c:270]: acc_log_request(): ACC: transaction answered:=20
timestamp=3D1648611831;time_unix=3D1648611831.933;method=3DBYE;from_tag=3Das7=
30369e7;to_tag=3Das3b06b3e2;call_id=3D53bad4f0034b61a5745163d82daef402;code=
=3D200;reason=3DOK
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D100=20
ru=3D<null> tu=3Dsip:fwddest(a)0.0.0.1:5060 DLG_status=3D<null> dlg-h-id=3D12=
036
INFO: <script>: onreply_route[MANAGE_REPLY] branch [0] rm=3DINVITE rs=3D200=20
ru=3D<null> tu=3Dsip:fwddest(a)0.0.0.1:5060 DLG_status=3D<null> dlg-h-id=3D12=
036
ERROR: <script>: got result for bye =3D Bye!; DLG_lifetime =3D 8
NOTICE: acc [acc.c:270]: acc_log_request(): ACC: transaction answered:=20
timestamp=3D1648611832;time_unix=3D1648611832.115;method=3DBYE;from_tag=3Das2=
92200a1;to_tag=3Das75834dc4;call_id=3D7cef233547dca0ac7882ce5249b3b54c;code=
=3D200;reason=3DOK
ERROR: dialog [dlg_handlers.c:363]: dlg_terminated_confirmed(): failed=20
to get dialog from params!

** Everything ends cleanly on the other SIP endpoints **

What can I do from here to try to isolate this further?

Thanks,

--=20
Trevor Peirce
AcroVoice Solutions Inc



--===============0905184179==--