[Freeswitch-users] Invalid URI for NOTIFY

Wed Mar 1 08:50:15 MSK 2017

Observed the same issue even after db manual update for to header.
I suspect contact from
db[<sip:Test at 192.168.42.91:49198;transport=tls;registering_acc=freeswitch_com;fs_nat=yes;fs_path=sip%3A50.10.1.93%3A36959%3Btransport%3Dtls%3Bregistering_acc%3Dfreeswitch_com>]
is causing the invalid uri as it is always showing contact[0].

nua_stack.c:569 nua_stack_signal() nua(0x7fdb28037ed0): recv signal
r_destroy
nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7fdb28037ed0): removing
notify usage with event conference
nta.c:4479 nta_leg_destroy() nta_leg_destroy(0x7fdb2803e2b0)
soa.c:356 soa_destroy() soa_destroy(static::0x7fdb2803d860) called
nua_stack.c:529 nua_signal() nua(0x7fdb28037ed0): sent signal r_destroy
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 0[full_to] = [<
sip:3000 at freeswitch.com>]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 1[full_from] =
["Test" <sip:test at freeswitch.com>;tag=f11396c0]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 2[contact ||
';_;isfocus'] = [0]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 3[expires] =
[1488349612]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 4[call_id] =
[a2331da5b51899232a9fecca80dfe106 at 0:0:0:0:0:0:0:0]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 5[event] =
[conference]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 6[network_ip] =
[50.10.1.93]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 7[network_port]
= [36959]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 8[ct] =
[application/conference-info+xml]
2017-03-01 05:38:33.419093 [CRIT] sofia_presence.c:4535 arg 9[pt] = [<?xml
version="1.0"?>
<conference-info version="1" state="full"
xmlns="urn:ietf:params:xml:ns:conference-info" entity="
sip:3000 at freeswitch.com">
  <conference-description>

2017-03-01 05:38:33.419093 [ERR] sofia_presence.c:2308 SEND PRES NOTIFY:
file[sofia_presence.c]
func[sofia_presence_send_sql]
line[4539]
profile[external]
via[SIP/2.0/udp 10.0.0.240;rport]
ip[50.10.1.93]
port[36959]
route[(null)]
contact[0]
to[<sip:3000 at freeswitch.com>]
from["Test" <sip:test at freeswitch.com>;tag=f11396c0]
url[0]
call_id[a2331da5b51899232a9fecca80dfe106 at 0:0:0:0:0:0:0:0]
expires_str[]
event[conference]
ct[application/conference-info+xml]
pl[<?xml version="1.0"?>
<conference-info version="1" state="full"
xmlns="urn:ietf:params:xml:ns:conference-info" entity="
sip:3000 at freeswitch.com">
  <conference-description>

call_info[]
exptime[2899]
nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
nua.c:716 nua_notify() nua: nua_notify: entering
nua_stack.c:569 nua_stack_signal() nua(0x7fdb38067200): recv signal r_notify
nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:280 soa_clone() soa_clone(static::0x7fdb280021b0, 0x7fdb28001a60,
0x7fdb38067200) called
soa.c:403 soa_set_params() soa_set_params(static::0x7fdb28032390, ...)
called
soa.c:403 soa_set_params() soa_set_params(static::0x7fdb28032390, ...)
called
nta.c:4426 nta_leg_tcreate() nta_leg_tcreate(0x7fdb28019db0)
nta.c:8072 outgoing_create() nta outgoing create: invalid URI
nta.c:8733 outgoing_free() nta: outgoing_free(0x7fdb280321d0)
nua_stack.c:271 nua_stack_event() nua(0x7fdb38067200): event r_notify 900
Internal error at nua_client.c:713
nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
nua_stack.c:529 nua_signal() nua(0x7fdb38067200): sent signal r_notify

Regards
Raman

On Tue, Feb 28, 2017 at 9:18 PM, Michael Jerris <mike at jerris.com> wrote:

> i have a way to work around this issue.  I suspect the client is doing
> something strange with putting the to tag in on its own when it shouldn’t
> but I can’t tell for sure from the info you have put out already.  If you
> file a Jira I can push a fix for you to test and confirm.  Testing what I
> asked will also help confirm.
>
> On Feb 28, 2017, at 10:37 AM, Michael Jerris <mike at jerris.com> wrote:
>
> Please read back and try what i suggested.
>
> On Feb 28, 2017, at 10:20 AM, Ram <raman.chv at gmail.com> wrote:
>
> Hi Michael,
>
> Following is my SUBSCRIBE, which has single to tag, don't how another to
> tag come in  while composing
>
> SUBSCRIBE sip:3000 at 10.50.163.240:5060;transport=tcp SIP/2.0
> CSeq: 2 SUBSCRIBE
> From: "Test" <sip:test at freeswitch.com>;tag=141c3e7f
> To: <sip:3000 at freeswitch.com>;tag=X8yKKXpQFg1rH
> Call-ID: 3382a80cc99813fe8c38652094d35168 at 0:0:0:0:0:0:0:0
> Route: <sip:10.43.18.93:5060;transport=tcp;lr=on>
> Via: SIP/2.0/TCP 10.3.2.173;branch=z9hG4bK6766.
> 3d9373331b5e0abb74dbc9fac0ebdd94.0;i=c08a3
> Via: SIP/2.0/TCP 10.43.18.93:5061;received=10.3.1.111;branch=z9hG4bK6766.
> 6ef433bc043278dcf0d7203aacfd6d9d.0;i=1
> Via: SIP/2.0/TCP 10.3.2.173;rport=41015;branch=z9hG4bK6766.
> 481cb8ecdfed560d335ae90dbcc8b52a.0;i=5f1a3
> Via: SIP/2.0/TLS 192.168.42.91:49198;received=
> 182.72.148.178;branch=z9hG4bK-3430-7c9fbf29730430944ed135b4f21590aa
> Contact: "Test" <sip:test at 192.168.42.91:49198;
> transport=tls;registering_acc=vvoip_flowtalk_com>
> User-Agent: Jitsi2.10.5550Windows 8.1
> Max-Forwards: 68
> Event: conference
> Accept: application/conference-info+xml
> Expires: 3600
> Content-Length: 0
>
>
>
> And logs while initiating NOTIFY
>
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 0[full_to] = [<
> sip:3000 at freeswitch.com>;tag=X8yKKXpQFg1rH;tag=X8yKKXpQFg1rH]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 1[full_from] =
> ["Test" <sip:test at freeswitch.com>;tag=141c3e7f]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 2[contact ||
> ';_;isfocus'] = [0]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 3[expires] =
> [1488298190]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 4[call_id] = [
> 3382a80cc99813fe8c38652094d35168 at 0:0:0:0:0:0:0:0]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 5[event] =
> [conference]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 6[network_ip]
> = [10.43.18.93]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg
> 7[network_port] = [34672]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 8[ct] =
> [application/conference-info+xml]
> 2017-02-28 15:09:50.108202 [CRIT] sofia_presence.c:4535 arg 9[pt] = [<?xml
> version="1.0"?>
>
>
> Regards
> Raman
>
> On Tue, Feb 28, 2017 at 7:35 PM, Michael Jerris <mike at jerris.com> wrote:
>
>> did you try what i suggested?
>>
>> On Tue, Feb 28, 2017 at 4:24 AM Ram <raman.chv at gmail.com> wrote:
>>
>>> Thanks Michael, and Brain.
>>>
>>> 1. I tried after carrying the FS-10041 patch to 1.6.15 source, still i
>>> am facing the same.
>>> And for To tags, freeswitch is adding the duplicate To tag while
>>> creating notify, i am unable to find where to control the same.
>>>
>>>
>>> Following is the db entry created for subscription, may help you to get
>>> some idea about the issue
>>>
>>>
>>> | conf  | test | freeswitch.com | 3000        | freeswitch.com |
>>> freeswitch.com | conference | "user" <sip:test at 192.168.42.91:49198;
>>> transport=tls;registering_acc=freeswitch_com;fs_nat=yes;fs_p
>>> ath=sip%3A52.78.55.33%3A59019%3Btransport%3Dtls%3Bregistering_acc%3Dfreeswitch_com>
>>> | ecea3c49301aded05c155660703ebed8 at 0:0:0:0:0:0:0:0 | "Test" <
>>> sip:test at freeswitch.com>;tag=664f4da1 | SIP/2.0/TCP
>>> 10.3.3.173;branch=z9hG4bK4a46.6b72e5d6b53e1a00ecf9c27b2286b3
>>> 26.0;i=c92a3;received=52.78.55.33;rport=59019 | 1488211342 |
>>> Jitsi2.10.5550Windows 8.1 | application/conference-info+xml  | external
>>>     | ip-10-4-2-63 | 59019        | 52.78.55.93 |      -1 | sip        | <
>>> sip:3000 at freeswitch.com>;tag=79rrXS3jDH8Xp;tag=79rrXS3jDH8Xp |
>>>
>>>
>>> Regards
>>> Raman
>>>
>>>
>>> On Thu, Feb 23, 2017 at 2:01 AM, Brian West <brian at freeswitch.org>
>>> wrote:
>>>
>>> Probably related to FS-10041
>>>
>>> On Wed, Feb 22, 2017 at 9:25 AM, Michael Jerris <mike at jerris.com> wrote:
>>>
>>> maybe because the to tag is in there twice?
>>>
>>> you can try editing that in the db directly before it goes to send the
>>> next notify and see if that helps to confirm my guess
>>>
>>> On Feb 22, 2017, at 5:45 AM, Ram <raman.chv at gmail.com> wrote:
>>>
>>> Hi,
>>>
>>> I am observed following error while freeswitch sending notify
>>>
>>> nta.c:8061 outgoing_create() nta outgoing create: invalid URI
>>>
>>> Help me to identify the issue,
>>>
>>>
>>> Following are freeswitch logs for reference.
>>>
>>> nua_stack.c:529 nua_signal() nua(0x7f4e800826a0): sent signal r_destroy
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 0[full_to] =
>>> [<sip:3000 at freeswitch.com>;tag=DHHj59Sg6cNDr;tag=DHHj59Sg6cNDr]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 1[full_from]
>>> = ["Test" <sip:test at freeswitch.com>;tag=b66cbfe7]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 2[contact ||
>>> ';_;isfocus'] = [0]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 3[expires] =
>>> [1487762486]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 4[call_id] =
>>> [fd8a7306f3c49be8163cb94075e2c23c at 0:0:0:0:0:0:0:0]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 5[event] =
>>> [conference]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg
>>> 6[network_ip] = [50.10.1.93]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg
>>> 7[network_port] = [38893]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 8[ct] =
>>> [application/conference-info+xml]
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:4535 arg 9[pt] =
>>> [<?xml version="1.0"?>
>>>
>>>
>>> 2017-02-22 10:21:26.394790 [ERR] sofia_presence.c:2308 SEND PRES NOTIFY:
>>> file[sofia_presence.c]
>>> func[sofia_presence_send_sql]
>>> line[4539]
>>> profile[external]
>>> via[SIP/2.0/udp 10.0.0.240;rport]
>>> ip[50.10.1.93]
>>> port[38893]
>>> route[(null)]
>>> contact[0]
>>> to[<sip:3000 at freeswitch.com>;tag=DHHj59Sg6cNDr;tag=DHHj59Sg6cNDr]
>>> from["Test" <sip:test at freeswitch.com>;tag=b66cbfe7]
>>> url[0]
>>> call_id[fd8a7306f3c49be8163cb94075e2c23c at 0:0:0:0:0:0:0:0]
>>> expires_str[]
>>>
>>>
>>> call_info[]
>>> exptime[3600]
>>> nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
>>> nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
>>> nua.c:716 nua_notify() nua: nua_notify: entering
>>> nua_stack.c:569 nua_stack_signal() nua(0x7f4e90070360): recv signal
>>> r_notify
>>> nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params:
>>> entering
>>> soa.c:280 soa_clone() soa_clone(static::0x7f4e800021b0, 0x7f4e80001a60,
>>> 0x7f4e90070360) called
>>> soa.c:403 soa_set_params() soa_set_params(static::0x7f4e800352d0, ...)
>>> called
>>> soa.c:403 soa_set_params() soa_set_params(static::0x7f4e800352d0, ...)
>>> called
>>> nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f4e80082800)
>>> nta.c:8061 outgoing_create() nta outgoing create: invalid URI
>>> nta.c:8722 outgoing_free() nta: outgoing_free(0x7f4e8007f540)
>>> nua_stack.c:271 nua_stack_event() nua(0x7f4e90070360): event r_notify
>>> 900 Internal error at nua_client.c:713
>>> nua_stack.c:359 nua_application_event() nua: nua_application_event:
>>> entering
>>> nua_stack.c:529 nua_signal() nua(0x7f4e90070360): sent signal r_notify
>>> 2017-02-22 10:21:26.394790 [ERR] sofia_presence.c:1320 SLA EVENT:
>>> 2017-02-22 10:21:26.394790 [CRIT] sofia_presence.c:1321 DUMP
>>>
>>>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://confluence.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20170301/3883b2dc/attachment-0001.html 