No ring back tone

when I make a call I do not hear any ringing from the phone I’m dialing out on (everything else is fine, rings the other end etc).

It’s anyone’s guess unless you present some logs

Herein are logs of the call that i have tried to make using my mobile number .
Logs Freeswitch

I guess the call is being sent by an Asterisk machine and you can hear the ringing if you call to another extension?

Are you expecting 180 or 183 from upstream provider? Did you look at sngrep?

  1. 2024-06-24 10:49:45.080225 99.93% [NOTICE] switch_channel.c:1142 New Channel sofia/default/0779205097@X.X.X.X [5270ecb2-cfea-4362-9c83-40941bf3ff2e]

  2. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0779205097@X.X.X.X) Running State Change CS_NEW (Cur 1 Tot 28801)

  3. 2024-06-24 10:49:45.080225 99.93% [INFO] sofia.c:10459 sofia/default/0779205097@X.X.X.X receiving invite from X.X.X.X:5060 version: 1.10.11 -release-25-f24064f7c9 64bit call-id: 72c5a1ea-b2bd-486a-af50-59b45743fd9c

  4. 2024-06-24 10:49:45.080225 99.93% [DEBUG] sofia.c:10553 verifying acl “default” for ip/port X.X.X.X:0.

  5. 2024-06-24 10:49:45.080225 99.93% [DEBUG] sofia.c:10582 IP X.X.X.X Approved by acl “default”. Access Granted.

  6. 2024-06-24 10:49:45.080225 99.93% [DEBUG] sofia.c:7493 Channel sofia/default/0779205097@X.X.X.X entering state [received][100]

  7. 2024-06-24 10:49:45.080225 99.93% [DEBUG] sofia.c:7503 Remote SDP:

  8. v=0

  9. o=- 961509953 961509953 IN IP4 X.X.X.X

  10. s=Asterisk

  11. c=IN IP4 X.X.X.X

  12. t=0 0

  13. m=audio 17494 RTP/AVP 8 0 3 111 9 18 101

  14. a=rtpmap:8 PCMA/8000

  15. a=rtpmap:0 PCMU/8000

  16. a=rtpmap:3 GSM/8000

  17. a=rtpmap:111 G726-32/8000

  18. a=rtpmap:9 G722/8000

  19. a=rtpmap:18 G729/8000

  20. a=fmtp:18 annexb=no

  21. a=rtpmap:101 telephone-event/8000

  22. a=fmtp:101 0-16

  23. a=ptime:20

  24. a=maxptime:150

  • 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match

  2. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

  3. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

  4. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

  5. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

  6. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]

  7. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]

  8. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMA:8:8000:20:64000:1]

  9. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMU:0:8000:20:64000:1]

  10. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

  11. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

  12. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]

  13. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]

  14. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000

  15. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:3731 Set Codec sofia/default/0779205097@X.X.X.X PCMA/8000 20 ms 160 samples 64000 bits 1 channels

  16. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_codec.c:111 sofia/default/0779205097@X.X.X.X Original read codec set to PCMA:8

  17. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000

  18. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_media.c:5856 sofia/default/0779205097@X.X.X.X Set 2833 dtmf send payload to 101 recv payload to 101

  19. 2024-06-24 10:49:45.080225 99.93% [DEBUG] sofia.c:7927 (sofia/default/0779205097@X.X.X.X) State Change CS_NEW → CS_INIT

  20. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:600 (sofia/default/0779205097@X.X.X.X) State NEW

  21. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0779205097@X.X.X.X) Running State Change CS_INIT (Cur 1 Tot 28801)

  22. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0779205097@X.X.X.X) State INIT

  23. 2024-06-24 10:49:45.080225 99.93% [DEBUG] mod_sofia.c:97 sofia/default/0779205097@X.X.X.X SOFIA INIT

  24. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/0779205097@X.X.X.X Standard INIT

  25. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/0779205097@X.X.X.X) State Change CS_INIT → CS_ROUTING

  26. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0779205097@X.X.X.X) State INIT going to sleep

  27. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0779205097@X.X.X.X) Running State Change CS_ROUTING (Cur 1 Tot 28801)

  28. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_channel.c:2399 (sofia/default/0779205097@X.X.X.X) Callstate Change DOWN → RINGING

  29. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0779205097@X.X.X.X) State ROUTING

  30. 2024-06-24 10:49:45.080225 99.93% [DEBUG] mod_sofia.c:158 sofia/default/0779205097@X.X.X.X SOFIA ROUTING

  31. 2024-06-24 10:49:45.080225 99.93% [DEBUG] switch_core_state_machine.c:230 sofia/default/0779205097@X.X.X.X Standard ROUTING

  32. 2024-06-24 10:49:45.080225 99.93% [INFO] mod_dialplan_xml.c:639 Processing 0779205097 <0779205097>->802 in context default

  33. 2024-06-24 10:49:45.100240 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN (‘global’,‘opensips’,‘callingcard’,‘calls’,‘InternationalPrefixes’)

  34. 2024-06-24 10:49:45.100240 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons

  35. 2024-06-24 10:49:45.140236 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [Dialplan] Dialed number : 802

  36. 2024-06-24 10:49:45.140236 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = ‘802’ AND status=0 limit 1

  37. 2024-06-24 10:49:45.140236 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [Dialplan] Caller Id name / number : 0779205097 / 0779205097

  38. 2024-06-24 10:49:45.140236 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number =“802” LIMIT 1

  39. 2024-06-24 10:49:45.140236 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [Dialplan] Call direction : inbound

  40. 2024-06-24 10:49:45.140236 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON(“X.X.X.X”) BETWEEN(INET_ATON(SUBSTRING_INDEX(ip, ‘/’, 1)) & 0xffffffff ^((0x1 <<(32 - SUBSTRING_INDEX(ip, ‘/’, -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(ip, ‘/’, 1)) |((0x100000000 >> SUBSTRING_INDEX(ip,‘/’, -1)) -1)) AND “802” LIKE CONCAT(prefix,‘%’) ORDER BY LENGTH(prefix) DESC LIMIT 1

  41. 2024-06-24 10:49:45.140236 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [Accountcode : 678170]

  42. 2024-06-24 10:49:45.140236 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = “678170” AND deleted = 0 limit 1

  43. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num =“802” AND A.accountid = ‘13’ limit 1

  44. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 802

  45. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number =“802” LIMIT 1

  46. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [Dialplan] New Call direction : inbound

  47. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number =“802” AND (accountid = 0 OR status = 1) LIMIT 1

  48. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound

  49. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :182

  50. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT ,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^802.’ OR patterns = ‘^80.’ OR patterns = '^8.’ OR patterns =‘–’) AND accountid = 182 ORDER BY LENGTH(PKGPTR.patterns) DESC

  51. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] =============== Account Information ===================

  52. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] User id : 13

  53. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Account code : 678170

  54. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Balance : 10000

  55. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Type : 0 [0:prepaid,1:postpaid]

  56. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Ratecard id : 4

  57. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] ========================================================

  58. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0

  59. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [GET_RATES] call_direction :inbound

  60. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number =“802” LIMIT 1

  61. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] call_direction:::::: inbound

  62. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] =============== Rates Information ===================

  63. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] ID : 307

  64. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Connectcost : 0.00000

  65. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Includedseconds : 0

  66. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Cost : 0.00000

  67. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] comment : 0793 0

  68. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Country Id : 197

  69. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Accid : 13

  70. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] ================================================================

  71. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 13 !!!

  72. 2024-06-24 10:49:45.160231 99.93% [NOTICE] switch_cpp.cpp:1466 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100

  73. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] Call Max length duration : 100 minutes

  74. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [userinfo] INB_FREE:TRUE

  75. 2024-06-24 10:49:45.160231 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [userinfo] free_inbound:1

  76. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 13 AND status=0 LIMIT 1

  77. 2024-06-24 10:49:45.160231 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = “182” AND deleted = 0 limit 1

  78. 2024-06-24 10:49:45.180261 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [userinfo] Actual CustomerInfo XML:13

  79. 2024-06-24 10:49:45.180261 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [userinfo] Userinfo XML:13

  80. 2024-06-24 10:49:45.180261 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] [userinfo] Actual CustomerInfo XML : 13

  81. 2024-06-24 10:49:45.180261 99.93% [INFO] switch_cpp.cpp:1466 [ASTPP] maxlength::::::::: 100

  82. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_2

  83. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_cpp.cpp:1466 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>

  84. Dialplan: sofia/default/0779205097@X.X.X.X parsing [default->802] continue=false

  85. Dialplan: sofia/default/0779205097@X.X.X.X Regex (PASS) [802] destination_number(802) =~ /802/ break=on-false

  86. Dialplan: sofia/default/0779205097@X.X.X.X Action set(effective_destination_number=802)

  87. Dialplan: sofia/default/0779205097@X.X.X.X Action set(bridge_pre_execute_bleg_app=sched_hangup)

  88. Dialplan: sofia/default/0779205097@X.X.X.X Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)

  89. Dialplan: sofia/default/0779205097@X.X.X.X Action set(callstart=2024-06-24 07:49:45)

  90. Dialplan: sofia/default/0779205097@X.X.X.X Action set(hangup_after_bridge=true)

  91. Dialplan: sofia/default/0779205097@X.X.X.X Action set(continue_on_fail=TRUE)

  92. Dialplan: sofia/default/0779205097@X.X.X.X Action set(account_id=13)

  93. Dialplan: sofia/default/0779205097@X.X.X.X Action set(parent_id=0)

  94. Dialplan: sofia/default/0779205097@X.X.X.X Action set(entity_id=3)

  95. Dialplan: sofia/default/0779205097@X.X.X.X Action set(call_processed=internal)

  96. Dialplan: sofia/default/0779205097@X.X.X.X Action set(call_direction=inbound)

  97. Dialplan: sofia/default/0779205097@X.X.X.X Action set(accountname=GW)

  98. Dialplan: sofia/default/0779205097@X.X.X.X Action export(t38_passthru=true)

  99. Dialplan: sofia/default/0779205097@X.X.X.X Action set(fax_enable_t38=true)

  100. Dialplan: sofia/default/0779205097@X.X.X.X Action set(fax_enable_t38_request=true)

  101. Dialplan: sofia/default/0779205097@X.X.X.X Action set(origination_rates_did=ID:307|CODE:^802.*|DESTINATION:0793 0|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:197|ACCID:182)

  102. Dialplan: sofia/default/0779205097@X.X.X.X Action set(origination_rates=0)

  103. Dialplan: sofia/default/0779205097@X.X.X.X Action set(original_caller_id_name=0779205097)

  104. Dialplan: sofia/default/0779205097@X.X.X.X Action set(original_caller_id_number=0779205097)

  105. Dialplan: sofia/default/0779205097@X.X.X.X Action set(effective_caller_id_name=0779205097)

  106. Dialplan: sofia/default/0779205097@X.X.X.X Action set(effective_caller_id_number=0779205097)

  107. Dialplan: sofia/default/0779205097@X.X.X.X Action set(receiver_accid=182)

  108. Dialplan: sofia/default/0779205097@X.X.X.X Action export(presence_data=x|||MTN(678170)|||^802.* // 0793 0 // 0.00000||||||DID)

  109. Dialplan: sofia/default/0779205097@X.X.X.X Action export(call_type=2)

  110. Dialplan: sofia/default/0779205097@X.X.X.X Action set(calltype=DIRECT-IP)

  111. Dialplan: sofia/default/0779205097@X.X.X.X Action bridge([leg_timeout=60]sofia/${sofia_profile_name}/802@X.X.X.X)

  112. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0779205097@X.X.X.X) State Change CS_ROUTING → CS_EXECUTE

  113. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0779205097@X.X.X.X) State ROUTING going to sleep

  114. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0779205097@X.X.X.X) Running State Change CS_EXECUTE (Cur 1 Tot 28801)

  115. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0779205097@X.X.X.X) State EXECUTE

  116. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_sofia.c:213 sofia/default/0779205097@X.X.X.X SOFIA EXECUTE

  117. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_core_state_machine.c:323 sofia/default/0779205097@X.X.X.X Standard EXECUTE

  118. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(effective_destination_number=802)

  119. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [effective_destination_number]=[802]

  120. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(bridge_pre_execute_bleg_app=sched_hangup)

  121. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [bridge_pre_execute_bleg_app]=[sched_hangup]

  122. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(bridge_pre_execute_bleg_data=+6000 normal_clearing)

  123. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]

  124. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(callstart=2024-06-24 07:49:45)

  125. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [callstart]=[2024-06-24 07:49:45]

  126. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(hangup_after_bridge=true)

  127. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [hangup_after_bridge]=[true]

  128. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(continue_on_fail=TRUE)

  129. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [continue_on_fail]=[TRUE]

  130. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(account_id=13)

  131. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [account_id]=[13]

  132. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(parent_id=0)

  133. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [parent_id]=[0]

  134. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(entity_id=3)

  135. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [entity_id]=[3]

  136. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(call_processed=internal)

  137. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [call_processed]=[internal]

  138. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(call_direction=inbound)

  139. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [call_direction]=[inbound]

  140. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(accountname=GW)

  141. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [accountname]=[GW]

  142. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X export(t38_passthru=true)

  143. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [t38_passthru]=[true]

  144. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(fax_enable_t38=true)

  145. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [fax_enable_t38]=[true]

  146. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(fax_enable_t38_request=true)

  147. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [fax_enable_t38_request]=[true]

  148. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(origination_rates_did=ID:307|CODE:^802.*|DESTINATION:0793 0|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:197|ACCID:182)

  149. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [origination_rates_did]=[ID:307|CODE:^802.*|DESTINATION:0793 0|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:197|ACCID:182]

  150. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(origination_rates=0)

  151. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [origination_rates]=[0]

  152. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(original_caller_id_name=0779205097)

  153. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [original_caller_id_name]=[0779205097]

  154. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(original_caller_id_number=0779205097)

  155. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [original_caller_id_number]=[0779205097]

  156. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(effective_caller_id_name=0779205097)

  157. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [effective_caller_id_name]=[0779205097]

  158. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(effective_caller_id_number=0779205097)

  159. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [effective_caller_id_number]=[0779205097]

  160. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(receiver_accid=182)

  161. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [receiver_accid]=[182]

  162. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X export(presence_data=x|||MTN(678170)|||^802.* // 0793 0 // 0.00000||||||DID)

  163. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||MTN(678170)|||^802.* // 0793 0 // 0.00000||||||DID]

  164. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X export(call_type=2)

  165. 2024-06-24 10:49:45.180261 99.93% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [call_type]=[2]

  166. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X set(calltype=DIRECT-IP)

  167. 2024-06-24 10:49:45.180261 99.93% [DEBUG] mod_dptools.c:1671 SET sofia/default/0779205097@X.X.X.X [calltype]=[DIRECT-IP]

  168. EXECUTE [depth=0] sofia/default/0779205097@X.X.X.X bridge([leg_timeout=60]sofia/default/802@X.X.X.X)

  169. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_channel.c:1288 sofia/default/0779205097@X.X.X.X EXPORTING[export_vars] [t38_passthru]=[true] to event

  170. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_channel.c:1288 sofia/default/0779205097@X.X.X.X EXPORTING[export_vars] [presence_data]=[x|||MTN(678170)|||^802.* // 0793 0 // 0.00000||||||DID] to event

  171. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_channel.c:1288 sofia/default/0779205097@X.X.X.X EXPORTING[export_vars] [call_type]=[2] to event

  172. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_ivr_originate.c:2301 Parsing global variables

  173. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_ivr_originate.c:2863 Parsing session specific variables

  174. 2024-06-24 10:49:45.200185 99.93% [NOTICE] switch_channel.c:1142 New Channel sofia/default/802@X.X.X.X [3becb040-dd64-409a-be72-dce8a58b921d]

  175. 2024-06-24 10:49:45.200185 99.93% [DEBUG] mod_sofia.c:5110 (sofia/default/802@X.X.X.X) State Change CS_NEW → CS_INIT

  176. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_ivr_originate.c:3171 sofia/default/802@X.X.X.X Setting leg timeout to 60

  177. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/802@X.X.X.X) Running State Change CS_INIT (Cur 2 Tot 28802)

  178. 2024-06-24 10:49:45.200185 99.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/802@X.X.X.X) State INIT

  179. 2024-06-24 10:49:45.200185 99.93% [DEBUG] mod_sofia.c:97 sofia/default/802@X.X.X.X SOFIA INIT

  180. 2024-06-24 10:49:45.200185 99.93% [INFO] sofia_glue.c:1659 sofia/default/802@X.X.X.X sending invite call-id: (null)

  181. 2024-06-24 10:49:45.200185 99.93% [DEBUG] sofia_glue.c:1662 sofia/default/802@X.X.X.X sending invite version: 1.10.11 -release-25-f24064f7c9 64bit

  182. Local SDP:

  183. v=0

  184. o=FreeSWITCH 1719183395 1719183396 IN IP4 X.X.X.X

  185. s=FreeSWITCH

  186. c=IN IP4 X.X.X.X

  187. t=0 0

  188. m=audio 31990 RTP/AVP 8 101

  189. a=rtpmap:8 PCMA/8000

  190. a=rtpmap:101 telephone-event/8000

This log does not help. If you have sngrep of the call then please share.

Download pcap of the call and share that pcap.