[Freeswitch-users] AUDIO RTP REPORTS ERROR: [Remote Address Error!] -- [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]

Tue Oct 26 18:02:54 UTC 2021

Hello Everyone

I am using webrtc client sip.js + freeswitch 1.10.2 installed from src.

Whenever the first call comes to sip.js it gets disconnected with error
AUDIO RTP REPORTS ERROR: [Remote Address Error!] --
[INCOMPATIBLE_DESTINATION]

Here is the log file attached.

Is there any bug in the freeswitch version ? Or am I using the wrong
configuration?

Note there is no NAT on the server, it DO droplet which is directly
connected to public IP.

Any help will be highly appreciated.

Thanks.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20211026/045ace18/attachment.html>
-------------- next part --------------
2021-10-26 12:52:29.050821 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/2l3s73da at ppcf1t3o2201.invalid [d0dc30cc-9ae7-4c04-b1d6-4917c2d279ec]
2021-10-26 12:52:29.050821 [DEBUG] mod_sofia.c:5089 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State Change CS_NEW -> CS_INIT
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Running State Change CS_INIT (Cur 2 Tot 33)
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State INIT
2021-10-26 12:52:29.050821 [DEBUG] mod_sofia.c:93 sofia/internal/2l3s73da at ppcf1t3o2201.invalid SOFIA INIT
2021-10-26 12:52:29.050821 [DEBUG] sofia_glue.c:1587 sip:2l3s73da at 49.36.66.155:54529;transport=wss Setting proxy route to sofia/internal/2l3s73da at ppcf1t3o2201.invalid
2021-10-26 12:52:29.050821 [DEBUG] sofia_glue.c:1618 sofia/internal/2l3s73da at ppcf1t3o2201.invalid sending invite version: 1.10.2 -release 64bit
Local SDP:
v=0
o=FreeSWITCH 1635243287 1635243288 IN IP4 159.6X.1XX.1X
s=FreeSWITCH
c=IN IP4 159.6X.1XX.1X
t=0 0
a=msid-semantic: WMS SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5
m=audio 27462 RTP/SAVPF 9 0 8 3 101
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fingerprint:sha-256 05:2D:ED:8F:CD:13:CE:A4:E2:7E:39:E3:56:52:D4:57:FF:4C:D6:6D:A6:34:B6:C0:30:AC:F5:FE:86:0A:1A:57
a=setup:actpass
a=rtcp-mux
a=rtcp:27462 IN IP4 159.6X.1XX.1X
a=ssrc:1166113573 cname:ujjsYJe8BAhsltt0
a=ssrc:1166113573 msid:SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5 a0
a=ssrc:1166113573 mslabel:SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5
a=ssrc:1166113573 label:SUEn7PzWk1vj1UlaPB66hNYKnESNmJO5a0
a=ice-ufrag:UpW23LDgflTsVyog
a=ice-pwd:Hyzv7Euz4CRfVb6GqSyju1pA
a=candidate:2385770488 1 udp 659136 159.6X.1XX.1X 27462 typ host generation 0
a=candidate:2385770488 2 udp 659136 159.6X.1XX.1X 27462 typ host generation 0
a=ptime:20
a=sendrecv

2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2l3s73da at ppcf1t3o2201.invalid Standard INIT
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State Change CS_INIT -> CS_ROUTING
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State INIT going to sleep
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Running State Change CS_ROUTING (Cur 2 Tot 33)
2021-10-26 12:52:29.050821 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da at ppcf1t3o2201.invalid entering state [calling][0]
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State ROUTING
2021-10-26 12:52:29.050821 [DEBUG] mod_sofia.c:154 sofia/internal/2l3s73da at ppcf1t3o2201.invalid SOFIA ROUTING
2021-10-26 12:52:29.050821 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State ROUTING going to sleep
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 33)
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State CONSUME_MEDIA
2021-10-26 12:52:29.050821 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State CONSUME_MEDIA going to sleep
2021-10-26 12:52:29.270824 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da at ppcf1t3o2201.invalid entering state [proceeding][180]
2021-10-26 12:52:29.270824 [NOTICE] sofia.c:7412 Ring-Ready sofia/internal/2l3s73da at ppcf1t3o2201.invalid!
2021-10-26 12:52:29.270824 [DEBUG] switch_channel.c:3437 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Callstate Change DOWN -> RINGING
2021-10-26 12:52:38.810821 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da at ppcf1t3o2201.invalid entering state [completing][200]
2021-10-26 12:52:38.810821 [DEBUG] sofia.c:7311 Remote SDP:
v=0
o=- 5763818288033074925 2 IN IP4 127.0.0.1
s=-
t=0 0
a=msid-semantic: WMS 7z7Ljk2XXKuNEXsvU3Esz2fbmVvl5URTXwlP
m=audio 50131 UDP/TLS/RTP/SAVPF 9 0 8 101
c=IN IP4 49.36.66.155
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=rtcp:9 IN IP4 192.0.2.63
a=candidate:840328171 1 udp 2113937151 d26fe240-6e5f-4d34-8488-39f093b59d67.local 50131 typ host generation 0 network-cost 999
a=candidate:2430225745 1 udp 2113939711 e31f962c-3822-4856-aa21-25478692ef23.local 50132 typ host generation 0 network-cost 999
a=candidate:842163049 1 udp 1677729535 49.36.66.155 50131 typ srflx raddr 192.0.2.63 rport 0 generation 0 network-cost 999
a=ice-ufrag:eyg7
a=ice-pwd:2S4J8PnNelLFAW6kaef2ZKEh
a=ice-options:trickle
a=fingerprint:sha-256 1C:3D:B2:DF:8D:EB:C3:1A:57:1F:1B:FF:41:EF:56:F6:2D:D4:A3:A6:69:C8:B0:49:B4:7B:39:82:22:BA:57:3E
a=setup:active
a=mid:0
a=rtcp-mux
a=ssrc:3396866038 cname:yLBRvE6bQhZN8vDr
a=ssrc:3396866038 msid:7z7Ljk2XXKuNEXsvU3Esz2fbmVvl5URTXwlP 64ccd886-3848-44a7-bd97-e474846a0969
a=ssrc:3396866038 mslabel:7z7Ljk2XXKuNEXsvU3Esz2fbmVvl5URTXwlP
a=ssrc:3396866038 label:64ccd886-3848-44a7-bd97-e474846a0969

