Hi there!
Trying to turn up a customer and they have these devices. Inbound works fine. Dialing out, extension or a number, I get that error. I have checked and two Codecs match PCMA and PCMU. I also reduced the number of codecs on the device. Google doesnt come up with much except codec issues.
If someone can point me in the right direction, I would be greatly appreciated. I've changed the real IPs and FQDNs.
Thanks leon
Here's a snippet from the log yesterday:
Trying to turn up a customer and they have these devices. Inbound works fine. Dialing out, extension or a number, I get that error. I have checked and two Codecs match PCMA and PCMU. I also reduced the number of codecs on the device. Google doesnt come up with much except codec issues.
If someone can point me in the right direction, I would be greatly appreciated. I've changed the real IPs and FQDNs.
Thanks leon
Here's a snippet from the log yesterday:
2025-06-12 15:39:32.301132 97.83% [DEBUG] sofia.c:10554 verifying acl "providers" for ip/port 1.2.3.4:0.
2025-06-12 15:39:32.301132 97.83% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'internal' for [1010@mypbx.com] from ip 1.2.3.4
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.301132 97.83% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/1001@mypbx.com:5060) State NEW
2025-06-12 15:39:32.301132 97.83% [DEBUG] sofia.c:2419 detaching session 14d067a6-03a2-4569-b805-28c44bbf6350
2025-06-12 15:39:32.421152 97.83% [DEBUG] sofia.c:2532 Re-attaching to session 14d067a6-03a2-4569-b805-28c44bbf6350
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [INFO] sofia.c:10460 sofia/internal/1001@mypbx.com:5060 receiving invite from 1.2.3.4:10624 version: 1.10.12-release git d8481a9 2025-01-13 17:41:43Z 64bit call-id: 1_1147233283@10.1.10.138
2025-06-12 15:39:32.421152 97.83% [DEBUG] sofia.c:10554 verifying acl "providers" for ip/port 1.2.3.4:0.
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] sofia.c:11675 Setting NAT mode based on via received
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] sofia.c:7493 Channel sofia/internal/1001@mypbx.com:5060 entering state [received][100]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] sofia.c:7503 Remote SDP:
14d067a6-03a2-4569-b805-28c44bbf6350 v=0
14d067a6-03a2-4569-b805-28c44bbf6350 o=- 20149 20149 IN IP4 10.1.10.138
14d067a6-03a2-4569-b805-28c44bbf6350 s=SDP data
14d067a6-03a2-4569-b805-28c44bbf6350 c=IN IP4 10.1.10.138
14d067a6-03a2-4569-b805-28c44bbf6350 t=0 0
14d067a6-03a2-4569-b805-28c44bbf6350 m=audio 12702 RTP/AVP 0 18 9 8 101
14d067a6-03a2-4569-b805-28c44bbf6350 c=IN IP4 10.1.10.138
14d067a6-03a2-4569-b805-28c44bbf6350 a=rtpmap:0 PCMU/8000
14d067a6-03a2-4569-b805-28c44bbf6350 a=rtpmap:18 G729/8000
14d067a6-03a2-4569-b805-28c44bbf6350 a=fmtp:18 annexb=no
14d067a6-03a2-4569-b805-28c44bbf6350 a=rtpmap:9 G722/8000
14d067a6-03a2-4569-b805-28c44bbf6350 a=rtpmap:8 PCMA/8000
14d067a6-03a2-4569-b805-28c44bbf6350 a=rtpmap:101 telephone-event/8000
14d067a6-03a2-4569-b805-28c44bbf6350 a=fmtp:101 0-15
14d067a6-03a2-4569-b805-28c44bbf6350 a=rtcp:12703 IN IP4 10.1.10.138
14d067a6-03a2-4569-b805-28c44bbf6350 a=ptime:20
14d067a6-03a2-4569-b805-28c44bbf6350 a=candidate:472638531 1 UDP 2130706431 10.1.10.138 12702 typ host
14d067a6-03a2-4569-b805-28c44bbf6350 a=candidate:472638531 2 UDP 2130706430 10.1.10.138 12703 typ host
14d067a6-03a2-4569-b805-28c44bbf6350 a=ice-ufrag:319697fe0c03d9d95bf2e657
14d067a6-03a2-4569-b805-28c44bbf6350 a=ice-pwd:044b3c5a5efa6bc426fe917d
14d067a6-03a2-4569-b805-28c44bbf6350
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5586 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5586 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5524 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5436 Set telephone-event payload to 101@8000
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:3730 Set Codec sofia/internal/1001@mypbx.com:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_codec.c:132 sofia/internal/1001@mypbx.com:5060 Original read codec set to PCMU:0
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [WARNING] switch_core_media.c:4153 NO candidate ACL defined, Defaulting to wan.auto
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4209 Save audio Candidate cid: 1 proto: UDP type: host addr: 10.1.10.138:12702
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4209 Save audio Candidate cid: 2 proto: UDP type: host addr: 10.1.10.138:12703
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4254 Searching for rtp candidate.
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4254 Searching for rtcp candidate.
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4301 Look for Relay Candidates as last resort
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4254 Searching for rtp candidate.
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4254 Searching for rtcp candidate.
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:4307 sofia/internal/1001@mypbx.com:5060 no suitable candidates found.
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5796 Set telephone-event payload to 101@8000
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_media.c:5854 sofia/internal/1001@mypbx.com:5060 Set 2833 dtmf send payload to 101 recv payload to 101
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [NOTICE] sofia.c:7985 Hangup sofia/internal/1001@mypbx.com:5060 [CS_NEW] [INCOMPATIBLE_DESTINATION]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] sofia.c:1527 Channel is already hungup.
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@mypbx.com:5060) Running State Change CS_HANGUP (Cur 3 Tot 28382)
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/1001@mypbx.com:5060) Callstate Change DOWN -> HANGUP
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1001@mypbx.com:5060) State HANGUP
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] mod_sofia.c:469 Channel sofia/internal/1001@mypbx.com:5060 hanging up, cause: INCOMPATIBLE_DESTINATION
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 488
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:59 sofia/internal/1001@mypbx.com:5060 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1001@mypbx.com:5060) State HANGUP going to sleep
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/1001@mypbx.com:5060) State Change CS_HANGUP -> CS_REPORTING
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1001@mypbx.com:5060) Running State Change CS_REPORTING (Cur 3 Tot 28382)
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1001@mypbx.com:5060) State REPORTING
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:168 sofia/internal/1001@mypbx.com:5060 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1001@mypbx.com:5060) State REPORTING going to sleep
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/1001@mypbx.com:5060) State Change CS_REPORTING -> CS_DESTROY
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_session.c:1748 Session 28382 (sofia/internal/1001@mypbx.com:5060) Locked, Waiting on external entities
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [NOTICE] switch_core_session.c:1766 Session 28382 (sofia/internal/1001@mypbx.com:5060) Ended
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [NOTICE] switch_core_session.c:1770 Close Channel sofia/internal/1001@mypbx.com:5060 [CS_DESTROY]
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/1001@mypbx.com:5060) Running State Change CS_DESTROY (Cur 2 Tot 28382)
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1001@mypbx.com:5060) State DESTROY
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] mod_sofia.c:380 sofia/internal/1001@mypbx.com:5060 SOFIA DESTROY
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:175 sofia/internal/1001@mypbx.com:5060 Standard DESTROY
14d067a6-03a2-4569-b805-28c44bbf6350 2025-06-12 15:39:32.421152 97.83% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1001@mypbx.com:5060) State DESTROY going to sleep