[OpenSIPS-Users] LATE SDP Script on Opensips 3.3

Sat Feb 4 13:25:48 UTC 2023

Hi,

It seems I have fixed issue number 2. But issue 1 is still there (I call a
browser (Firefox or chrome), the call is rejected and I get the error:
"Failed to get local SDP".). Can someone please confirm if NAT/IP detection
works on Opensips 3.3.3? I have tested the exact same config and it works
fine on 3.0.5.

I have df7jal23ls0d.invalid in the SDP on Opensips 3.3.3.

Best wishes.

On Thu, Jan 26, 2023 at 10:44 PM HS <bullehs at gmail.com> wrote:

> Hi all,
>
> Here's is a link to the log of 2 calls. First part is a call that has
> one-way audio and the second is a call that has great bi-directional audio.
> https://pastebin.com/ggUFUmsp
>
> Appreciate if someone can identify if opensips config is ok pls, or
> suggestions on how to fix pls.
>
> On Sat, Jan 21, 2023 at 3:34 PM HS <bullehs at gmail.com> wrote:
>
>> Hi all.
>>
>> I am trying to setup an Opensips 3.3 instance on AWS and have rtpengine
>> installed. I am using a modified version of the script here:
>>
>> http://www.opensips.org/pub/docs/tutorials/websockets/opensips-late.cfg
>>
>> Two issues.
>> 1. When I call a browser (Firefox or chrome), the call is rejected and I
>> get the error: "Failed to get local SDP".
>> 2. One-way audio in specific circumstances - log below. Investigating
>> further I found that if I use "t_on_reply("handle_nat");" the calls go
>> through fine. However, if I use "t_on_branch("handle_nat");" I see
>> behaviour below.
>>
>> Thanks in advance.
>>
>> I am using 2 UA - Microsip and Linphone. When I call from Microsip
>> (Laptop) to Linphone (Android) I have great audio and the logs show this:
>>
>> Jan 20 16:11:06 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3]: [control] Received command 'answer'
>> from 127.0.0.1:33919
>> Jan 20 16:11:06 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3]: [control] Replying to 'answer' from
>> 127.0.0.1:33919 (elapsed time 0.000268 sec)
>> Jan 20 16:11:06 ip-172-31-13-220 /usr/sbin/opensips[79601]: incoming reply
>> Jan 20 16:11:07 ip-172-31-13-220 dhclient[451]: DHCPREQUEST for
>> 172.31.13.220 on ens5 to 172.31.0.1 port 67
>> Jan 20 16:11:07 ip-172-31-13-220 dhclient[451]: DHCPACK of 172.31.13.220
>> from 172.31.0.1
>> Jan 20 16:11:07 ip-172-31-13-220 dhclient[451]: bound to 172.31.13.220 --
>> renewal in 1607 seconds.
>> Jan 20 16:11:10 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3 port 13215]: [core] Confirmed peer
>> address as My.Pub.IP.Add:4011
>> Jan 20 16:11:21 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3]: [control] Received command 'delete'
>> from 127.0.0.1:33919
>> Jan 20 16:11:21 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3]: [core] Scheduling deletion of call
>> branch 'bGUEC~8' (via-branch '') in 30 seconds
>> Jan 20 16:11:21 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3]: [core] Scheduling deletion of call
>> branch '33a25bec39c742678ce74836e0ff40d1' (via-branch '') in 30 seconds
>> Jan 20 16:11:21 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3]: [core] Scheduling deletion of entire
>> call in 30 seconds
>> Jan 20 16:11:21 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [f75f1e374747457ca016e3a3a62ff4b3]: [control] Replying to 'delete' from
>> 127.0.0.1:33919 (elapsed time 0.000179 sec)
>>
>>
>> However, when I call from Linphone to Microsip, there's one-way audio and
>> the following in the logs:
>>
>> Jan 20 16:02:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Received command 'offer' from 127.0.0.1:40022
>> Jan 20 16:02:29 ip-172-31-13-220 rtpengine[42387]: NOTICE: [CjBB-HZqpe]:
>> [core] Creating new call
>> Jan 20 16:02:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Replying to 'offer' from 127.0.0.1:40022 (elapsed time
>> 0.000500 sec)
>> Jan 20 16:02:37 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Received command 'answer' from 127.0.0.1:40022
>> Jan 20 16:02:37 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Replying to 'answer' from 127.0.0.1:40022 (elapsed time
>> 0.000209 sec)
>> Jan 20 16:02:37 ip-172-31-13-220 /usr/sbin/opensips[79600]: incoming reply
>> Jan 20 16:02:38 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Received command 'answer' from 127.0.0.1:40022
>> Jan 20 16:02:38 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Replying to 'answer' from 127.0.0.1:40022 (elapsed time
>> 0.000178 sec)
>> Jan 20 16:02:38 ip-172-31-13-220 /usr/sbin/opensips[79600]: incoming reply
>> Jan 20 16:02:39 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Received command 'answer' from 127.0.0.1:40022
>> Jan 20 16:02:39 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Replying to 'answer' from 127.0.0.1:40022 (elapsed time
>> 0.000139 sec)
>> Jan 20 16:02:39 ip-172-31-13-220 /usr/sbin/opensips[79600]: incoming reply
>> Jan 20 16:02:44 ip-172-31-13-220 dhclient[524]: XMT: Solicit on ens5,
>> interval 111260ms.
>> Jan 20 16:02:45 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe port
>> 13139]: [core] Confirmed peer address as My.Pub.IP.Add:4007
>> Jan 20 16:02:46 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe port
>> 13146]: [core] Confirmed peer address as My.Pub.IP.Add:7078
>> Jan 20 16:02:46 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe port
>> 13146]: [core] Kernelizing media stream: My.Pub.IP.Add:7078 ->
>> 172.31.13.220:13146 | 172.31.13.220:13138 -> My.Pub.IP.Add:4006
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] Final packet stats:
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] --- Tag
>> 'de22d68c1c7e4e759933a940e2f9eaa8', created 1:30 ago for branch ''
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] ---     subscribed to 'PCjqSrP'
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] ---     subscription for
>> 'PCjqSrP'
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] ------ Media #1 (audio over
>> RTP/AVP) using opus/48000/2
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] --------- Port
>> 172.31.13.220:13126 <>   My.Pub.IP.Add:4004 , SSRC 3cca2cda, in 4 p, 185
>> b, 0 e, 83 ts, out 2 p, 67 b, 0 e
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] --------- Port
>> 172.31.13.220:13127 <>   My.Pub.IP.Add:4005  (RTCP), SSRC 3cca2cda, in 4
>> p, 276 b, 0 e, 83 ts, out 0 p, 0 b, 0 e
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] --- Tag 'PCjqSrP', created 1:30
>> ago for branch ''
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] ---     subscribed to
>> 'de22d68c1c7e4e759933a940e2f9eaa8'
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] ---     subscription for
>> 'de22d68c1c7e4e759933a940e2f9eaa8'
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] ------ Media #1 (audio over
>> RTP/AVP) using opus/48000/2
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] --------- Port
>> 172.31.13.220:13112 <>   My.Pub.IP.Add:7078 , SSRC 1a445cd2, in 2 p, 67
>> b, 0 e, 83 ts, out 4 p, 185 b, 0 e
>> Jan 20 16:02:51 ip-172-31-13-220 rtpengine[42387]: INFO:
>> [8fbde711f8864432ba7a37290d224a9a]: [core] --------- Port
>> 172.31.13.220:13113 <>   My.Pub.IP.Add:7079  (RTCP), SSRC 0, in 2 p, 40
>> b, 2 e, 83 ts, out 4 p, 276 b, 0 e
>> Jan 20 16:02:52 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe port
>> 13138]: [core] Confirmed peer address as My.Pub.IP.Add:4006
>> Jan 20 16:02:52 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe port
>> 13138]: [core] Kernelizing media stream: My.Pub.IP.Add:4006 ->
>> 172.31.13.220:13138 | 172.31.13.220:13146 -> My.Pub.IP.Add:7078
>> Jan 20 16:02:59 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Received command 'delete' from 127.0.0.1:40022
>> Jan 20 16:02:59 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] Scheduling deletion of call branch '8Cn0Y-HU7' (via-branch '') in 30
>> seconds
>> Jan 20 16:02:59 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] Scheduling deletion of call branch
>> 'eaaabeb8d95e4b849c4ebc092e9aee47' (via-branch '') in 30 seconds
>> Jan 20 16:02:59 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] Scheduling deletion of entire call in 30 seconds
>> Jan 20 16:02:59 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [control] Replying to 'delete' from 127.0.0.1:40022 (elapsed time
>> 0.000111 sec)
>> Jan 20 16:03:01 ip-172-31-13-220 CRON[79619]: (root) CMD ((cd
>> /var/www/html/opensips-cp/; /usr/bin/php cron_job/get_opensips_stats.php))
>> Jan 20 16:03:01 ip-172-31-13-220 CRON[79618]: (CRON) info (No MTA
>> installed, discarding output)
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] Final packet stats:
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] --- Tag '8Cn0Y-HU7', created 1:00 ago for branch ''
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] ---     subscribed to 'eaaabeb8d95e4b849c4ebc092e9aee47'
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] ---     subscription for 'eaaabeb8d95e4b849c4ebc092e9aee47'
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] ------ Media #1 (audio over RTP/AVP) using opus/48000/2
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] --------- Port   172.31.13.220:13146 <>   My.Pub.IP.Add:7078 ,
>> SSRC 3b8965ac, in 6 p, 242 b, 0 e, 35 ts, out 7 p, 322 b, 0 e
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] --------- Port   172.31.13.220:13147 <>   My.Pub.IP.Add:7079
>>  (RTCP), SSRC 3b8965ac, in 5 p, 228 b, 4 e, 49 ts, out 12 p, 916 b, 0 e
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] --- Tag 'eaaabeb8d95e4b849c4ebc092e9aee47', created 1:00 ago for
>> branch ''
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] ---     subscribed to '8Cn0Y-HU7'
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] ---     subscription for '8Cn0Y-HU7'
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] ------ Media #1 (audio over RTP/AVP) using opus/48000/2
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] --------- Port   172.31.13.220:13138 <>   My.Pub.IP.Add:4006 ,
>> SSRC 52e506de, in 7 p, 322 b, 0 e, 37 ts, out 6 p, 242 b, 0 e
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] --------- Port   172.31.13.220:13139 <>   My.Pub.IP.Add:4007
>>  (RTCP), SSRC 52e506de, in 12 p, 916 b, 0 e, 29 ts, out 1 p, 148 b, 0 e
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] Removing media stream from kernel: local 172.31.13.220:13138
>> Jan 20 16:03:29 ip-172-31-13-220 rtpengine[42387]: INFO: [CjBB-HZqpe]:
>> [core] Removing media stream from kernel: local 172.31.13.220:13146
>>
>> Appreciate any feedback.
>>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20230204/9a2accfc/attachment-0001.html>