[Freeswitch-users] INCOMPATIBLE_DESTINATION

Mon Jun 9 17:02:50 MSD 2014

The gcc 4.6 toolchain has a bug that causes this issue.  Using 4.7 fixed it for us.

On Jun 8, 2014, at 5:33 AM, Shlomi Agiv <shlomi.agiv at cell-buddy.com> wrote:

> It's a very similar system to raspberry pi - it's armel based.
> 
> What's the issue?
> Was it solved?
> What can I do about it?
> 
> Thanks,
> Shlomi
> 
> 
> On Thu, Jun 5, 2014 at 6:14 PM, Christopher Rienzo <cmrienzo at gmail.com> wrote:
> Is your freeswitch server running on raspberry pi?  This exact problem was experienced on it and was caused by compiler bug.
> 
> 
> On Thu, Jun 5, 2014 at 11:06 AM, Shlomi Agiv <shlomi.agiv at cell-buddy.com> wrote:
> more than that, if i understand correctly, the ptime is defined as 20 but is somehow changed.
> 
> 2014-06-05 15:04:17.474052 [DEBUG] sofia_glue.c:1226 sofia/internal/sip:1005 at 10.0.4.4:5060 sending invite version: 1.4.4  32bit
> Local SDP:
> v=0
> o=FreeSWITCH 1401962817 1401962818 IN IP4 10.0.1.233
> s=FreeSWITCH
> c=IN IP4 10.0.1.233
> t=0 0
> m=audio 17840 RTP/AVP 8 0 101 13
> a=rtpmap:8 PCMA/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=ptime:20
> a=sendrecv
> 
> 2014-06-05 15:04:17.474052 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/sip:1005 at 10.0.4.4:5060 [BREAK]
> 2014-06-05 15:04:17.474052 [DEBUG] switch_core_state_machine.c:40 sofia/internal/sip:1005 at 10.0.4.4:5060 Standard INIT
> 2014-06-05 15:04:17.474052 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/sip:1005 at 10.0.4.4:5060) State Change CS_INIT -> CS_ROUTING
> 2014-06-05 15:04:17.474052 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:1005 at 10.0.4.4:5060 [BREAK]
> 2014-06-05 15:04:17.474052 [DEBUG] switch_core_state_machine.c:507 (sofia/internal/sip:1005 at 10.0.4.4:5060) State INIT going to sleep
> 2014-06-05 15:04:17.474052 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:1005 at 10.0.4.4:5060) Running State Change CS_ROUTING
> 2014-06-05 15:04:17.474052 [DEBUG] sofia.c:6206 Channel sofia/internal/sip:1005 at 10.0.4.4:5060 entering state [calling][0]
> 2014-06-05 15:04:17.494068 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/sip:1005 at 10.0.4.4:5060) State ROUTING
> 2014-06-05 15:04:17.494068 [DEBUG] mod_sofia.c:123 sofia/internal/sip:1005 at 10.0.4.4:5060 SOFIA ROUTING
> 2014-06-05 15:04:17.494068 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:1005 at 10.0.4.4:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
> 2014-06-05 15:04:17.494068 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/sip:1005 at 10.0.4.4:5060 [BREAK]
> 2014-06-05 15:04:17.494068 [DEBUG] switch_core_state_machine.c:523 (sofia/internal/sip:1005 at 10.0.4.4:5060) State ROUTING going to sleep
> 2014-06-05 15:04:17.494068 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/sip:1005 at 10.0.4.4:5060 [BREAK]
> 2014-06-05 15:04:17.494068 [DEBUG] switch_core_session.c:1052 Send signal sofia/internal/sip:1005 at 10.0.4.4:5060 [BREAK]
> 2014-06-05 15:04:17.514119 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/sip:1005 at 10.0.4.4:5060) Running State Change CS_CONSUME_MEDIA
> 2014-06-05 15:04:17.534216 [DEBUG] sofia.c:6206 Channel sofia/internal/sip:1005 at 10.0.4.4:5060 entering state [proceeding][180]
> 2014-06-05 15:04:17.534216 [NOTICE] sofia.c:6296 Ring-Ready sofia/internal/sip:1005 at 10.0.4.4:5060!
> 2014-06-05 15:04:17.534216 [DEBUG] switch_channel.c:3271 (sofia/internal/sip:1005 at 10.0.4.4:5060) Callstate Change DOWN -> RINGING
> 2014-06-05 15:04:17.534216 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/sip:1005 at 10.0.4.4:5060) State CONSUME_MEDIA
> 2014-06-05 15:04:17.534216 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/sip:1005 at 10.0.4.4:5060) State CONSUME_MEDIA going to sleep
> 2014-06-05 15:04:17.534216 [INFO] switch_ivr_originate.c:1191 Sending early media
> 2014-06-05 15:04:17.534216 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[G722:9:8000:20:64000]
> 2014-06-05 15:04:17.534216 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[PCMU:0:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[PCMA:8:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[GSM:3:8000:20:13200]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3309 Set telephone-event payload to 101
> 2014-06-05 15:04:17.554154 [DEBUG] switch_core_media.c:3626 Set 2833 dtmf send/recv payload to 101
> 2014-06-05 15:04:17.554154 [ERR] mod_sofia.c:2201 CODEC NEGOTIATION ERROR.  SDP:
> v=0
> o=- 3610969455 3610969455 IN IP4 10.0.1.46
> s=pjmedia
> c=IN IP4 10.0.1.46
> t=0 0
> m=audio 4002 RTP/AVP 99 8 0 101
> c=IN IP4 10.0.1.46
> a=rtpmap:99 SILK/24000
> a=fmtp:99 useinbandfec=0
> a=rtpmap:8 PCMA/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=rtcp:4003 IN IP4 10.0.1.46
> 
> 
> 
> On Thu, Jun 5, 2014 at 4:59 PM, Shlomi Agiv <shlomi.agiv at cell-buddy.com> wrote:
> strangely enough, it doesn't seem like a client issue.
> I have registered an IP phone, ekiga on pc, and csipsimple, and tried making calls from each to each, all showing the same behavior.
> for instance, from ip phone(1005) to ekiga(1009)
> 2014-06-05 13:58:00.462309 [NOTICE] sofia.c:6296 Ring-Ready sofia/internal/sip:1009 at 10.0.1.14:5060!
> 2014-06-05 13:58:00.462309 [DEBUG] switch_channel.c:3271 (sofia/internal/sip:1009 at 10.0.1.14:5060) Callstate Change DOWN -> RINGING
> 2014-06-05 13:58:00.462309 [INFO] switch_ivr_originate.c:1191 Sending early media
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G729:18:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G729:18:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G729:18:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G729:18:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G723:4:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G723:4:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G723:4:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G723:4:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G726-32:2:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G726-32:2:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G726-32:2:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G726-32:2:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G722:9:16000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G722:9:16000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G722:9:16000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 13:58:00.462309 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [G722:9:16000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 13:58:00.482314 [DEBUG] switch_core_media.c:3309 Set telephone-event payload to 101
> 2014-06-05 13:58:00.482314 [DEBUG] switch_core_media.c:3626 Set 2833 dtmf send/recv payload to 101
> 2014-06-05 13:58:00.482314 [ERR] mod_sofia.c:2201 CODEC NEGOTIATION ERROR.  SDP:
> v=0
> o=1005 75411051 19394211 IN IP4 10.0.4.4
> s=A conversation
> c=IN IP4 10.0.4.4
> t=0 0
> m=audio 10136 RTP/AVP 0 8 18 4 2 9 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:18 G729/8000
> a=rtpmap:4 G723/8000
> a=rtpmap:2 G726-32/8000
> a=rtpmap:9 G722/16000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> 
> 
> 
> On Thu, Jun 5, 2014 at 3:26 PM, Steven Ayre <steveayre at gmail.com> wrote:
> Looks like a ptime mismatch, can you set the phone to use 20ms?
> 
> 
> On 5 June 2014 10:06, Shlomi Agiv <shlomi.agiv at cell-buddy.com> wrote:
> Hi,
> I'm running freswitch version 1.4.4 and getting a strange codec error
> 
> when i call from a phone running CSIPSIMPLE(registered as user 1000) to 1005, the call hangs up and I get:
> 2014-06-05 08:31:59.168784 [INFO] switch_ivr_originate.c:1191 Sending early media
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[G722:9:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[PCMU:0:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[PCMA:8:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [SILK:99:24000:20:0]/[GSM:3:8000:20:13200]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[G722:9:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[PCMU:0:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[PCMA:8:8000:20:64000]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMA:8:8000:30:32000]/[GSM:3:8000:20:13200]
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3309 Set telephone-event payload to 101
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_media.c:3626 Set 2833 dtmf send/recv payload to 101
> 2014-06-05 08:31:59.168784 [ERR] mod_sofia.c:2201 CODEC NEGOTIATION ERROR.  SDP:
> v=0
> o=- 3610945918 3610945918 IN IP4 10.0.1.46
> s=pjmedia
> c=IN IP4 10.0.1.46
> t=0 0
> m=audio 4020 RTP/AVP 99 0 8 101
> c=IN IP4 10.0.1.46
> a=rtpmap:99 SILK/24000
> a=fmtp:99 useinbandfec=0
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=rtcp:4021 IN IP4 10.0.1.46
> 
> 2014-06-05 08:31:59.168784 [DEBUG] switch_core_session.c:907 Send signal sofia/internal/1000 at 10.0.1.233 [BREAK]
> 2014-06-05 08:31:59.168784 [NOTICE] switch_channel.c:3432 Hangup sofia/internal/1000 at 10.0.1.233 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
> 
> 
> when i call using cmd 'originate user/1005 &bridge(user/1000)' The call goes through and I get:
> 
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_media.c:3437 Audio Codec Compare [PCMU:0:8000:20:64000] ++++ is saved as a match
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMA:8:8000:20:64000]
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_media.c:3383 Audio Codec Compare [PCMU:0:8000:20:64000]/[GSM:3:8000:20:13200]
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_media.c:3309 Set telephone-event payload to 101
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_media.c:2343 Set Codec sofia/internal/sip:1000 at 10.0.1.46:51113 PCMU/8000 20 ms 160 samples 64000 bits
> 2014-06-05 08:25:18.073562 [DEBUG] switch_core_codec.c:111 sofia/internal/sip:1000 at 10.0.1.46:51113 Original read codec set to PCMU:0
> 2014-06-05 08:25:18.093570 [DEBUG] switch_core_media.c:3617 Set 2833 dtmf send payload to 101
> 2014-06-05 08:25:18.093570 [DEBUG] switch_core_media.c:4841 AUDIO RTP [sofia/internal/sip:1000 at 10.0.1.46:51113] 10.0.1.233 port 32636 -> 10.0.1.46 port 4016 codec: 0 ms: 20
> 2014-06-05 08:25:18.093570 [DEBUG] switch_rtp.c:3349 Starting timer [soft] 160 bytes per 20ms
> 2014-06-05 08:25:18.113587 [INFO] switch_core_media.c:5053 Activating RTCP PORT 4017
> 
> 
> Even stranger, when I call using the same configuration under 1.2.17, the call goes through:
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:5278 Audio Codec Compare [SILK:99:24000:20:0]/[G722:9:8000:20:64000]
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:5278 Audio Codec Compare [SILK:99:24000:20:0]/[PCMU:0:8000:20:64000]
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:5278 Audio Codec Compare [SILK:99:24000:20:0]/[PCMA:8:8000:20:64000]
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:5278 Audio Codec Compare [SILK:99:24000:20:0]/[GSM:3:8000:20:13200]
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:5278 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:5278 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:3186 Set Codec sofia/internal/1000 at 10.0.1.233 PCMU/8000 20 ms 160 samples 64000 bits
> 2014-06-05 08:43:39.400774 [DEBUG] switch_core_codec.c:111 sofia/internal/1000 at 10.0.1.233 Original read codec set to PCMU:0
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:5446 Set 2833 dtmf send/recv payload to 101
> 2014-06-05 08:43:39.400774 [DEBUG] sofia_glue.c:3445 AUDIO RTP [sofia/internal/1000 at 10.0.1.233] 10.0.1.233 port 23172 -> 10.0.1.46 port 4004 codec: 0 ms: 20
> 
> 
> what could possibly be the reason for that? I have little understanding of codecs handling.
> Thanks,
> Shlomi Agiv
> 

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140609/31d8def9/attachment-0001.html 