[OpenSIPS-Users] LATE SDP Script on Opensips 3.3

Thu Jan 26 17:44:49 UTC 2023

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/20230126/02d53a75/attachment.html>