FreeSWITCH Pastebin

FOIP DID email not receive fax issue

From Prashant Kumar, 1 Year ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/7d9cf8c3
Embed
Download Paste or View Raw
  1. Type /help <enter> to see a list of commands
  2.  
  3.  
  4.  
  5. +OK log level  [7]
  6. freeswitch@betaAlpesh>
  7. freeswitch@betaAlpesh>
  8. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
  9. 2023-10-03 07:22:14.195907 84.63% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1007799@65.109.231.66:5079 [1e112296-32db-4010-bd14-d8031933b6ed]
  10. 2023-10-03 07:22:14.195907 84.63% [DEBUG] mod_sofia.c:5117 (sofia/default/1007799@65.109.231.66:5079) State Change CS_NEW -> CS_INIT
  11. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66:5079) Running State Change CS_INIT (Cur 1 Tot 3)
  12. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1007799@65.109.231.66:5079) State INIT
  13. 2023-10-03 07:22:14.195907 84.63% [DEBUG] mod_sofia.c:97 sofia/default/1007799@65.109.231.66:5079 SOFIA INIT
  14. 2023-10-03 07:22:14.195907 84.63% [INFO] sofia_glue.c:1654 sofia/default/1007799@65.109.231.66:5079 sending invite call-id: (null)
  15. 2023-10-03 07:22:14.195907 84.63% [DEBUG] sofia_glue.c:1657 sofia/default/1007799@65.109.231.66:5079 sending invite version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit
  16. Local SDP:
  17. v=0
  18. o=FreeSWITCH 1696295272 1696295273 IN IP4 65.109.231.66
  19. s=FreeSWITCH
  20. c=IN IP4 65.109.231.66
  21. t=0 0
  22. m=audio 22462 RTP/AVP 8 101
  23. a=rtpmap:8 PCMA/8000
  24. a=rtpmap:101 telephone-event/8000
  25. a=fmtp:101 0-15
  26. a=ptime:20
  27. a=sendrecv
  28.  
  29. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:40 sofia/default/1007799@65.109.231.66:5079 Standard INIT
  30. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1007799@65.109.231.66:5079) State Change CS_INIT -> CS_ROUTING
  31. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1007799@65.109.231.66:5079) State INIT going to sleep
  32. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66:5079) Running State Change CS_ROUTING (Cur 1 Tot 3)
  33. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1007799@65.109.231.66:5079) State ROUTING
  34. 2023-10-03 07:22:14.195907 84.63% [DEBUG] mod_sofia.c:158 sofia/default/1007799@65.109.231.66:5079 SOFIA ROUTING
  35. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1007799@65.109.231.66:5079) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  36. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1007799@65.109.231.66:5079) State ROUTING going to sleep
  37. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66:5079) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 3)
  38. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1007799@65.109.231.66:5079) State CONSUME_MEDIA
  39. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1007799@65.109.231.66:5079) State CONSUME_MEDIA going to sleep
  40. 2023-10-03 07:22:14.195907 84.63% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66:5079 entering state [calling][0]
  41. 2023-10-03 07:22:14.195907 84.63% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1007799@65.109.231.66 [e5f7db7d-67d1-49fb-b0dc-0d1369657c74]
  42. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66) Running State Change CS_NEW (Cur 2 Tot 4)
  43. 2023-10-03 07:22:14.195907 84.63% [INFO] sofia.c:10453 sofia/default/1007799@65.109.231.66 receiving invite from 65.109.231.66:5079 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: 6a9c32a4-dc60-123c-15af-960002079de3
  44. 2023-10-03 07:22:14.195907 84.63% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 65.109.231.66:0.
  45. 2023-10-03 07:22:14.195907 84.63% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66 entering state [received][100]
  46. 2023-10-03 07:22:14.195907 84.63% [DEBUG] sofia.c:7497 Remote SDP:
  47. v=0
  48. o=FreeSWITCH 1696295272 1696295273 IN IP4 65.109.231.66
  49. s=FreeSWITCH
  50. c=IN IP4 65.109.231.66
  51. t=0 0
  52. m=audio 22462 RTP/AVP 8 101
  53. a=rtpmap:8 PCMA/8000
  54. a=rtpmap:101 telephone-event/8000
  55. a=fmtp:101 0-15
  56. a=ptime:20
  57.  
  58. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  59. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  60. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  61. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  62. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/1007799@65.109.231.66 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  63. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_codec.c:111 sofia/default/1007799@65.109.231.66 Original read codec set to PCMA:8
  64. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  65. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_media.c:5850 sofia/default/1007799@65.109.231.66 Set 2833 dtmf send payload to 101 recv payload to 101
  66. 2023-10-03 07:22:14.195907 84.63% [DEBUG] sofia.c:7921 (sofia/default/1007799@65.109.231.66) State Change CS_NEW -> CS_INIT
  67. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1007799@65.109.231.66) State NEW
  68. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66) Running State Change CS_INIT (Cur 2 Tot 4)
  69. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1007799@65.109.231.66) State INIT
  70. 2023-10-03 07:22:14.195907 84.63% [DEBUG] mod_sofia.c:97 sofia/default/1007799@65.109.231.66 SOFIA INIT
  71. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:40 sofia/default/1007799@65.109.231.66 Standard INIT
  72. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1007799@65.109.231.66) State Change CS_INIT -> CS_ROUTING
  73. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1007799@65.109.231.66) State INIT going to sleep
  74. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66) Running State Change CS_ROUTING (Cur 2 Tot 4)
  75. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_channel.c:2380 (sofia/default/1007799@65.109.231.66) Callstate Change DOWN -> RINGING
  76. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1007799@65.109.231.66) State ROUTING
  77. 2023-10-03 07:22:14.195907 84.63% [DEBUG] mod_sofia.c:158 sofia/default/1007799@65.109.231.66 SOFIA ROUTING
  78. 2023-10-03 07:22:14.195907 84.63% [DEBUG] switch_core_state_machine.c:230 sofia/default/1007799@65.109.231.66 Standard ROUTING
  79. 2023-10-03 07:22:14.195907 84.63% [INFO] mod_dialplan_xml.c:639 Processing 1007799 <1007799>->1007799 in context default
  80. 2023-10-03 07:22:14.215896 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
  81. 2023-10-03 07:22:14.215896 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  82. 2023-10-03 07:22:14.215896 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  83. 2023-10-03 07:22:14.215896 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  84. 2023-10-03 07:22:14.215896 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1007799
  85. 2023-10-03 07:22:14.215896 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
  86. 2023-10-03 07:22:14.215896 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERmod_sofia
  87. 2023-10-03 07:22:14.215896 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  88. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '1007799' limit 1
  89. 2023-10-03 07:22:14.235922 84.63% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
  90. 2023-10-03 07:22:14.235922 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9972249370 / 9972249370
  91. 2023-10-03 07:22:14.235922 84.63% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Call Type FAX
  92. 2023-10-03 07:22:14.235922 84.63% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Account Code :9972249370
  93. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  94. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
  95. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
  96. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [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, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1007799" LIMIT 1
  97. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  98. 2023-10-03 07:22:14.235922 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
  99. 2023-10-03 07:22:14.235922 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9972249370]
  100. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "9972249370" OR id = "9972249370") AND deleted = 0 limit 1
  101. 2023-10-03 07:22:14.235922 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] account call balance :SELECT * FROM accounts WHERE id =5
  102. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [update_first_used_account] Query :update accounts SET first_used = '2023-10-03 07:22:14' where id = '5'
  103. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
  104. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]1
  105. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Call Direction : inbound
  106. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID ACCOUNT ID : 5
  107. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [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 P.is_terminated = 0 AND (patterns = '^1007799.*' OR patterns = '^100779.*' OR patterns = '^10077.*' OR patterns = '^1007.*' OR patterns = '^100.*' OR patterns = '^10.*' OR patterns = '^1.*' OR patterns ='--') AND accountid = 5 ORDER BY LENGTH(PKGPTR.patterns) DESC
  108. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [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 ="1007799" AND A.accountid = '5' limit 1
  109. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 1007799
  110. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  111. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
  112. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
  113. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [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, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1007799" LIMIT 1
  114. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  115. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
  116. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '1007799' OR number = '100779' OR number = '10077' OR number = '1007' OR number = '100' OR number = '10' OR number = '1' OR number ='--') OR (number = '1007799*' OR number = '100779*' OR number = '10077*' OR number = '1007*' OR number = '100*' OR number = '10*' OR number = '1*' OR number ='--') OR (number = '9972249370' OR number = '997224937' OR number = '99722493' OR number = '9972249' OR number = '997224' OR number = '99722' OR number = '9972' OR number = '997' OR number = '99' OR number = '9' OR number ='--') OR (number = '9972249370*' OR number = '997224937*' OR number = '99722493*' OR number = '9972249*' OR number = '997224*' OR number = '99722*' OR number = '9972*' OR number = '997*' OR number = '99*' OR number = '9*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  117. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  118. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '1007799' OR routing_prefix = '100779' OR routing_prefix = '10077' OR routing_prefix = '1007' OR routing_prefix = '100' OR routing_prefix = '10' OR routing_prefix = '1' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  119. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  120. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 5
  121. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9972249370
  122. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] account call balance :SELECT * FROM accounts WHERE id =5
  123. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 499998
  124. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  125. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
  126. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  127. 2023-10-03 07:22:14.255923 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  128. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  129. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::2
  130. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
  131. 2023-10-03 07:22:14.255923 84.63% [DEBUG] switch_cpp.cpp:1465 [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, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1007799" LIMIT 1
  132. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  133. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
  134. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  135. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 2
  136. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  137. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  138. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.95000
  139. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 1007799
  140. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 88
  141. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 5
  142. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  143. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] account call balance :SELECT * FROM accounts WHERE id =5
  144. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your499998 balance Accountid 5 !!!
  145. 2023-10-03 07:22:14.275899 84.63% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 1000 mins!
  146. 2023-10-03 07:22:14.275899 84.63% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 1000 mins!
  147. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Daily call credit limit : 1000
  148. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 1000 minutes
  149. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
  150. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
  151. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 5 AND status=0 LIMIT 1
  152. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (id = "5" OR number = "5") AND deleted = 0 limit 1
  153. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] account call balance :SELECT * FROM accounts WHERE id =5
  154. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:5
  155. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:5
  156. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 5
  157. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 1000
  158. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_sip_device_info_for_did_pstn_call] Query :SELECT username FROM sip_devices WHERE username = 'mod_sofia' and status = 0 limit 1
  159. 2023-10-03 07:22:14.275899 84.63% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Outbound Dialplan
  160. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  161. 2023-10-03 07:22:14.275899 84.63% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  162. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [XML] Custom Function Name :: custom_inbound_6
  163. 2023-10-03 07:22:14.275899 84.63% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Inbound Dialplan
  164. 2023-10-03 07:22:14.275899 84.63% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  165. <document type="freeswitch/xml">
  166. <section name="dialplan" description="ASTPP Dialplan">
  167. <context name="default">
  168. <extension name="1007799">
  169. <condition field="destination_number" expression="1007799">
  170. <action application="set" data="effective_destination_number=1007799"/>
  171. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  172. <action application="set" data="bridge_pre_execute_bleg_data=+60000 normal_clearing"/>
  173. <action application="set" data="notify_flag=0"/>
  174. <action application="set" data="notify_credit_limit=5.00000"/>
  175. <action application="set" data="type=0"/>
  176. <action application="set" data="callstart=2023-10-03 07:22:14"/>
  177. <action application="set" data="hangup_after_bridge=true"/>
  178. <action application="set" data="continue_on_fail=TRUE"/>
  179. <action application="set" data="account_id=5"/>
  180. <action application="set" data="parent_id=0"/>
  181. <action application="set" data="entity_id=0"/>
  182. <action application="set" data="call_processed=internal"/>
  183. <action application="set" data="call_direction=inbound"/>
  184. <action application="set" data="accountname=default"/>
  185. <action application="set" data="filename1=astpp.pbx.lua"/>
  186. <action application="export" data="t38_passthru=true"/>
  187. <action application="playback" data="silence_stream://2000"/>
  188. <action application="set" data="fax_enable_t38_request=true"/>
  189. <action application="set" data="sip_require_timer=false"/>
  190. <action application="set" data="call_request=3"/>
  191. <action application="set" data="module_name=apfax"/>
  192. <action application="set" data="fax_use_ecm=false"/>
  193. <action application="set" data="fax_v17_disabled=false"/>
  194. <action application="set" data="fax_disable_v17=true"/>
  195. <action application="set" data="fax_enable_t38=true"/>
  196. <action application="set" data="session_in_hangup_hook=true"/>
  197. <action application="set" data="origination_rates_did=ID:2|CODE:^1007799.*|DESTINATION:1007799|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.95000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:88|ACCID:5"/>
  198. <action application="set" data="origination_rates=0"/>
  199. <action application="set" data="original_caller_id_name=9972249370"/>
  200. <action application="set" data="original_caller_id_number=9972249370"/>
  201. <action application="set" data="effective_caller_id_name=9972249370"/>
  202. <action application="set" data="effective_caller_id_number=9972249370"/>
  203. <action application="set" data="receiver_accid=5"/>
  204. <action application="export" data="domain_name="/>
  205. <action application="export" data="sip_to_host="/>
  206. <action application="export" data="presence_data=x|||PK Admin Customer(9972249370)|||^1007799.* // 1007799 // 0.95000|||||||||DID|||65.109.231.66"/>
  207. <action application="export" data="call_type=6"/>
  208. <action application="set" data="calltype=FAX"/>
  209. <action application="answer"/>
  210. <action application="set" data="callfax=true"/>
  211. <action application="playback" data="silence_stream://2000"/>
  212. <action application="set" data="fax_enable_t38_request=true"/>
  213. <action application="set" data="sip_require_timer=false"/>
  214. <action application="set" data="call_request=6"/>
  215. <action application="set" data="module_name=apfax"/>
  216. <action application="set" data="fax_transfer_rate=9600"/>
  217. <action application="set" data="fax_use_ecm=false"/>
  218. <action application="set" data="fax_v17_disabled=false"/>
  219. <action application="set" data="fax_disable_v17=true"/>
  220. <action application="set" data="fax_enable_t38=true"/>
  221. <action application="set" data="api_hangup_hook=system /var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif 9972249370 PK+Admin+Customer_QA+Beta prashant443322123@gmail.com /var/www/html/astpp/attachments/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.pdf prashant443322123@gmail.com 1007799 9972249370"/>
  222. <action application="rxfax" data="/var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif"/>
  223. <action application="hangup"/>
  224. </condition>
  225. </extension>
  226. </context>
  227. </section>
  228. </document>
  229. Dialplan: sofia/default/1007799@65.109.231.66 parsing [default->1007799] continue=false
  230. Dialplan: sofia/default/1007799@65.109.231.66 Regex (PASS) [1007799] destination_number(1007799) =~ /1007799/ break=on-false
  231. Dialplan: sofia/default/1007799@65.109.231.66 Action set(effective_destination_number=1007799)
  232. Dialplan: sofia/default/1007799@65.109.231.66 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  233. Dialplan: sofia/default/1007799@65.109.231.66 Action set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  234. Dialplan: sofia/default/1007799@65.109.231.66 Action set(notify_flag=0)
  235. Dialplan: sofia/default/1007799@65.109.231.66 Action set(notify_credit_limit=5.00000)
  236. Dialplan: sofia/default/1007799@65.109.231.66 Action set(type=0)
  237. Dialplan: sofia/default/1007799@65.109.231.66 Action set(callstart=2023-10-03 07:22:14)
  238. Dialplan: sofia/default/1007799@65.109.231.66 Action set(hangup_after_bridge=true)
  239. Dialplan: sofia/default/1007799@65.109.231.66 Action set(continue_on_fail=TRUE)
  240. Dialplan: sofia/default/1007799@65.109.231.66 Action set(account_id=5)
  241. Dialplan: sofia/default/1007799@65.109.231.66 Action set(parent_id=0)
  242. Dialplan: sofia/default/1007799@65.109.231.66 Action set(entity_id=0)
  243. Dialplan: sofia/default/1007799@65.109.231.66 Action set(call_processed=internal)
  244. Dialplan: sofia/default/1007799@65.109.231.66 Action set(call_direction=inbound)
  245. Dialplan: sofia/default/1007799@65.109.231.66 Action set(accountname=default)
  246. Dialplan: sofia/default/1007799@65.109.231.66 Action set(filename1=astpp.pbx.lua)
  247. Dialplan: sofia/default/1007799@65.109.231.66 Action export(t38_passthru=true)
  248. Dialplan: sofia/default/1007799@65.109.231.66 Action playback(silence_stream://2000)
  249. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_enable_t38_request=true)
  250. Dialplan: sofia/default/1007799@65.109.231.66 Action set(sip_require_timer=false)
  251. Dialplan: sofia/default/1007799@65.109.231.66 Action set(call_request=3)
  252. Dialplan: sofia/default/1007799@65.109.231.66 Action set(module_name=apfax)
  253. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_use_ecm=false)
  254. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_v17_disabled=false)
  255. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_disable_v17=true)
  256. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_enable_t38=true)
  257. Dialplan: sofia/default/1007799@65.109.231.66 Action set(session_in_hangup_hook=true)
  258. Dialplan: sofia/default/1007799@65.109.231.66 Action set(origination_rates_did=ID:2|CODE:^1007799.*|DESTINATION:1007799|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.95000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:88|ACCID:5)
  259. Dialplan: sofia/default/1007799@65.109.231.66 Action set(origination_rates=0)
  260. Dialplan: sofia/default/1007799@65.109.231.66 Action set(original_caller_id_name=9972249370)
  261. Dialplan: sofia/default/1007799@65.109.231.66 Action set(original_caller_id_number=9972249370)
  262. Dialplan: sofia/default/1007799@65.109.231.66 Action set(effective_caller_id_name=9972249370)
  263. Dialplan: sofia/default/1007799@65.109.231.66 Action set(effective_caller_id_number=9972249370)
  264. Dialplan: sofia/default/1007799@65.109.231.66 Action set(receiver_accid=5)
  265. Dialplan: sofia/default/1007799@65.109.231.66 Action export(domain_name=)
  266. Dialplan: sofia/default/1007799@65.109.231.66 Action export(sip_to_host=)
  267. Dialplan: sofia/default/1007799@65.109.231.66 Action export(presence_data=x|||PK Admin Customer(9972249370)|||^1007799.* // 1007799 // 0.95000|||||||||DID|||65.109.231.66)
  268. Dialplan: sofia/default/1007799@65.109.231.66 Action export(call_type=6)
  269. Dialplan: sofia/default/1007799@65.109.231.66 Action set(calltype=FAX)
  270. Dialplan: sofia/default/1007799@65.109.231.66 Action answer()
  271. Dialplan: sofia/default/1007799@65.109.231.66 Action set(callfax=true)
  272. Dialplan: sofia/default/1007799@65.109.231.66 Action playback(silence_stream://2000)
  273. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_enable_t38_request=true)
  274. Dialplan: sofia/default/1007799@65.109.231.66 Action set(sip_require_timer=false)
  275. Dialplan: sofia/default/1007799@65.109.231.66 Action set(call_request=6)
  276. Dialplan: sofia/default/1007799@65.109.231.66 Action set(module_name=apfax)
  277. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_transfer_rate=9600)
  278. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_use_ecm=false)
  279. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_v17_disabled=false)
  280. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_disable_v17=true)
  281. Dialplan: sofia/default/1007799@65.109.231.66 Action set(fax_enable_t38=true)
  282. Dialplan: sofia/default/1007799@65.109.231.66 Action set(api_hangup_hook=system /var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif 9972249370 PK+Admin+Customer_QA+Beta prashant443322123@gmail.com /var/www/html/astpp/attachments/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.pdf prashant443322123@gmail.com 1007799 9972249370)
  283. Dialplan: sofia/default/1007799@65.109.231.66 Action rxfax(/var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif)
  284. Dialplan: sofia/default/1007799@65.109.231.66 Action hangup()
  285. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_state_machine.c:281 (sofia/default/1007799@65.109.231.66) State Change CS_ROUTING -> CS_EXECUTE
  286. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1007799@65.109.231.66) State ROUTING going to sleep
  287. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66) Running State Change CS_EXECUTE (Cur 2 Tot 4)
  288. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1007799@65.109.231.66) State EXECUTE
  289. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_sofia.c:213 sofia/default/1007799@65.109.231.66 SOFIA EXECUTE
  290. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_state_machine.c:323 sofia/default/1007799@65.109.231.66 Standard EXECUTE
  291. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(effective_destination_number=1007799)
  292. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [effective_destination_number]=[1007799]
  293. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(bridge_pre_execute_bleg_app=sched_hangup)
  294. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [bridge_pre_execute_bleg_app]=[sched_hangup]
  295. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  296. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [bridge_pre_execute_bleg_data]=[+60000 normal_clearing]
  297. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(notify_flag=0)
  298. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [notify_flag]=[0]
  299. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(notify_credit_limit=5.00000)
  300. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [notify_credit_limit]=[5.00000]
  301. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(type=0)
  302. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [type]=[0]
  303. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(callstart=2023-10-03 07:22:14)
  304. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [callstart]=[2023-10-03 07:22:14]
  305. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(hangup_after_bridge=true)
  306. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [hangup_after_bridge]=[true]
  307. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(continue_on_fail=TRUE)
  308. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [continue_on_fail]=[TRUE]
  309. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(account_id=5)
  310. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [account_id]=[5]
  311. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(parent_id=0)
  312. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [parent_id]=[0]
  313. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(entity_id=0)
  314. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [entity_id]=[0]
  315. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(call_processed=internal)
  316. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [call_processed]=[internal]
  317. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(call_direction=inbound)
  318. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [call_direction]=[inbound]
  319. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(accountname=default)
  320. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [accountname]=[default]
  321. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(filename1=astpp.pbx.lua)
  322. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [filename1]=[astpp.pbx.lua]
  323. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 export(t38_passthru=true)
  324. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [t38_passthru]=[true]
  325. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_session.c:2785 Application playback Requires media! pre_answering channel sofia/default/1007799@65.109.231.66
  326. 2023-10-03 07:22:14.295905 84.63% [INFO] switch_core_session.c:2787 Sending early media
  327. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/1007799@65.109.231.66] 65.109.231.66 port 19558 -> 65.109.231.66 port 22462 codec: 8 ms: 20
  328. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
  329. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8963 sofia/default/1007799@65.109.231.66 Set 2833 dtmf send payload to 101
  330. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8970 sofia/default/1007799@65.109.231.66 Set 2833 dtmf receive payload to 101
  331. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8993 sofia/default/1007799@65.109.231.66 Set rtp dtmf delay to 40
  332. 2023-10-03 07:22:14.295905 84.63% [DEBUG] mod_sofia.c:2609 Ring SDP:
  333. v=0
  334. o=FreeSWITCH 1696298176 1696298177 IN IP4 65.109.231.66
  335. s=FreeSWITCH
  336. c=IN IP4 65.109.231.66
  337. t=0 0
  338. m=audio 19558 RTP/AVP 8 101
  339. a=rtpmap:8 PCMA/8000
  340. a=rtpmap:101 telephone-event/8000
  341. a=fmtp:101 0-15
  342. a=ptime:20
  343. a=sendrecv
  344.  
  345. 2023-10-03 07:22:14.295905 84.63% [NOTICE] mod_sofia.c:2612 Pre-Answer sofia/default/1007799@65.109.231.66!
  346. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_channel.c:3566 (sofia/default/1007799@65.109.231.66) Callstate Change RINGING -> EARLY
  347. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 playback(silence_stream://2000)
  348. 2023-10-03 07:22:14.295905 84.63% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66 entering state [early][183]
  349. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_ivr_play_say.c:1566 Codec Activated L16@8000hz 1 channels 20ms
  350. 2023-10-03 07:22:14.295905 84.63% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66:5079 entering state [proceeding][183]
  351. 2023-10-03 07:22:14.295905 84.63% [DEBUG] sofia.c:7497 Remote SDP:
  352. v=0
  353. o=FreeSWITCH 1696298176 1696298177 IN IP4 65.109.231.66
  354. s=FreeSWITCH
  355. c=IN IP4 65.109.231.66
  356. t=0 0
  357. m=audio 19558 RTP/AVP 8 101
  358. a=rtpmap:8 PCMA/8000
  359. a=rtpmap:101 telephone-event/8000
  360. a=fmtp:101 0-15
  361. a=ptime:20
  362.  
  363. 2023-10-03 07:22:14.295905 84.63% [NOTICE] sofia.c:7500 Pre-Answer sofia/default/1007799@65.109.231.66:5079!
  364. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_channel.c:3566 (sofia/default/1007799@65.109.231.66:5079) Callstate Change DOWN -> EARLY
  365. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  366. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  367. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  368. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/1007799@65.109.231.66:5079 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  369. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_codec.c:111 sofia/default/1007799@65.109.231.66:5079 Original read codec set to PCMA:8
  370. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  371. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:5850 sofia/default/1007799@65.109.231.66:5079 Set 2833 dtmf send payload to 101 recv payload to 101
  372. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/1007799@65.109.231.66:5079] 65.109.231.66 port 22462 -> 65.109.231.66 port 19558 codec: 8 ms: 20
  373. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
  374. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8963 sofia/default/1007799@65.109.231.66:5079 Set 2833 dtmf send payload to 101
  375. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8970 sofia/default/1007799@65.109.231.66:5079 Set 2833 dtmf receive payload to 101
  376. 2023-10-03 07:22:14.295905 84.63% [DEBUG] switch_core_media.c:8993 sofia/default/1007799@65.109.231.66:5079 Set rtp dtmf delay to 40
  377. 2023-10-03 07:22:16.295924 82.57% [DEBUG] switch_ivr_play_say.c:2015 done playing file silence_stream://2000
  378. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_enable_t38_request=true)
  379. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_enable_t38_request]=[true]
  380. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(sip_require_timer=false)
  381. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [sip_require_timer]=[false]
  382. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(call_request=3)
  383. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [call_request]=[3]
  384. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(module_name=apfax)
  385. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [module_name]=[apfax]
  386. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_use_ecm=false)
  387. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_use_ecm]=[false]
  388. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_v17_disabled=false)
  389. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_v17_disabled]=[false]
  390. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_disable_v17=true)
  391. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_disable_v17]=[true]
  392. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_enable_t38=true)
  393. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_enable_t38]=[true]
  394. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(session_in_hangup_hook=true)
  395. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [session_in_hangup_hook]=[true]
  396. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(origination_rates_did=ID:2|CODE:^1007799.*|DESTINATION:1007799|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.95000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:88|ACCID:5)
  397. 2023-10-03 07:22:16.295924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [origination_rates_did]=[ID:2|CODE:^1007799.*|DESTINATION:1007799|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:6|COST:0.95000|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:88|ACCID:5]
  398. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(origination_rates=0)
  399. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [origination_rates]=[0]
  400. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(original_caller_id_name=9972249370)
  401. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [original_caller_id_name]=[9972249370]
  402. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(original_caller_id_number=9972249370)
  403. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [original_caller_id_number]=[9972249370]
  404. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(effective_caller_id_name=9972249370)
  405. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [effective_caller_id_name]=[9972249370]
  406. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(effective_caller_id_number=9972249370)
  407. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [effective_caller_id_number]=[9972249370]
  408. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(receiver_accid=5)
  409. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [receiver_accid]=[5]
  410. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 export(domain_name=)
  411. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [domain_name]=[UNDEF]
  412. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 export(sip_to_host=)
  413. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_to_host]=[UNDEF]
  414. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 export(presence_data=x|||PK Admin Customer(9972249370)|||^1007799.* // 1007799 // 0.95000|||||||||DID|||65.109.231.66)
  415. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||PK Admin Customer(9972249370)|||^1007799.* // 1007799 // 0.95000|||||||||DID|||65.109.231.66]
  416. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 export(call_type=6)
  417. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_type]=[6]
  418. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(calltype=FAX)
  419. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [calltype]=[FAX]
  420. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 answer()
  421. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_core_media.c:8633 Audio params are unchanged for sofia/default/1007799@65.109.231.66.
  422. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/1007799@65.109.231.66:
  423. v=0
  424. o=FreeSWITCH 1696298176 1696298178 IN IP4 65.109.231.66
  425. s=FreeSWITCH
  426. c=IN IP4 65.109.231.66
  427. t=0 0
  428. m=audio 19558 RTP/AVP 8 101
  429. a=rtpmap:8 PCMA/8000
  430. a=rtpmap:101 telephone-event/8000
  431. a=fmtp:101 0-15
  432. a=ptime:20
  433. a=sendrecv
  434.  
  435. 2023-10-03 07:22:16.315924 82.57% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66 entering state [completed][200]
  436. 2023-10-03 07:22:16.315924 82.57% [NOTICE] mod_dptools.c:1408 Channel [sofia/default/1007799@65.109.231.66] has been answered
  437. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_channel.c:3893 (sofia/default/1007799@65.109.231.66) Callstate Change EARLY -> ACTIVE
  438. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(callfax=true)
  439. 2023-10-03 07:22:16.315924 82.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [callfax]=[true]
  440. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 playback(silence_stream://2000)
  441. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_ivr_play_say.c:1566 Codec Activated L16@8000hz 1 channels 20ms
  442. 2023-10-03 07:22:16.315924 82.57% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66:5079 entering state [completing][200]
  443. 2023-10-03 07:22:16.315924 82.57% [DEBUG] sofia.c:7494 Duplicate SDP
  444. v=0
  445. o=FreeSWITCH 1696298176 1696298177 IN IP4 65.109.231.66
  446. s=FreeSWITCH
  447. c=IN IP4 65.109.231.66
  448. t=0 0
  449. m=audio 19558 RTP/AVP 8 101
  450. a=rtpmap:8 PCMA/8000
  451. a=rtpmap:101 telephone-event/8000
  452. a=fmtp:101 0-15
  453. a=ptime:20
  454.  
  455. 2023-10-03 07:22:16.315924 82.57% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66:5079 entering state [ready][200]
  456. 2023-10-03 07:22:16.315924 82.57% [NOTICE] sofia.c:8616 Channel [sofia/default/1007799@65.109.231.66:5079] has been answered
  457. 2023-10-03 07:22:16.315924 82.57% [DEBUG] switch_channel.c:3893 (sofia/default/1007799@65.109.231.66:5079) Callstate Change EARLY -> ACTIVE
  458. 2023-10-03 07:22:16.315924 82.57% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66 entering state [ready][200]
  459. 2023-10-03 07:22:16.335891 82.57% [DEBUG] switch_ivr_originate.c:3902 Originate Resulted in Success: [sofia/default/1007799@65.109.231.66:5079] Peer UUID: 1e112296-32db-4010-bd14-d8031933b6ed
  460. 2023-10-03 07:22:16.335891 82.57% [INFO] switch_channel.c:3270 sofia/default/1007799@65.109.231.66:5079 Flipping CID from "" <1007799> to "Outbound Call" <1007799>
  461. 2023-10-03 07:22:16.335891 82.57% [DEBUG] mod_commands.c:5163 (sofia/default/1007799@65.109.231.66:5079) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
  462. 2023-10-03 07:22:16.335891 82.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66:5079) Running State Change CS_EXECUTE (Cur 2 Tot 4)
  463. 2023-10-03 07:22:16.335891 82.57% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1007799@65.109.231.66:5079) State EXECUTE
  464. 2023-10-03 07:22:16.335891 82.57% [DEBUG] mod_sofia.c:213 sofia/default/1007799@65.109.231.66:5079 SOFIA EXECUTE
  465. 2023-10-03 07:22:16.335891 82.57% [DEBUG] switch_core_state_machine.c:323 sofia/default/1007799@65.109.231.66:5079 Standard EXECUTE
  466. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66:5079 txfax(/opt/ASTPP/web_interface/astpp/FAX/send_fax_files/20231003125111.tiff)
  467. 2023-10-03 07:22:16.335891 82.57% [DEBUG] mod_spandsp_fax.c:1607 Raw read codec activation Success L16 20000
  468. 2023-10-03 07:22:16.335891 82.57% [DEBUG] switch_core_codec.c:223 sofia/default/1007799@65.109.231.66:5079 Push codec L16:100
  469. 2023-10-03 07:22:16.335891 82.57% [DEBUG] mod_spandsp_fax.c:1625 Raw write codec activation Success L16
  470. 2023-10-03 07:22:16.335891 82.57% [DEBUG] switch_rtp.c:7511 Correct audio ip/port confirmed.
  471. 2023-10-03 07:22:16.636002 82.23% [DEBUG] switch_rtp.c:7511 Correct audio ip/port confirmed.
  472. 2023-10-03 07:22:18.295929 82.37% [DEBUG] switch_ivr_play_say.c:2015 done playing file silence_stream://2000
  473. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_enable_t38_request=true)
  474. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_enable_t38_request]=[true]
  475. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(sip_require_timer=false)
  476. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [sip_require_timer]=[false]
  477. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(call_request=6)
  478. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [call_request]=[6]
  479. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(module_name=apfax)
  480. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [module_name]=[apfax]
  481. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_transfer_rate=9600)
  482. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_transfer_rate]=[9600]
  483. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_use_ecm=false)
  484. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_use_ecm]=[false]
  485. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_v17_disabled=false)
  486. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_v17_disabled]=[false]
  487. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_disable_v17=true)
  488. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_disable_v17]=[true]
  489. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(fax_enable_t38=true)
  490. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [fax_enable_t38]=[true]
  491. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 set(api_hangup_hook=system /var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif 9972249370 PK+Admin+Customer_QA+Beta prashant443322123@gmail.com /var/www/html/astpp/attachments/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.pdf prashant443322123@gmail.com 1007799 9972249370)
  492. 2023-10-03 07:22:18.295929 82.37% [DEBUG] mod_dptools.c:1673 SET sofia/default/1007799@65.109.231.66 [api_hangup_hook]=[system /var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif 9972249370 PK+Admin+Customer_QA+Beta prashant443322123@gmail.com /var/www/html/astpp/attachments/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.pdf prashant443322123@gmail.com 1007799 9972249370]
  493. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 rxfax(/var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif)
  494. 2023-10-03 07:22:18.315912 82.37% [DEBUG] mod_spandsp_fax.c:1607 Raw read codec activation Success L16 20000
  495. 2023-10-03 07:22:18.315912 82.37% [DEBUG] switch_core_codec.c:223 sofia/default/1007799@65.109.231.66 Push codec L16:100
  496. 2023-10-03 07:22:18.315912 82.37% [DEBUG] mod_spandsp_fax.c:1625 Raw write codec activation Success L16
  497. 2023-10-03 07:22:19.575907 82.70% [DEBUG] switch_core_media.c:11908 sofia/default/1007799@65.109.231.66 image media sdp:
  498. v=0
  499. o=FreeSWITCH 1696298176 1696298179 IN IP4 65.109.231.66
  500. s=FreeSWITCH
  501. c=IN IP4 65.109.231.66
  502. t=0 0
  503. m=image 19558 udptl t38
  504. a=T38FaxVersion:0
  505. a=T38MaxBitRate:9600
  506. a=T38FaxFillBitRemoval
  507. a=T38FaxRateManagement:transferredTCF
  508. a=T38FaxMaxBuffer:2000
  509. a=T38FaxMaxDatagram:400
  510. a=T38FaxUdpEC:t38UDPRedundancy
  511.  
  512. 2023-10-03 07:22:19.575907 82.70% [INFO] sofia_glue.c:1654 sofia/default/1007799@65.109.231.66 sending invite call-id: (null)
  513. 2023-10-03 07:22:19.575907 82.70% [DEBUG] sofia_glue.c:1657 sofia/default/1007799@65.109.231.66 sending invite version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit
  514. Local SDP:
  515. v=0
  516. o=FreeSWITCH 1696298176 1696298179 IN IP4 65.109.231.66
  517. s=FreeSWITCH
  518. c=IN IP4 65.109.231.66
  519. t=0 0
  520. m=image 19558 udptl t38
  521. a=T38FaxVersion:0
  522. a=T38MaxBitRate:9600
  523. a=T38FaxFillBitRemoval
  524. a=T38FaxRateManagement:transferredTCF
  525. a=T38FaxMaxBuffer:2000
  526. a=T38FaxMaxDatagram:400
  527. a=T38FaxUdpEC:t38UDPRedundancy
  528.  
  529. 2023-10-03 07:22:19.575907 82.70% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66:5079 entering state [received][100]
  530. 2023-10-03 07:22:19.575907 82.70% [DEBUG] sofia.c:7497 Remote SDP:
  531. v=0
  532. o=FreeSWITCH 1696298176 1696298179 IN IP4 65.109.231.66
  533. s=FreeSWITCH
  534. c=IN IP4 65.109.231.66
  535. t=0 0
  536. m=image 19558 udptl t38
  537. a=T38FaxVersion:0
  538. a=T38MaxBitRate:9600
  539. a=T38FaxFillBitRemoval
  540. a=T38FaxRateManagement:transferredTCF
  541. a=T38FaxMaxBuffer:2000
  542. a=T38FaxMaxDatagram:400
  543. a=T38FaxUdpEC:t38UDPRedundancy
  544.  
  545. 2023-10-03 07:22:19.575907 82.70% [DEBUG] switch_core_media.c:5118 sofia/default/1007799@65.109.231.66:5079 T38 ACCEPT on request
  546. 2023-10-03 07:22:19.575907 82.70% [DEBUG] switch_core_media.c:5222 sofia/default/1007799@65.109.231.66:5079 T38 IS POSSIBLE on request
  547. 2023-10-03 07:22:19.595945 82.70% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66 entering state [calling][0]
  548. 2023-10-03 07:22:19.595945 82.70% [INFO] mod_spandsp_fax.c:1159 T38 SDP Origin = FreeSWITCH
  549. T38FaxVersion = 0
  550. T38MaxBitRate = 9600
  551. T38FaxFillBitRemoval = 1
  552. T38FaxTranscodingMMR = 0
  553. T38FaxTranscodingJBIG = 0
  554. T38FaxRateManagement = 'transferredTCF'
  555. T38FaxMaxBuffer = 2000
  556. T38FaxMaxDatagram = 400
  557. T38FaxUdpEC = 't38UDPRedundancy'
  558. T38VendorInfo = ''
  559. ip = '65.109.231.66'
  560. port = 19558
  561. 2023-10-03 07:22:19.595945 82.70% [DEBUG] switch_core_media.c:12337 Remote address:port [65.109.231.66:19558] has not changed.
  562. 2023-10-03 07:22:19.595945 82.70% [DEBUG] switch_core_media.c:11908 sofia/default/1007799@65.109.231.66:5079 image media sdp:
  563. v=0
  564. o=FreeSWITCH 1696295272 1696295274 IN IP4 65.109.231.66
  565. s=FreeSWITCH
  566. c=IN IP4 65.109.231.66
  567. t=0 0
  568. m=image 22462 udptl t38
  569. a=T38FaxVersion:0
  570. a=T38MaxBitRate:14400
  571. a=T38FaxFillBitRemoval
  572. a=T38FaxRateManagement:transferredTCF
  573. a=T38FaxMaxBuffer:2000
  574. a=T38FaxMaxDatagram:400
  575. a=T38FaxUdpEC:t38UDPRedundancy
  576.  
  577. 2023-10-03 07:22:19.595945 82.70% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66:5079 entering state [completed][200]
  578. 2023-10-03 07:22:19.595945 82.70% [DEBUG] switch_core_media.c:12337 Remote address:port [65.109.231.66:19558] has not changed.
  579. 2023-10-03 07:22:19.616004 82.70% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66 entering state [completing][200]
  580. 2023-10-03 07:22:19.616004 82.70% [DEBUG] sofia.c:7497 Remote SDP:
  581. v=0
  582. o=FreeSWITCH 1696295272 1696295274 IN IP4 65.109.231.66
  583. s=FreeSWITCH
  584. c=IN IP4 65.109.231.66
  585. t=0 0
  586. m=image 22462 udptl t38
  587. a=T38FaxVersion:0
  588. a=T38MaxBitRate:14400
  589. a=T38FaxFillBitRemoval
  590. a=T38FaxRateManagement:transferredTCF
  591. a=T38FaxMaxBuffer:2000
  592. a=T38FaxMaxDatagram:400
  593. a=T38FaxUdpEC:t38UDPRedundancy
  594.  
  595. 2023-10-03 07:22:19.616004 82.70% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66:5079 entering state [ready][200]
  596. 2023-10-03 07:22:19.635915 82.70% [DEBUG] sofia.c:7487 Channel sofia/default/1007799@65.109.231.66 entering state [ready][200]
  597. 2023-10-03 07:22:19.635915 82.70% [DEBUG] switch_core_media.c:5118 sofia/default/1007799@65.109.231.66 T38 ACCEPT on response
  598. 2023-10-03 07:22:19.635915 82.70% [DEBUG] switch_core_media.c:5222 sofia/default/1007799@65.109.231.66 T38 IS POSSIBLE on response
  599. 2023-10-03 07:22:19.635915 82.70% [DEBUG] switch_core_media.c:8633 Audio params are unchanged for sofia/default/1007799@65.109.231.66.
  600. 2023-10-03 07:22:19.635915 82.70% [DEBUG] switch_core_media.c:12337 Remote address:port [65.109.231.66:22462] has not changed.
  601. 2023-10-03 07:22:19.635915 82.70% [DEBUG] switch_core_media.c:12337 Remote address:port [65.109.231.66:22462] has not changed.
  602. 2023-10-03 07:22:25.055916 82.43% [INFO] mod_spandsp_fax.c:360 === Negotiation Result =======================================================
  603. Remote station id: SpanDSP Fax Ident
  604. Local station id:  9972249370
  605. Transfer Rate:     9600
  606. ECM status         off
  607. T38 status         negotiated
  608. remote country:    
  609. remote vendor:    
  610. remote model:      
  611. Total fax pages:   0
  612. ==============================================================================
  613. 2023-10-03 07:22:27.055922 82.07% [INFO] mod_spandsp_fax.c:360 === Negotiation Result =======================================================
  614. Remote station id: 9972249370
  615. Local station id:  SpanDSP Fax Ident
  616. Transfer Rate:     9600
  617. ECM status         off
  618. T38 status         negotiated
  619. remote country:    
  620. remote vendor:    
  621. remote model:      
  622. ==============================================================================
  623. 2023-10-03 07:22:35.775913 84.83% [INFO] mod_spandsp_fax.c:496 ==== Page Received ===========================================================
  624. Page no = 1
  625. Image type = bi-level (bi-level in the file)
  626. Image size = 1728 x 1202 pixels (1728 x 1202 pixels in the file)
  627. Image resolution = 8040/m x 3850/m (8040/m x 3850/m in the file)
  628. Compression = T.4 2-D (4)
  629. Compressed image size = 4279 bytes
  630. Bad rows = 0
  631. Longest bad row run = 0
  632. ==============================================================================
  633. 2023-10-03 07:22:36.955925 84.87% [INFO] mod_spandsp_fax.c:496 ==== Page Sent ===============================================================
  634. Page no = 1
  635. Total fax pages:   1
  636. Image type = bi-level (bi-level in the file)
  637. Image size = 1728 x 1202 pixels (1728 x 1186 pixels in the file)
  638. Image resolution = 8040/m x 3850/m (8031/m x 3858/m in the file)
  639. Compression = T.4 2-D (4)
  640. Compressed image size = 4279 bytes
  641. Bad rows = 0
  642. Longest bad row run = 0
  643. ==============================================================================
  644. 2023-10-03 07:22:38.135897 84.90% [INFO] mod_spandsp_fax.c:596 ==============================================================================
  645. Fax successfully received.
  646. Remote station id: 9972249370
  647. Local station id:  SpanDSP Fax Ident
  648. Pages transferred: 1
  649. Total fax pages:   1
  650. Image resolution:  8040x3850
  651. Transfer Rate:     9600
  652. ECM status         off
  653. T38 status         negotiated
  654. remote country:    
  655. remote vendor:    
  656. remote model:      
  657. ==============================================================================
  658. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_codec.c:248 sofia/default/1007799@65.109.231.66 Restore previous codec PCMA:8.
  659. EXECUTE [depth=0] sofia/default/1007799@65.109.231.66 hangup()
  660. 2023-10-03 07:22:38.135897 84.90% [NOTICE] mod_dptools.c:1376 Hangup sofia/default/1007799@65.109.231.66 [CS_EXECUTE] [NORMAL_CLEARING]
  661. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_session.c:2973 sofia/default/1007799@65.109.231.66 skip receive message [PHONE_EVENT] (channel is hungup already)
  662. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1007799@65.109.231.66) State EXECUTE going to sleep
  663. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66) Running State Change CS_HANGUP (Cur 2 Tot 4)
  664. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1007799@65.109.231.66) Callstate Change ACTIVE -> HANGUP
  665. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1007799@65.109.231.66) State HANGUP
  666. 2023-10-03 07:22:38.135897 84.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/1007799@65.109.231.66 hanging up, cause: NORMAL_CLEARING
  667. 2023-10-03 07:22:38.135897 84.90% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/1007799@65.109.231.66
  668. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/1007799@65.109.231.66 Standard HANGUP, cause: NORMAL_CLEARING
  669. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1007799@65.109.231.66) State HANGUP going to sleep
  670. 2023-10-03 07:22:38.135897 84.90% [NOTICE] sofia.c:1065 Hangup sofia/default/1007799@65.109.231.66:5079 [CS_EXECUTE] [NORMAL_CLEARING]
  671. 2023-10-03 07:22:38.135897 84.90% [INFO] mod_spandsp_fax.c:596 ==============================================================================
  672. Fax successfully sent.
  673. Remote station id: SpanDSP Fax Ident
  674. Local station id:  9972249370
  675. Pages transferred: 1
  676. Total fax pages:   1
  677. Image resolution:  8040x3850
  678. Transfer Rate:     9600
  679. ECM status         off
  680. T38 status         negotiated
  681. remote country:    
  682. remote vendor:    
  683. remote model:      
  684. ==============================================================================
  685. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_codec.c:248 sofia/default/1007799@65.109.231.66:5079 Restore previous codec PCMA:8.
  686. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_session.c:2973 sofia/default/1007799@65.109.231.66:5079 skip receive message [PHONE_EVENT] (channel is hungup already)
  687. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1007799@65.109.231.66:5079) State EXECUTE going to sleep
  688. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66:5079) Running State Change CS_HANGUP (Cur 2 Tot 4)
  689. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1007799@65.109.231.66:5079) Callstate Change ACTIVE -> HANGUP
  690. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1007799@65.109.231.66:5079) State HANGUP
  691. 2023-10-03 07:22:38.135897 84.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/1007799@65.109.231.66:5079 hanging up, cause: NORMAL_CLEARING
  692. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/1007799@65.109.231.66:5079 Standard HANGUP, cause: NORMAL_CLEARING
  693. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1007799@65.109.231.66:5079) State HANGUP going to sleep
  694. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1007799@65.109.231.66:5079) State Change CS_HANGUP -> CS_REPORTING
  695. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66:5079) Running State Change CS_REPORTING (Cur 2 Tot 4)
  696. 2023-10-03 07:22:38.135897 84.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1007799@65.109.231.66:5079) State REPORTING
  697. 2023-10-03 07:22:38.135897 84.90% [INFO] mod_json_cdr.c:272 Process [1e112296-32db-4010-bd14-d8031933b6ed.cdr.json]
  698. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/1007799@65.109.231.66:5079 Standard REPORTING, cause: NORMAL_CLEARING
  699. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1007799@65.109.231.66:5079) State REPORTING going to sleep
  700. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1007799@65.109.231.66:5079) State Change CS_REPORTING -> CS_DESTROY
  701. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_session.c:1743 Session 3 (sofia/default/1007799@65.109.231.66:5079) Locked, Waiting on external entities
  702. 2023-10-03 07:22:38.175886 84.90% [NOTICE] switch_core_session.c:1761 Session 3 (sofia/default/1007799@65.109.231.66:5079) Ended
  703. 2023-10-03 07:22:38.175886 84.90% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/1007799@65.109.231.66:5079 [CS_DESTROY]
  704. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1007799@65.109.231.66:5079) Running State Change CS_DESTROY (Cur 1 Tot 4)
  705. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1007799@65.109.231.66:5079) State DESTROY
  706. 2023-10-03 07:22:38.175886 84.90% [DEBUG] mod_sofia.c:379 sofia/default/1007799@65.109.231.66:5079 SOFIA DESTROY
  707. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/1007799@65.109.231.66:5079 Standard DESTROY
  708. 2023-10-03 07:22:38.175886 84.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1007799@65.109.231.66:5079) State DESTROY going to sleep
  709. 2023-10-03 07:22:38.275914 84.90% [DEBUG] switch_core_state_machine.c:777 Hangup Command with Session system(/var/www/html/astpp/FAX/scripts/emailfax.sh /var/www/html/astpp/FAX/receive_fax_files/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.tif 9972249370 PK+Admin+Customer_QA+Beta prashant443322123@gmail.com /var/www/html/astpp/attachments/e5f7db7d-67d1-49fb-b0dc-0d1369657c74-FAX.pdf prashant443322123@gmail.com 1007799 9972249370):
  710.  
  711. 2023-10-03 07:22:38.275914 84.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1007799@65.109.231.66) State Change CS_HANGUP -> CS_REPORTING
  712. 2023-10-03 07:22:38.275914 84.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1007799@65.109.231.66) Running State Change CS_REPORTING (Cur 1 Tot 4)
  713. 2023-10-03 07:22:38.275914 84.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1007799@65.109.231.66) State REPORTING
  714. 2023-10-03 07:22:38.295921 84.90% [INFO] mod_json_cdr.c:272 Process [e5f7db7d-67d1-49fb-b0dc-0d1369657c74.cdr.json]
  715. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_state_machine.c:168 sofia/default/1007799@65.109.231.66 Standard REPORTING, cause: NORMAL_CLEARING
  716. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1007799@65.109.231.66) State REPORTING going to sleep
  717. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1007799@65.109.231.66) State Change CS_REPORTING -> CS_DESTROY
  718. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_session.c:1743 Session 4 (sofia/default/1007799@65.109.231.66) Locked, Waiting on external entities
  719. 2023-10-03 07:22:38.575889 84.43% [NOTICE] switch_core_session.c:1761 Session 4 (sofia/default/1007799@65.109.231.66) Ended
  720. 2023-10-03 07:22:38.575889 84.43% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/1007799@65.109.231.66 [CS_DESTROY]
  721. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1007799@65.109.231.66) Running State Change CS_DESTROY (Cur 0 Tot 4)
  722. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1007799@65.109.231.66) State DESTROY
  723. 2023-10-03 07:22:38.575889 84.43% [DEBUG] mod_sofia.c:379 sofia/default/1007799@65.109.231.66 SOFIA DESTROY
  724. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_state_machine.c:175 sofia/default/1007799@65.109.231.66 Standard DESTROY
  725. 2023-10-03 07:22:38.575889 84.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1007799@65.109.231.66) State DESTROY going to sleep
  726. freeswitch@betaAlpesh>

Reply to "FOIP DID email not receive fax issue"