Hi Mike, Thanks for the reply. yes, the negation codec is done correctly at least from the output messages in the logs. I found the source of the problem, for some i have multiple FS pids, restarting solve my problem., now there is only one. No more drop calls. The "defunct" happen because the g729 license-server wasn't fully started, this is a known issue, but the two main pid's no idea why. Just in case i also set in switch.xml the parameter threaded-system-exec" to "true". Just for info, the output for "ps aux | grep freeswit" when the problem happen: 0 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 11741 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 11755 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 11756 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 11773 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 11803 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 11804 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 11838 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 12027 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 12194 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 12195 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 12232 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 13966 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14562 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14563 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14564 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14586 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14590 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14630 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14652 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14653 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14670 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14801 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 14802 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16401 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16792 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16899 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16900 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16909 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16910 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16930 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 16931 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17092 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17093 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17128 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17301 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17318 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17319 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17320 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17321 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17341 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17342 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17375 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17376 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17386 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17387 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17407 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17461 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 17462 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 18870 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 18879 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 18905 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 18906 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 19084 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 19085 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 19332 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 19333 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 19334 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 19351 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 19352 0.0 0.0 0 0 ? Z< Oct07 0:00 \_ [freeswitch] <defunct> root 24268 0.0 2.2 1259792 739152 ? SN Oct10 0:00 \_ /usr/bin/freeswitch -ncwait -nonat -scripts /opt/commsmundi/scripts/fs root 24855 0.0 4.2 1920068 1397272 ? SN Oct23 0:00 \_ /usr/bin/freeswitch -ncwait -nonat -scripts /opt/commsmundi/scripts/fs On 24/10/2019 20:35, Mike Jerris wrote:
Take a look at log with sip trace enabled but this usually happens when we can’t negotiate codecs.
On Oct 24, 2019, at 10:19 AM, António Silva via FreeSWITCH-users <freeswitch-users@lists.freeswitch.org <mailto:freeswitch-users@lists.freeswitch.org>> wrote:
*From: *António Silva <asilva@wirelessmundi.com <mailto:asilva@wirelessmundi.com>> *Subject: **call drop because of no write codec, incompatible_destination* *Date: *October 24, 2019 at 9:40:00 AM MDT *To: *FreeSWITCH Users Help <freeswitch-users@lists.freeswitch.org <mailto:freeswitch-users@lists.freeswitch.org>>
Hi,
I'm seeing multiple calls drop with incompatible_destination, i try to call again the same destination and it works fine. it gets me crazy...
In logs i see the error, "has no write codec", in the capture i see the negotiation both sides have codecs, they are correctly configure in directory.
what could be causing this? i'm using version 1.8.6
Part of the LOG where the error happens:
f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:37.694352 [ALERT] switch_core_state_machine.c:701 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 session thread sleep state: CS_CONSUME_MEDIA! f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.794408 [ALERT] switch_core_session.c:1130 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [BREAK] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.794408 [ALERT] switch_core_session.c:1130 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [BREAK] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.794408 [ALERT] switch_core_state_machine.c:705 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 session thread wake state: CS_CONSUME_MEDIA! f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.814367 [ALERT] switch_core_media.c:486 Looking for zrtp-hash f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.814367 [ALERT] switch_core_media.c:439 Deciding whether to pass zrtp-hash between legs f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.814367 [ALERT] switch_core_media.c:441 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.834519 [DEBUG] sofia.c:7325 Channel sofia/1-perfil-fija/sip:11@131.179.185.131:5112 entering state [completing][200] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.834519 [DEBUG] sofia.c:7335 Remote SDP: f39e569f-5184-481a-9f67-6185367e2829 v=0 f39e569f-5184-481a-9f67-6185367e2829 o=11 5008 38 IN IP4 192.168.100.11 f39e569f-5184-481a-9f67-6185367e2829 s=Mapping f39e569f-5184-481a-9f67-6185367e2829 c=IN IP4 192.168.100.11 f39e569f-5184-481a-9f67-6185367e2829 t=0 0 f39e569f-5184-481a-9f67-6185367e2829 m=audio 5008 RTP/AVP 0 101 f39e569f-5184-481a-9f67-6185367e2829 a=rtpmap:0 PCMU/8000 f39e569f-5184-481a-9f67-6185367e2829 a=rtpmap:101 telephone-event/8000 f39e569f-5184-481a-9f67-6185367e2829 a=fmtp:101 0-16 f39e569f-5184-481a-9f67-6185367e2829 a=ptime:20 f39e569f-5184-481a-9f67-6185367e2829 f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.834519 [NOTICE] sofia.c:7338 Pre-Answer sofia/1-perfil-fija/sip:11@131.179.185.131:5112! f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.934371 [ALERT] switch_core_session.c:2662 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 Send KeyFrame f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.934371 [DEBUG] switch_core_session.c:2825 EXECUTE [depth=1] sofia/1-perfil-fija/sip:11@131.179.185.131:5112 lua(execute_on_ring_b.lua) f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.934371 [ALERT] switch_core_session.c:2889 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 receive message [APPLICATION_EXEC] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.934371 [ALERT] switch_core_session.c:1047 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [BREAK] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.954358 [ALERT] switch_core_session.c:1047 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [BREAK] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:41.954358 [ALERT] switch_ivr_originate.c:4136 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 receive message [AUDIO_SYNC] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.034370 [DEBUG] switch_cpp.cpp:1187 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 destroy/unlink session from object f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.034370 [ALERT] switch_core_session.c:2905 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 receive message [APPLICATION_EXEC_COMPLETE] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.034370 [DEBUG] switch_channel.c:3537 (sofia/1-perfil-fija/sip:11@131.179.185.131:5112) Callstate Change RINGING -> EARLY f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] sofia.c:7338 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 receive message [PROGRESS_EVENT] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_core_media.c:486 Looking for zrtp-hash f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_core_media.c:439 Deciding whether to pass zrtp-hash between legs f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_core_media.c:441 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_ivr_bridge.c:1650 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 receive message [AUDIO_SYNC] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_core_state_machine.c:701 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 session thread sleep state: CS_CONSUME_MEDIA! f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_ivr_bridge.c:1751 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 receive message [BRIDGE] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_core_session.c:981 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [BREAK] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [DEBUG] switch_ivr_bridge.c:1795 (sofia/1-perfil-fija/sip:11@131.179.185.131:5112) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.094512 [ALERT] switch_core_session.c:1480 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [BREAK] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.114382 [ALERT] switch_core_state_machine.c:705 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 session thread wake state: CS_CONSUME_MEDIA! f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.114382 [DEBUG] switch_core_state_machine.c:584 (sofia/1-perfil-fija/sip:11@131.179.185.131:5112) Running State Change CS_EXCHANGE_MEDIA (Cur 242 Tot 835614) f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.114382 [DEBUG] switch_core_state_machine.c:653 (sofia/1-perfil-fija/sip:11@131.179.185.131:5112) State EXCHANGE_MEDIA f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.114382 [ALERT] switch_core_state_machine.c:653 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 Send KeyFrame f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.114382 [DEBUG] mod_sofia.c:657 SOFIA EXCHANGE_MEDIA f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.134357 [ERR] switch_core_media.c:15507 sofia/1-perfil-fija/sip:11@131.179.185.131:5112 has no write codec. f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.134357 [NOTICE] switch_core_media.c:15508 Hangup sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [CS_EXCHANGE_MEDIA] [INCOMPATIBLE_DESTINATION] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.134357 [ALERT] switch_channel.c:3346 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [KILL] f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.134357 [DEBUG] mod_hash.c:297 Usage for total_account:11@clinicalluch.daratel.com <mailto:11@clinicalluch.daratel.com> is now 0 f39e569f-5184-481a-9f67-6185367e2829 2019-10-21 11:50:42.134357 [ALERT] switch_core_session.c:1480 Send signal sofia/1-perfil-fija/sip:11@131.179.185.131:5112 [BREAK]
-- Saludos / Regards / Cumprimentos António Silva
_________________________________________________________________________
The FreeSWITCH project is sponsored by SignalWire https://signalwire.com Enhance your FreeSWITCH install with disruptive priced SMS and PSTN services. Build your next product on our scalable cloud platform.
Join our online community to chat in real time https://signalwire.community
Professional FreeSWITCH Services sales@freeswitch.com <mailto:sales@freeswitch.com> https://freeswitch.com
Official FreeSWITCH Sites https://freeswitch.com/oss https://freeswitch.org/confluence https://cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users https://freeswitch.com
-- Saludos / Regards / Cumprimentos António Silva