[OpenSIPS-Users] Trouble with forked calls and rtpengine

Thu Jan 6 19:25:25 UTC 2022

Attached here is a prettier version of the three offers.
-------------- next part --------------
>From opensips

Jan  1 10:03:57 [2670144] Invite with first via host 192.168.1.2 and branch ID z9hG4bKd83e.3a8b6577.0
Jan  1 10:03:57 [2670144] WebRTC-legacy interworking
Jan  1 10:03:57 [2670144] The answer profile must be opposite of the offer profile
Jan  1 10:03:57 [2670144] Setting RTP profile for answer to  debug via-branch=2 SDES-off ICE=force UDP/TLS/RTP/SAVPF replace-session-connection replace-origin DTLS-fingerprint=sha-256 rtcp-mux-require generate-mid
Jan  1 10:03:57 [2670144] DBG:core:comp_scriptvar: str 29:  in-iface=ipv4-priv out-iface=ipv6
Jan  1 10:03:57 [2670144] Interfaces are  in-iface=ipv4-priv out-iface=ipv6
Jan  1 10:03:57 [2670144] DBG:core:parse_headers: flags=ffffffffffffffff
Jan  1 10:03:57 [2670144] DBG:core:decode_mime_type: Decoding MIME type for:[application/sdp]
Jan  1 10:03:57 [2670144] DBG:core:parse_headers: flags=40
Jan  1 10:03:57 [2670144] DBG:core:parse_to_param: tag=as1g4gcnjp
Jan  1 10:03:57 [2670144] DBG:core:_parse_to: end of header reached, state=29
Jan  1 10:03:57 [2670144] DBG:core:_parse_to: display={"Guest"}, ruri={sip:7 at cwdrive.mooo.com}
Jan  1 10:03:57 [2670144] DBG:core:parse_headers: flags=4
Jan  1 10:03:57 [2670144] DBG:rtpengine:rtpe_function_call: proxy reply: d3:sdp741:v=0
o=twinkle 2116263177 238598101 IN IP6 2001:569:7eb9:a400:223:7dff:feb8:d2b4
s=-
c=IN IP6 2001:569:7eb9:a400:223:7dff:feb8:d2b4
t=0 0
m=audio 35020 UDP/TLS/RTP/SAVPF 0 110
a=mid:0
a=rtpmap:0 PCMU/8000
a=rtpmap:110 telephone-event/8000
a=fmtp:110 0-15
a=sendrecv
a=rtcp:35021
a=setup:active
a=fingerprint:sha-256 D9:B5:31:EE:D5:88:EC:84:B7:D7:D6:C7:73:45:A3:09:3B:A4:32:0A:C0:B0:DC:28:56:4C:DB:03:22:0B:22:DE
a=ptime:20
a=ice-ufrag:ARYGxrUa
a=ice-pwd:jyP7JQCqLbW9wGFzL5ClW45SLj
a=ice-options:trickle
a=candidate:1wwouT4DYwT3ocfl 1 UDP 2130706431 2001:569:7eb9:a400:223:7dff:feb8:d2b4 35020 typ host
a=candidate:1wwouT4DYwT3ocfl 2 UDP 2130706430 2001:569:7eb9:a400:223:7dff:feb8:d2b4 35021 typ host
a=end-of-candidates
6:result2:oke

Notice from above --
Setting RTP profile for answer to  debug via-branch=2 SDES-off ICE=force UDP/TLS/RTP/SAVPF replace-session-connection replace-origin DTLS-fingerprint=sha-256 rtcp-mux-require generate-mid

rtcp-mux-required was passed to rtpengine but sdp from rtpengine did not include it.

>From the caller web application

ac_webrtc.min.js:9 emit "peerconnection:setremotedescriptionfailed" [error:DOMException: Failed to execute 'setRemoteDescription' on 'RTCPeerConnection': Failed to set remote answer sdp: The m= section with mid='0' is invalid. RTCP-MUX is not enabled when it is required.]

>From rtpengine log

First offer
"ICE": "remove", "direction": [ "ipv6", "ipv4-priv" ], "flags": [ "debug" ], "replace": [ "session-connection", "origin" ], "transport-protocol": "RTP/AVP", "rtcp-mux": [ "demux" ], "call-id": "s25p40fpr5g0u52b96dp", "via-branch": "z9hG4bK3119290", "received-from": [ "IP6", "2001:569:7EB9:A400:8A42:A64E:CE7C:F58F" ], "from-tag": "as1g4gcnjp", "command": "offer" }
Jan  1 10:03:54 slim rtpengine[2517903]: NOTICE: [s25p40fpr5g0u52b96dp]: [core] Creating new call
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] getting monologue for tag 'as1g4gcnjp' in call 's25p40fpr5g0u52b96dp'
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] creating new monologue
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] tagging monologue with 'as1g4gcnjp'
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] create new "other side" monologue for viabranch z9hG4bK3119290
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] creating new monologue
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] tagging monologue with viabranch 'z9hG4bK3119290'
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] this= other=as1g4gcnjp

Second offer
"ICE": "remove", "direction": [ "ipv6", "ipv4-priv" ], "flags": [ "debug" ], "replace": [ "session-connection", "origin" ], "transport-protocol": "RTP/AVP", "rtcp-mux": [ "demux" ], "call-id": "s25p40fpr5g0u52b96dp", "via-branch": "z9hG4bK3119290", "received-from": [ "IP6", "2001:569:7EB9:A400:8A42:A64E:CE7C:F58F" ], "from-tag": "as1g4gcnjp", "command": "offer" }
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] getting monologue for tag 'as1g4gcnjp' in call 's25p40fpr5g0u52b96dp'
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] found existing monologue
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] this= other=as1g4gcnjp

Third offer
 "ICE": "force", "DTLS-fingerprint": "sha-256", "direction": [ "ipv4-priv", "ipv4-ext" ], "flags": [ "debug", "SDES-off", "generate-mid" ], "replace": [ "session-connection", "origin" ], "transport-protocol": "UDP/TLS/RTP/SAVPF", "rtcp-mux": [ "require" ], "call-id": "s25p40fpr5g0u52b96dp", "via-branch": "z9hG4bK3119290", "received-from": [ "IP6", "2001:569:7EB9:A400:8A42:A64E:CE7C:F58F" ], "from-tag": "as1g4gcnjp", "command": "offer" }
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] getting monologue for tag 'as1g4gcnjp' in call 's25p40fpr5g0u52b96dp'
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] found existing monologue
Jan  1 10:03:54 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] this= other=as1g4gcnjp

The answer
"ICE": "force", "DTLS-fingerprint": "sha-256", "direction": [ "ipv4-priv", "ipv6" ], "flags": [ "debug", "SDES-off", "generate-mid" ], "replace": [ "session-connection", "origin" ], "transport-protocol": "UDP/TLS/RTP/SAVPF", "rtcp-mux": [ "require" ], "call-id": "s25p40fpr5g0u52b96dp", "via-branch": "z9hG4bK3119290", "received-from": [ "IP4", "192.168.1.87" ], "from-tag": "as1g4gcnjp", "to-tag": "kvhgy", "command": "answer" }
Jan  1 10:03:57 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] getting dialogue for tags 'kvhgy'<>'as1g4gcnjp' in call 's25p40fpr5g0u52b96dp'
Jan  1 10:03:57 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] tagging monologue with 'kvhgy'
Jan  1 10:03:57 slim rtpengine[2517903]: DEBUG: [s25p40fpr5g0u52b96dp]: [internals] this=as1g4gcnjp other=kvhgy