2021-10-26 12:52:38.810821 [DEBUG] sofia.c:7301 Channel sofia/internal/2l3s73da at ppcf1t3o2201.invalid entering state [ready][200]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101 at 8000
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/2l3s73da at ppcf1t3o2201.invalid G722/8000 20 ms 160 samples 64000 bits 1 channels
2021-10-26 12:52:38.810821 [DEBUG] switch_core_codec.c:111 sofia/internal/2l3s73da at ppcf1t3o2201.invalid Original read codec set to G722:9
2021-10-26 12:52:38.810821 [WARNING] switch_core_media.c:4252 NO candidate ACL defined, Defaulting to wan.auto
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: host addr: d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: host addr: e31f962c-3822-4856-aa21-25478692ef23.local:50132
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: srflx addr: 49.36.66.155:50131
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4327 Searching for rtp candidate.
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4336 Choose rtp candidate, index 0, d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4102 sofia/internal/2l3s73da at ppcf1t3o2201.invalid choosing family v4
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4347 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4399 setting remote audio ice addr to index 0 d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131 based on candidate
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:4434 Setting remote rtcp audio addr to d26fe240-6e5f-4d34-8488-39f093b59d67.local:50131 based on candidate
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101 at 8000
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:5909 sofia/internal/2l3s73da at ppcf1t3o2201.invalid Set 2833 dtmf send payload to 101 recv payload to 101
2021-10-26 12:52:38.810821 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/internal/2l3s73da at ppcf1t3o2201.invalid] 159.6X.1XX.1X port 27462 -> d26fe240-6e5f-4d34-8488-39f093b59d67.local port 50131 codec: 9 ms: 20
2021-10-26 12:52:38.810821 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
2021-10-26 12:52:38.810821 [ERR] switch_core_media.c:9679 AUDIO RTP REPORTS ERROR: [Remote Address Error!]
2021-10-26 12:52:38.810821 [NOTICE] switch_core_media.c:9680 Hangup sofia/internal/2l3s73da at ppcf1t3o2201.invalid [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
2021-10-26 12:52:38.810821 [ERR] sofia.c:8481 RTP Error!
2021-10-26 12:52:38.810821 [DEBUG] sofia.c:1548 Channel is already hungup.
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Running State Change CS_HANGUP (Cur 2 Tot 33)
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Callstate Change RINGING -> HANGUP
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State HANGUP
2021-10-26 12:52:38.810821 [DEBUG] mod_sofia.c:453 Channel sofia/internal/2l3s73da at ppcf1t3o2201.invalid hanging up, cause: INCOMPATIBLE_DESTINATION
2021-10-26 12:52:38.810821 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/2l3s73da at ppcf1t3o2201.invalid
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2l3s73da at ppcf1t3o2201.invalid Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State HANGUP going to sleep
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State Change CS_HANGUP -> CS_REPORTING
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Running State Change CS_REPORTING (Cur 2 Tot 33)
2021-10-26 12:52:38.810821 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State REPORTING
2021-10-26 12:52:38.810821 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
2021-10-26 12:52:38.810821 [NOTICE] switch_ivr_originate.c:2955 Cannot create outgoing channel of type [user] cause: [INCOMPATIBLE_DESTINATION]
2021-10-26 12:52:38.810821 [DEBUG] switch_ivr_originate.c:3952 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
2021-10-26 12:52:38.830888 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2l3s73da at ppcf1t3o2201.invalid Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2021-10-26 12:52:38.830888 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State REPORTING going to sleep
2021-10-26 12:52:38.830888 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) State Change CS_REPORTING -> CS_DESTROY
2021-10-26 12:52:38.830888 [DEBUG] switch_core_session.c:1726 Session 33 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Locked, Waiting on external entities
2021-10-26 12:52:38.830888 [NOTICE] switch_core_session.c:1744 Session 33 (sofia/internal/2l3s73da at ppcf1t3o2201.invalid) Ended
2021-10-26 12:52:38.830888 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/2l3s73da at ppcf1t3o2201.invalid [CS_DESTROY]