<div dir="ltr">Hi<div><br></div><div>perfectly agree that there are so many queries to this end and almost all pointers are towards codec negotiation failures. i still have this issue after i see that codec negotiation goes through.</div><div><br></div><div>i have set up 2 Js-SIP clients. webrtc.</div><div>i am able to connect and register both clients (can see those in <u><b>sofia status profile external reg</b></u>)</div><div><br></div><div>however when i place a call from one client (extension 1000) to another (extension 1001), i receive a "488 Not Acceptable Here" message and the call ends with "Bad Media Description" error and i never get the call on receiver side</div><div><br></div><div>i am completely at the surface still and have given it a try with all solutions posted to this end</div><div><br></div><div>attaching the entire log in a file here. please help </div><div><br></div><div>here are some important bits from the file:</div><div><br></div><div><br></div><div>INVITE sip<br><br>INVITE <a href="mailto:sip%3A1001@11.22.33.44">sip:1001@11.22.33.44</a> SIP/2.0<br>Via: SIP/2.0/WSS 11.22.33.44;branch=z9hG4bK7948245<br>Max-Forwards: 69<br>To: <<a href="mailto:sip%3A1001@11.22.33.44">sip:1001@11.22.33.44</a>><br>From: <<a href="mailto:sip%3A1000@11.22.33.44">sip:1000@11.22.33.44</a>>;tag=jimkkm04qn<br>Call-ID: aoc0tkcjs47mrj5n1jl4<br>CSeq: 3752 INVITE<br>X-Test: tester-tested<br>Contact: <<a href="mailto:sip%3A1000@11.22.33.44">sip:1000@11.22.33.44</a>;ob><br>Content-Type: application/sdp<br>Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER,INFO,NOTIFY<br>Supported: ice,replaces,outbound<br>User-Agent: JsSIP 3.9.1<br>Content-Length: 1950<br><br>v=0<br>o=- 8689874841498562319 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=extmap-allow-mixed<br>a=msid-semantic: WMS qikHHSycCzJUUpbTJgCfWpaVC44ukpKp5iAX<br>m=audio 59566 UDP/TLS/RTP/SAVPF 111 63 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 192.168.1.64<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:3460887983 1 udp 2122260223 192.168.1.64 59566 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:2403671225 1 udp 2122194687 172.27.239.86 55376 typ host generation 0 network-id 2 network-cost 50<br>a=candidate:2160789855 1 tcp 1518280447 192.168.1.64 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=candidate:3251036233 1 tcp 1518214911 172.27.239.86 9 typ host tcptype active generation 0 network-id 2 network-cost 50<br>a=ice-ufrag:KQsy<br>a=ice-pwd:oTFySc5ArZ1oxVZ+qb+errt5<br>a=ice-options:trickle<br>a=fingerprint:sha-256 C9:60:D5:F6:7C:26:A5:2B:A5:1C:C5:23:0A:36:4B:E2:CE:D5:C8:15:8A:AD:35:DB:2C:E3:7D:C4:E8:7C:D3:E2<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time">http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time</a><br>a=extmap:3 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=sendrecv<br>a=msid:qikHHSycCzJUUpbTJgCfWpaVC44ukpKp5iAX 871ec968-71eb-4810-a9ff-dcd4c80705b2<br>a=rtcp-mux<br>a=rtpmap:111 opus/48000/2<br>a=rtcp-fb:111 transport-cc<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:63 red/48000/2<br>a=fmtp:63 111/111<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=ssrc:3961239200 cname:Bb33wRyqhUohexBZ<br>a=ssrc:3961239200 msid:qikHHSycCzJUUpbTJgCfWpaVC44ukpKp5iAX 871ec968-71eb-4810-a9ff-dcd4c80705b2<br><div><br></div><div><br></div><div>=================</div><div><br></div><div>Remote SDP:</div><div><br></div><div>2022-10-06 10:18:15.078122 99.83% [NOTICE] switch_channel.c:1123 New Channel sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a> []<br> 2022-10-06 10:18:15.078122 99.83% [DEBUG] switch_core_state_machine.c:581 (sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a>) Running State Change CS_NEW (Cur 1 Tot 11)<br> 2022-10-06 10:18:15.078122 99.83% [INFO] sofia.c:10465 sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a> receiving invite from <a href="http://3.7.130.46:54985">3.7.130.46:54985</a> version: 1.10.8-dev git 98d2e62 2022-09-09 13:32:35Z 64bit call-id: aoc0tkcjs47mrj5n1jl4<br> 2022-10-06 10:18:15.078122 99.83% [DEBUG] sofia.c:11674 Setting NAT mode based on websockets<br> 2022-10-06 10:18:15.078122 99.83% [DEBUG] sofia.c:7499 Channel sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a> entering state [received][100]<br> 2022-10-06 10:18:15.078122 99.83% [DEBUG] sofia.c:7509 Remote SDP:<br> v=0<br> o=- 8689874841498562319 2 IN IP4 127.0.0.1<br> s=-<br> t=0 0<br> a=group:BUNDLE 0<br> a=extmap-allow-mixed<br> a=msid-semantic: WMS qikHHSycCzJUUpbTJgCfWpaVC44ukpKp5iAX<br> m=audio 59566 UDP/TLS/RTP/SAVPF 111 63 103 104 9 0 8 106 105 13 110 112 113 126<br> c=IN IP4 192.168.1.64<br> a=rtpmap:111 opus/48000/2<br> a=fmtp:111 minptime=10;useinbandfec=1<br> a=rtpmap:63 red/48000/2<br> a=fmtp:63 111/111<br> a=rtpmap:103 ISAC/16000<br> a=rtpmap:104 ISAC/32000<br> a=rtpmap:9 G722/8000<br> a=rtpmap:0 PCMU/8000<br> a=rtpmap:8 PCMA/8000<br> a=rtpmap:106 CN/32000<br> a=rtpmap:105 CN/16000<br> a=rtpmap:13 CN/8000<br> a=rtpmap:110 telephone-event/48000<br> a=rtpmap:112 telephone-event/32000<br> a=rtpmap:113 telephone-event/16000<br> a=rtpmap:126 telephone-event/8000<br> a=rtcp:9 IN IP4 0.0.0.0<br> a=candidate:3460887983 1 udp 2122260223 192.168.1.64 59566 typ host generation 0 network-id 1 network-cost 10<br> a=candidate:2403671225 1 udp 2122194687 172.27.239.86 55376 typ host generation 0 network-id 2 network-cost 50<br> a=candidate:2160789855 1 tcp 1518280447 192.168.1.64 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br> a=candidate:3251036233 1 tcp 1518214911 172.27.239.86 9 typ host tcptype active generation 0 network-id 2 network-cost 50<br> a=ice-ufrag:KQsy<br> a=ice-pwd:oTFySc5ArZ1oxVZ+qb+errt5<br> a=ice-options:trickle<br> a=fingerprint:sha-256 C9:60:D5:F6:7C:26:A5:2B:A5:1C:C5:23:0A:36:4B:E2:CE:D5:C8:15:8A:AD:35:DB:2C:E3:7D:C4:E8:7C:D3:E2<br> a=setup:actpass<br> a=mid:0<br> a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br> a=extmap:2 <a href="http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time">http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time</a><br> a=extmap:3 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br> a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid<br> a=msid:qikHHSycCzJUUpbTJgCfWpaVC44ukpKp5iAX 871ec968-71eb-4810-a9ff-dcd4c80705b2<br> a=rtcp-mux<br> a=rtcp-fb:111 transport-cc<br> a=ssrc:3961239200 cname:Bb33wRyqhUohexBZ<br> a=ssrc:3961239200 msid:qikHHSycCzJUUpbTJgCfWpaVC44ukpKp5iAX 871ec968-71eb-4810-a9ff-dcd4c80705b2<br> <br></div><div>=============================================</div><div>codec negotiation. i have removed OPUS as it was giving me bitrate mismatch</div><div><u>G722, PCMU, PCMA are exact match</u></div><div><br></div><div> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [opus:111:48000:20:0:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [opus:111:48000:20:0:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [opus:111:48000:20:0:1]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [red:63:48000:20:0:2]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [red:63:48000:20:0:2]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [red:63:48000:20:0:2]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5718 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5718 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5718 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [CN:105:16000:20:0:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [CN:105:16000:20:0:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [CN:105:16000:20:0:1]/[PCMA:8:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [CN:13:8000:20:0:1]/[G722:9:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMU:0:8000:20:64000:1]<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_core_media.c:5663 Audio Codec Compare [CN:13:8000:20:0:1]/[PCMA:8:8000:20:64000:1]<br></div><div><br></div><div>=======================================================</div><div><br></div><div>SIP to receiver</div><div><br></div><div><br></div><div>2022-10-06 10:18:25.018122 99.80% [DEBUG] mod_sofia.c:913 Local SDP sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a>:<br> v=0<br> o=FreeSWITCH 1665021629 1665021630 IN IP4 11.22.33.44<br> s=FreeSWITCH<br> c=IN IP4 11.22.33.44<br> t=0 0<br> a=msid-semantic: WMS VUSFGZ4CEjXvMLYzijtMWkwcRx3I8zET<br> m=audio 29876 UDP/AVPF 9 126<br> a=rtpmap:9 G722/8000<br> a=rtpmap:126 telephone-event/8000<br> a=ptime:20<br> a=sendrecv<br> a=rtcp:29877 IN IP4 11.22.33.44<br> a=ice-ufrag:0OsMZPPHcIga8KUi<br> a=ice-pwd:XaZYj9vC6oyD4TjUxCxnhcUk<br> a=candidate:0153546995 1 udp 2130706431 11.22.33.44 29876 typ host generation 0<br> a=candidate:0153546995 2 udp 2130706430 11.22.33.44 29877 typ host generation 0<br> a=end-of-candidates<br> a=ssrc:3007569695 cname:kmpRbjjGt625HQuP<br> a=ssrc:3007569695 msid:VUSFGZ4CEjXvMLYzijtMWkwcRx3I8zET a0<br> a=ssrc:3007569695 mslabel:VUSFGZ4CEjXvMLYzijtMWkwcRx3I8zET<br> a=ssrc:3007569695 label:VUSFGZ4CEjXvMLYzijtMWkwcRx3I8zETa0<br> <br> 2022-10-06 10:18:25.018122 99.80% [NOTICE] mod_dptools.c:1408 Channel [sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a>] has been answered<br>send 985 bytes to wss/[3.7.130.46]:54985 at 10:18:25.032155:<br>------------------------------------------------------------------------<br>SIP/2.0 200 OK<br>Via: SIP/2.0/WSS 11.22.33.44;branch=z9hG4bK7948245;received=3.7.130.46;rport=54985<br>From: <<a href="mailto:sip%3A1000@11.22.33.44">sip:1000@11.22.33.44</a>>;tag=jimkkm04qn<br>To: <<a href="mailto:sip%3A1001@11.22.33.44">sip:1001@11.22.33.44</a>>;tag=0jtBBc4ryZ8Zg<br>Call-ID: aoc0tkcjs47mrj5n1jl4<br>CSeq: 3752 INVITE<br>Contact: <sip:1001@11.22.33.44:5080;transport=udp><br>User-Agent: FreeSWITCH-mod_sofia/1.10.8-dev+git~20220909T133235Z~98d2e6212c~64bit<br>Accept: application/sdp<br>Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY<br>Supported: timer, path, replaces<br>Allow-Events: talk, hold, conference, refer<br>Session-Expires: 120;refresher=uas<br>Content-Type: application/sdp<br>Content-Disposition: session<br>Content-Length: 196<br>Remote-Party-ID: "1001" <<a href="mailto:sip%3A1001@11.22.33.44">sip:1001@11.22.33.44</a>>;party=calling;privacy=off;screen=no<br><br>v=0<br>o=FreeSWITCH 1665021629 1665021630 IN IP4 11.22.33.44<br>s=FreeSWITCH<br>c=IN IP4 11.22.33.44<br>t=0 0<br>a=msid-semantic: WMS VUSFGZ4CEjXvMLYzijtMWkwcRx3I8zET<br><b>m=audio 0 UDP/TLS/RTP/SAVPF 19</b><br><br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] switch_channel.c:3950 (sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a>) Callstate Change EARLY -> ACTIVE<br> 2022-10-06 10:18:25.018122 99.80% [DEBUG] sofia.c:7499 Channel sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a> entering state [completed][200]<br> EXECUTE [depth=0] sofia/external/<a href="mailto:1000@11.22.33.44">1000@11.22.33.44</a> sleep(1000)<br></div><div><br></div><div><br></div><div>==============================================</div><div><br></div><div><br></div><div>and then the BYE </div><div><br></div><div>------------------------------------------------------------------------<br>ACK sip:1001@11.22.33.44:5080;transport=udp SIP/2.0<br>Via: SIP/2.0/WSS 11.22.33.44;branch=z9hG4bK8228266<br>Max-Forwards: 69<br>To: <<a href="mailto:sip%3A1001@11.22.33.44">sip:1001@11.22.33.44</a>>;tag=0jtBBc4ryZ8Zg<br>From: <<a href="mailto:sip%3A1000@11.22.33.44">sip:1000@11.22.33.44</a>>;tag=jimkkm04qn<br>Call-ID: aoc0tkcjs47mrj5n1jl4<br>CSeq: 3752 ACK<br>Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER,INFO,NOTIFY<br>Supported: outbound<br>User-Agent: JsSIP 3.9.1<br>Content-Length: 0<br><br><br>recv 462 bytes from wss/[3.7.130.46]:54985 at 10:18:26.584127:<br>------------------------------------------------------------------------<br>BYE sip:1001@11.22.33.44:5080;transport=udp SIP/2.0<br>Via: SIP/2.0/WSS 11.22.33.44;branch=z9hG4bK162798<br>Max-Forwards: 69<br>To: <<a href="mailto:sip%3A1001@11.22.33.44">sip:1001@11.22.33.44</a>>;tag=0jtBBc4ryZ8Zg<br>From: <<a href="mailto:sip%3A1000@11.22.33.44">sip:1000@11.22.33.44</a>>;tag=jimkkm04qn<br>Call-ID: aoc0tkcjs47mrj5n1jl4<br>CSeq: 3753 BYE<br><b>Reason: SIP ;cause=488; text="Not Acceptable Here"</b><br>Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER,INFO,NOTIFY<br>Supported: outbound<br>User-Agent: JsSIP 3.9.1<br>Content-Length: 0<br></div><div><br></div><div><br></div>-- <br><div dir="ltr" data-smartmail="gmail_signature">Shwetank</div></div></div>