FreeSWITCH Pastebin

shadow billing not applied in reseller's customer

From Prashant kumar, 2 Years ago, written in FreeSWITCH.
- go back
URL https://pastebin.freeswitch.org/view/b1263d88
Embed
Viewing differences between and shadow billing not applied in reseller's customer
  1. freeswitch@devangASTPP>
  2. freeswitch@devangASTPP>
  3. 2022-05-18 13:43:13.521040 97.23% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2276576399@astppqa603.astppbilling.org:5060 [d77cdf1c-3270-492d-a8b1-39708f8fa0fb]
  4. 2022-05-18 13:43:13.521040 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Running State Change CS_NEW (Cur 1 Tot 42293)
  5. 2022-05-18 13:43:13.521040 97.23% [INFO] sofia.c:10462 sofia/default/2276576399@astppqa603.astppbilling.org:5060 receiving invite from 117.247.91.162:58529 version: 1.10.7 -release 64bit call-id: IT2VM6xNL1KvJaz2kgzYBA..
  6. 2022-05-18 13:43:13.521040 97.23% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 117.247.91.162:0.
  7. 2022-05-18 13:43:13.521040 97.23% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [98095494994@astppqa603.astppbilling.org] from ip 117.247.91.162
  8. 2022-05-18 13:43:13.521040 97.23% [DEBUG] switch_core_state_machine.c:600 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State NEW
  9. 2022-05-18 13:43:13.521040 97.23% [DEBUG] sofia.c:2419 detaching session d77cdf1c-3270-492d-a8b1-39708f8fa0fb
  10. 2022-05-18 13:43:14.001054 97.23% [DEBUG] sofia.c:2532 Re-attaching to session d77cdf1c-3270-492d-a8b1-39708f8fa0fb
  11. 2022-05-18 13:43:14.021038 97.23% [INFO] sofia.c:10462 sofia/default/2276576399@astppqa603.astppbilling.org:5060 receiving invite from 117.247.91.162:58529 version: 1.10.7 -release 64bit call-id: IT2VM6xNL1KvJaz2kgzYBA..
  12. 2022-05-18 13:43:14.021038 97.23% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 117.247.91.162:0.
  13. 2022-05-18 13:43:14.021038 97.23% [DEBUG] sofia.c:11672 Setting NAT mode based on via port
  14. 2022-05-18 13:43:14.021038 97.23% [DEBUG] sofia.c:7499 Channel sofia/default/2276576399@astppqa603.astppbilling.org:5060 entering state [received][100]
  15. 2022-05-18 13:43:14.021038 97.23% [DEBUG] sofia.c:7509 Remote SDP:
  16. v=0
  17. o=Z 0 0 IN IP4 117.247.91.162
  18. s=Z
  19. c=IN IP4 117.247.91.162
  20. t=0 0
  21. m=audio 8000 RTP/AVP 3 110 8 0 97 101
  22. a=rtpmap:110 speex/8000
  23. a=rtpmap:97 iLBC/8000
  24. a=fmtp:97 mode=30
  25. a=rtpmap:101 telephone-event/8000
  26. a=fmtp:101 0-16
  27.  
  28. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  29. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  30. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  31. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  32. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  33. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  34. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  35. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  36. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  37. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  38. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  39. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  40. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  41. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/2276576399@astppqa603.astppbilling.org:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  42. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_codec.c:111 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Original read codec set to PCMA:8
  43. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  44. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_media.c:5973 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  45. 2022-05-18 13:43:14.021038 97.23% [DEBUG] sofia.c:7933 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State Change CS_NEW -> CS_INIT
  46. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Running State Change CS_INIT (Cur 1 Tot 42293)
  47. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State INIT
  48. 2022-05-18 13:43:14.021038 97.23% [DEBUG] mod_sofia.c:97 sofia/default/2276576399@astppqa603.astppbilling.org:5060 SOFIA INIT
  49. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:40 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Standard INIT
  50. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State Change CS_INIT -> CS_ROUTING
  51. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State INIT going to sleep
  52. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Running State Change CS_ROUTING (Cur 1 Tot 42293)
  53. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_channel.c:2380 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Callstate Change DOWN -> RINGING
  54. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State ROUTING
  55. 2022-05-18 13:43:14.021038 97.23% [DEBUG] mod_sofia.c:158 sofia/default/2276576399@astppqa603.astppbilling.org:5060 SOFIA ROUTING
  56. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_core_state_machine.c:230 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Standard ROUTING
  57. 2022-05-18 13:43:14.021038 97.23% [INFO] mod_dialplan_xml.c:639 Processing 2276576399 <2276576399>->98095494994 in context default
  58. 2022-05-18 13:43:14.021038 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  59. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  60. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')
  61. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  62. 2022-05-18 13:43:14.021038 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 98095494994
  63. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '98095494994' AND status=0 limit 1
  64. 2022-05-18 13:43:14.021038 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER2276576399
  65. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  66. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT domain,accountid FROM domains WHERE domain = 'astppqa603.astppbilling.org' limit 1
  67. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL DOMAIN] Query :SELECT accountid from sip_devices where accountid = '10' and username = '98095494994' limit 1
  68. 2022-05-18 13:43:14.021038 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 2276576399 / 2276576399
  69. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="98095494994" limit 1
  70. 2022-05-18 13:43:14.021038 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
  71. 2022-05-18 13:43:14.021038 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 2276576399]
  72. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "2276576399" AND deleted = 0 limit 1
  73. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^98095494994.*' OR blocked_patterns = '^9809549499.*' OR blocked_patterns = '^980954949.*' OR blocked_patterns = '^98095494.*' OR blocked_patterns = '^9809549.*' OR blocked_patterns = '^980954.*' OR blocked_patterns = '^98095.*' OR blocked_patterns = '^9809.*' OR blocked_patterns = '^980.*' OR blocked_patterns = '^98.*' OR blocked_patterns = '^9.*' OR blocked_patterns ='--') AND accountid = 10 limit 1
  74. 2022-05-18 13:43:14.021038 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="98095494994" AND (accountid = 0 OR status = 1) LIMIT 1
  75. 2022-05-18 13:43:14.021038 97.23% [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 = '^98095494994.*' OR patterns = '^9809549499.*' OR patterns = '^980954949.*' OR patterns = '^98095494.*' OR patterns = '^9809549.*' OR patterns = '^980954.*' OR patterns = '^98095.*' OR patterns = '^9809.*' OR patterns = '^980.*' OR patterns = '^98.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 10 ORDER BY LENGTH(PKGPTR.patterns) DESC
  76. 2022-05-18 13:43:14.041034 97.23% [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 ="98095494994" AND A.accountid = '10' limit 1
  77. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 98095494994
  78. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="98095494994" limit 1
  79. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
  80. 2022-05-18 13:43:14.041034 97.23% [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 = '98095494994' OR number = '9809549499' OR number = '980954949' OR number = '98095494' OR number = '9809549' OR number = '980954' OR number = '98095' OR number = '9809' OR number = '980' OR number = '98' OR number = '9' OR number ='--') OR (number = '98095494994*' OR number = '9809549499*' OR number = '980954949*' OR number = '98095494*' OR number = '9809549*' OR number = '980954*' OR number = '98095*' OR number = '9809*' OR number = '980*' OR number = '98*' OR number = '9*' OR number ='--') OR (number = '2276576399' OR number = '227657639' OR number = '22765763' OR number = '2276576' OR number = '227657' OR number = '22765' OR number = '2276' OR number = '227' OR number = '22' OR number = '2' OR number ='--') OR (number = '2276576399*' OR number = '227657639*' OR number = '22765763*' OR number = '2276576*' OR number = '227657*' OR number = '22765*' OR number = '2276*' OR number = '227*' OR number = '22*' OR number = '2*' OR number ='--')) AND status=0 AND accountid = 0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  81. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  82. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '98095494994' OR routing_prefix = '9809549499' OR routing_prefix = '980954949' OR routing_prefix = '98095494' OR routing_prefix = '9809549' OR routing_prefix = '980954' OR routing_prefix = '98095' OR routing_prefix = '9809' OR routing_prefix = '980' OR routing_prefix = '98' OR routing_prefix = '9' OR routing_prefix ='--') AND status = 0 and reseller_id = 5 limit 1
  83. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  84. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 10
  85. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 2276576399
  86. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 196
  87. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  88. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
  89. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  90. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  91. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 2 AND status = 0
  92. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
  93. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 2 AND status = 0
  94. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^98095494994.*' OR pattern = '^9809549499.*' OR pattern = '^980954949.*' OR pattern = '^98095494.*' OR pattern = '^9809549.*' OR pattern = '^980954.*' OR pattern = '^98095.*' OR pattern = '^9809.*' OR pattern = '^980.*' OR pattern = '^98.*' OR pattern = '^9.*' OR pattern ='--') AND (caller_id_pattern = '^2276576399.*' OR caller_id_pattern = '^227657639.*' OR caller_id_pattern = '^22765763.*' OR caller_id_pattern = '^2276576.*' OR caller_id_pattern = '^227657.*' OR caller_id_pattern = '^22765.*' OR caller_id_pattern = '^2276.*' OR caller_id_pattern = '^227.*' OR caller_id_pattern = '^22.*' OR caller_id_pattern = '^2.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 2 OR accountid=10)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  95. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
  96. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  97. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 2
  98. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  99. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  100. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.60000
  101. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Reseller
  102. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
  103. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 10
  104. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
  105. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  106. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  107. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Shadow_billing_rates]SHADOWBILLING:|ID:2|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:0|ACCID:10!!!
  108. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your196 balance Accountid 10 !!!
  109. 2022-05-18 13:43:14.041034 97.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
  110. 2022-05-18 13:43:14.041034 97.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] FINDING LIMIT FOR RESELLER: 5
  111. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "5" AND deleted = 0 limit 1
  112. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^98095494994.*' OR blocked_patterns = '^9809549499.*' OR blocked_patterns = '^980954949.*' OR blocked_patterns = '^98095494.*' OR blocked_patterns = '^9809549.*' OR blocked_patterns = '^980954.*' OR blocked_patterns = '^98095.*' OR blocked_patterns = '^9809.*' OR blocked_patterns = '^980.*' OR blocked_patterns = '^98.*' OR blocked_patterns = '^9.*' OR blocked_patterns ='--') AND accountid = 5 limit 1
  113. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Reseller Information ===================
  114. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 5
  115. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9024864059
  116. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 1997.9
  117. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  118. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 1
  119. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  120. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
  121. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  122. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^98095494994.*' OR pattern = '^9809549499.*' OR pattern = '^980954949.*' OR pattern = '^98095494.*' OR pattern = '^9809549.*' OR pattern = '^980954.*' OR pattern = '^98095.*' OR pattern = '^9809.*' OR pattern = '^980.*' OR pattern = '^98.*' OR pattern = '^9.*' OR pattern ='--') AND (caller_id_pattern = '^2276576399.*' OR caller_id_pattern = '^227657639.*' OR caller_id_pattern = '^22765763.*' OR caller_id_pattern = '^2276576.*' OR caller_id_pattern = '^227657.*' OR caller_id_pattern = '^22765.*' OR caller_id_pattern = '^2276.*' OR caller_id_pattern = '^227.*' OR caller_id_pattern = '^22.*' OR caller_id_pattern = '^2.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 1 OR accountid=5)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  123. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
  124. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  125. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 9
  126. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  127. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  128. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.40000
  129. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Admin
  130. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 46
  131. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 5
  132. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
  133. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  134. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  135. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Shadow_billing_rates]SHADOWBILLING:1|ID:9|CODE:^98.*|DESTINATION:Admin|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.40000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:46|ACCID:5!!!
  136. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your1997.9 balance Accountid 5 !!!
  137. 2022-05-18 13:43:14.041034 97.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
  138. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Reseller xml_user_rates : SHADOWBILLING:|ID:2|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:0|ACCID:10||SHADOWBILLING:1|ID:9|CODE:^98.*|DESTINATION:Admin|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.40000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:46|ACCID:5
  139. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  140. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Lossless Routing Status : 1
  141. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
  142. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
  143. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=1 ORDER by id asc
  144. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^98095494994.*' OR pattern = '^9809549499.*' OR pattern = '^980954949.*' OR pattern = '^98095494.*' OR pattern = '^9809549.*' OR pattern = '^980954.*' OR pattern = '^98095.*' OR pattern = '^9809.*' OR pattern = '^980.*' OR pattern = '^98.*' OR pattern = '^9.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (1) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC
  145. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  146. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 8
  147. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^98.*
  148. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : Reseller
  149. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  150. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  151. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^98.*
  152. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
  153. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
  154. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
  155. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : YourProvider
  156. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
  157. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway : failoverQA440
  158. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
  159. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  160. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
  161. 2022-05-18 13:43:14.041034 97.23% [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 = 10 AND status=0 LIMIT 1
  162. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
  163. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  164. 2022-05-18 13:43:14.041034 97.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  165. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  166. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 3) AND status=0 limit 1
  167. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 1) AND status=0 limit 1
  168. 2022-05-18 13:43:14.041034 97.23% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="98095494994" LIMIT 1
  169. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  170. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
  171. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  172. <document type="freeswitch/xml">
  173. <section name="dialplan" description="ASTPP Dialplan">
  174. <context name="default">
  175. <extension name="98095494994">
  176. <condition field="destination_number" expression="98095494994">
  177. <action application="set" data="effective_destination_number=98095494994"/>
  178. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  179. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  180. <action application="set" data="notify_flag=0"/>
  181. <action application="set" data="notify_credit_limit=5.00000"/>
  182. <action application="set" data="type=0"/>
  183. <action application="set" data="callstart=2022-05-18 13:43:14"/>
  184. <action application="set" data="hangup_after_bridge=true"/>
  185. <action application="set" data="continue_on_fail=TRUE"/>
  186. <action application="set" data="account_id=10"/>
  187. <action application="set" data="parent_id=5"/>
  188. <action application="set" data="entity_id=0"/>
  189. <action application="set" data="call_processed=internal"/>
  190. <action application="set" data="call_direction=outbound"/>
  191. <action application="set" data="accountname=default"/>
  192. <action application="set" data="origination_rates=SHADOWBILLING:|ID:2|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:0|ACCID:10||SHADOWBILLING:1|ID:9|CODE:^98.*|DESTINATION:Admin|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.40000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:46|ACCID:5"/>
  193. <action application="set" data="original_caller_id_name=2276576399"/>
  194. <action application="set" data="original_caller_id_number=2276576399"/>
  195.  
  196. <action application="export" data="is_recording=1"/>
  197. <action application="export" data="media_bug_answer_req=true"/>
  198. <action application="export" data="record_sample_rate=8000"/>
  199. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
  200. <action application="set" data="effective_caller_id_name=2276576399"/>
  201. <action application="set" data="effective_caller_id_number=2276576399"/>
  202. <action application="set" data="calltype=STANDARD"/>
  203. <action application="set" data="termination_rates=ID:8|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.15000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3"/>
  204. <action application="set" data="trunk_id=1"/>
  205. <action application="set" data="provider_id=3"/>
  206. <action application="set" data="rate_flag=0"/>
  207. <action application="set" data="force_trunk_flag=0"/>
  208. <action application="export" data="presence_data=trunk_id=1"/>
  209. <action application="set" data="intcall=0"/>
  210. <action application="export" data="presence_id=98095494994"/>
  211. <action application="export" data="presence_data=x//5|||Reseller Cust(2276576399)|||^98.* // Reseller // 0.60000 |||YourTrunk // ^98.* // Reseller // 0.15000 // trunk_id=1|||STD|||117.247.91.162|||0.40000 // ^98.* // Admin"/>
  212. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/98095494994"/>
  213. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA440/98095494994"/>
  214. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA770/98095494994"/>
  215. </condition>
  216. </extension>
  217. </context>
  218. </section>
  219. </document>
  220. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 parsing [default->98095494994] continue=false
  221. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Regex (PASS) [98095494994] destination_number(98095494994) =~ /98095494994/ break=on-false
  222. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(effective_destination_number=98095494994)
  223. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  224. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  225. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(notify_flag=0)
  226. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(notify_credit_limit=5.00000)
  227. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(type=0)
  228. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(callstart=2022-05-18 13:43:14)
  229. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(hangup_after_bridge=true)
  230. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(continue_on_fail=TRUE)
  231. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(account_id=10)
  232. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(parent_id=5)
  233. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(entity_id=0)
  234. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(call_processed=internal)
  235. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(call_direction=outbound)
  236. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(accountname=default)
  237. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(origination_rates=SHADOWBILLING:|ID:2|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:0|ACCID:10||SHADOWBILLING:1|ID:9|CODE:^98.*|DESTINATION:Admin|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.40000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:46|ACCID:5)
  238. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(original_caller_id_name=2276576399)
  239. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(original_caller_id_number=2276576399)
  240. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action export(is_recording=1)
  241. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action export(media_bug_answer_req=true)
  242. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action export(record_sample_rate=8000)
  243. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
  244. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(effective_caller_id_name=2276576399)
  245. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(effective_caller_id_number=2276576399)
  246. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(calltype=STANDARD)
  247. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(termination_rates=ID:8|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.15000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3)
  248. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(trunk_id=1)
  249. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(provider_id=3)
  250. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(rate_flag=0)
  251. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(force_trunk_flag=0)
  252. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action export(presence_data=trunk_id=1)
  253. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action set(intcall=0)
  254. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action export(presence_id=98095494994)
  255. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action export(presence_data=x//5|||Reseller Cust(2276576399)|||^98.* // Reseller // 0.60000 |||YourTrunk // ^98.* // Reseller // 0.15000 // trunk_id=1|||STD|||117.247.91.162|||0.40000 // ^98.* // Admin)
  256. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/98095494994)
  257. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA440/98095494994)
  258. Dialplan: sofia/default/2276576399@astppqa603.astppbilling.org:5060 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA770/98095494994)
  259. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_core_state_machine.c:281 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State Change CS_ROUTING -> CS_EXECUTE
  260. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State ROUTING going to sleep
  261. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Running State Change CS_EXECUTE (Cur 1 Tot 42293)
  262. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_core_state_machine.c:647 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State EXECUTE
  263. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_sofia.c:213 sofia/default/2276576399@astppqa603.astppbilling.org:5060 SOFIA EXECUTE
  264. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_core_state_machine.c:323 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Standard EXECUTE
  265. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(effective_destination_number=98095494994)
  266. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [effective_destination_number]=[98095494994]
  267. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
  268. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
  269. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  270. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  271. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(notify_flag=0)
  272. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [notify_flag]=[0]
  273. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(notify_credit_limit=5.00000)
  274. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [notify_credit_limit]=[5.00000]
  275. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(type=0)
  276. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [type]=[0]
  277. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(callstart=2022-05-18 13:43:14)
  278. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [callstart]=[2022-05-18 13:43:14]
  279. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(hangup_after_bridge=true)
  280. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [hangup_after_bridge]=[true]
  281. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(continue_on_fail=TRUE)
  282. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [continue_on_fail]=[TRUE]
  283. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(account_id=10)
  284. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [account_id]=[10]
  285. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(parent_id=5)
  286. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [parent_id]=[5]
  287. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(entity_id=0)
  288. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [entity_id]=[0]
  289. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(call_processed=internal)
  290. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [call_processed]=[internal]
  291. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(call_direction=outbound)
  292. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [call_direction]=[outbound]
  293. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(accountname=default)
  294. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [accountname]=[default]
  295. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(origination_rates=SHADOWBILLING:|ID:2|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:0|ACCID:10||SHADOWBILLING:1|ID:9|CODE:^98.*|DESTINATION:Admin|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.40000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:46|ACCID:5)
  296. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [origination_rates]=[SHADOWBILLING:|ID:2|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:0|ACCID:10||SHADOWBILLING:1|ID:9|CODE:^98.*|DESTINATION:Admin|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.40000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:46|ACCID:5]
  297. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(original_caller_id_name=2276576399)
  298. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [original_caller_id_name]=[2276576399]
  299. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(original_caller_id_number=2276576399)
  300. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [original_caller_id_number]=[2276576399]
  301. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 export(is_recording=1)
  302. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [is_recording]=[1]
  303. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 export(media_bug_answer_req=true)
  304. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [media_bug_answer_req]=[true]
  305. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 export(record_sample_rate=8000)
  306. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [record_sample_rate]=[8000]
  307. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 export(execute_on_answer=record_session /usr/local/freeswitch/recordings/d77cdf1c-3270-492d-a8b1-39708f8fa0fb.wav)
  308. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/d77cdf1c-3270-492d-a8b1-39708f8fa0fb.wav]
  309. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(effective_caller_id_name=2276576399)
  310. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [effective_caller_id_name]=[2276576399]
  311. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(effective_caller_id_number=2276576399)
  312. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [effective_caller_id_number]=[2276576399]
  313. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(calltype=STANDARD)
  314. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [calltype]=[STANDARD]
  315. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(termination_rates=ID:8|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.15000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3)
  316. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [termination_rates]=[ID:8|CODE:^98.*|DESTINATION:Reseller|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.15000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3]
  317. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(trunk_id=1)
  318. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [trunk_id]=[1]
  319. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(provider_id=3)
  320. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [provider_id]=[3]
  321. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(rate_flag=0)
  322. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [rate_flag]=[0]
  323. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(force_trunk_flag=0)
  324. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [force_trunk_flag]=[0]
  325. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 export(presence_data=trunk_id=1)
  326. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
  327. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 set(intcall=0)
  328. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_dptools.c:1685 SET sofia/default/2276576399@astppqa603.astppbilling.org:5060 [intcall]=[0]
  329. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 export(presence_id=98095494994)
  330. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[98095494994]
  331. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 export(presence_data=x//5|||Reseller Cust(2276576399)|||^98.* // Reseller // 0.60000 |||YourTrunk // ^98.* // Reseller // 0.15000 // trunk_id=1|||STD|||117.247.91.162|||0.40000 // ^98.* // Admin)
  332. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x//5|||Reseller Cust(2276576399)|||^98.* // Reseller // 0.60000 |||YourTrunk // ^98.* // Reseller // 0.15000 // trunk_id=1|||STD|||117.247.91.162|||0.40000 // ^98.* // Admin]
  333. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/98095494994)
  334. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1269 sofia/default/2276576399@astppqa603.astppbilling.org:5060 EXPORTING[export_vars] [is_recording]=[1] to event
  335. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1269 sofia/default/2276576399@astppqa603.astppbilling.org:5060 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
  336. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1269 sofia/default/2276576399@astppqa603.astppbilling.org:5060 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
  337. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1269 sofia/default/2276576399@astppqa603.astppbilling.org:5060 EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/d77cdf1c-3270-492d-a8b1-39708f8fa0fb.wav] to event
  338. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1269 sofia/default/2276576399@astppqa603.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x//5|||Reseller Cust(2276576399)|||^98.* // Reseller // 0.60000 |||YourTrunk // ^98.* // Reseller // 0.15000 // trunk_id=1|||STD|||117.247.91.162|||0.40000 // ^98.* // Admin] to event
  339. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1269 sofia/default/2276576399@astppqa603.astppbilling.org:5060 EXPORTING[export_vars] [presence_id]=[98095494994] to event
  340. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_channel.c:1269 sofia/default/2276576399@astppqa603.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x//5|||Reseller Cust(2276576399)|||^98.* // Reseller // 0.60000 |||YourTrunk // ^98.* // Reseller // 0.15000 // trunk_id=1|||STD|||117.247.91.162|||0.40000 // ^98.* // Admin] to event
  341. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  342. 2022-05-18 13:43:14.041034 97.23% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  343. 2022-05-18 13:43:14.041034 97.23% [NOTICE] switch_channel.c:1123 New Channel sofia/default/98095494994 [fdf28577-90e0-4dec-ac3e-3adf9f086c20]
  344. 2022-05-18 13:43:14.041034 97.23% [DEBUG] mod_sofia.c:5121 (sofia/default/98095494994) State Change CS_NEW -> CS_INIT
  345. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_ivr_originate.c:3151 sofia/default/98095494994 Setting leg timeout to 30
  346. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/98095494994) Running State Change CS_INIT (Cur 2 Tot 42294)
  347. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:624 (sofia/default/98095494994) State INIT
  348. 2022-05-18 13:43:14.061035 97.23% [DEBUG] mod_sofia.c:97 sofia/default/98095494994 SOFIA INIT
  349. 2022-05-18 13:43:14.061035 97.23% [INFO] sofia_glue.c:1651 sofia/default/98095494994 sending invite call-id: (null)
  350. 2022-05-18 13:43:14.061035 97.23% [DEBUG] sofia_glue.c:1654 sofia/default/98095494994 sending invite version: 1.10.7 -release 64bit
  351. Local SDP:
  352. v=0
  353. o=FreeSWITCH 1652857850 1652857851 IN IP4 142.132.184.134
  354. s=FreeSWITCH
  355. c=IN IP4 142.132.184.134
  356. t=0 0
  357. m=audio 23544 RTP/AVP 8 18 101
  358. a=rtpmap:8 PCMA/8000
  359. a=rtpmap:18 G729/8000
  360. a=rtpmap:101 telephone-event/8000
  361. a=fmtp:101 0-15
  362. a=ptime:20
  363. a=sendrecv
  364.  
  365. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:40 sofia/default/98095494994 Standard INIT
  366. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:48 (sofia/default/98095494994) State Change CS_INIT -> CS_ROUTING
  367. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:624 (sofia/default/98095494994) State INIT going to sleep
  368. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/98095494994) Running State Change CS_ROUTING (Cur 2 Tot 42294)
  369. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/98095494994) State ROUTING
  370. 2022-05-18 13:43:14.061035 97.23% [DEBUG] mod_sofia.c:158 sofia/default/98095494994 SOFIA ROUTING
  371. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_ivr_originate.c:67 (sofia/default/98095494994) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  372. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/98095494994) State ROUTING going to sleep
  373. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/98095494994) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 42294)
  374. 2022-05-18 13:43:14.061035 97.23% [DEBUG] sofia.c:7499 Channel sofia/default/98095494994 entering state [calling][0]
  375. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:659 (sofia/default/98095494994) State CONSUME_MEDIA
  376. 2022-05-18 13:43:14.061035 97.23% [DEBUG] switch_core_state_machine.c:659 (sofia/default/98095494994) State CONSUME_MEDIA going to sleep
  377. 2022-05-18 13:43:14.141040 97.23% [DEBUG] sofia.c:7499 Channel sofia/default/98095494994 entering state [completing][200]
  378. 2022-05-18 13:43:14.141040 97.23% [DEBUG] sofia.c:7509 Remote SDP:
  379. v=0
  380. o=root 1402591549 1402591549 IN IP4 159.203.131.36
  381. s=Asterisk PBX 16.4.0
  382. c=IN IP4 159.203.131.36
  383. t=0 0
  384. m=audio 18876 RTP/AVP 8 101
  385. a=rtpmap:8 PCMA/8000
  386. a=rtpmap:101 telephone-event/8000
  387. a=fmtp:101 0-16
  388. a=maxptime:150
  389.  
  390. 2022-05-18 13:43:14.141040 97.23% [DEBUG] sofia.c:7499 Channel sofia/default/98095494994 entering state [ready][200]
  391. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  392. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  393. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  394. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  395. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/98095494994 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  396. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_codec.c:111 sofia/default/98095494994 Original read codec set to PCMA:8
  397. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  398. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:5973 sofia/default/98095494994 Set 2833 dtmf send payload to 101 recv payload to 101
  399. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/98095494994] 142.132.184.134 port 23544 -> 159.203.131.36 port 18876 codec: 8 ms: 20
  400. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  401. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:9089 sofia/default/98095494994 Set 2833 dtmf send payload to 101
  402. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:9096 sofia/default/98095494994 Set 2833 dtmf receive payload to 101
  403. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media.c:9119 sofia/default/98095494994 Set rtp dtmf delay to 40
  404. 2022-05-18 13:43:14.141040 97.23% [NOTICE] sofia.c:8683 Channel [sofia/default/98095494994] has been answered
  405. EXECUTE [depth=1] sofia/default/98095494994 record_session(/usr/local/freeswitch/recordings/d77cdf1c-3270-492d-a8b1-39708f8fa0fb.wav)
  406. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
  407. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/98095494994
  408. 2022-05-18 13:43:14.141040 97.23% [DEBUG] switch_channel.c:3950 (sofia/default/98095494994) Callstate Change DOWN -> ACTIVE
  409. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/2276576399@astppqa603.astppbilling.org:5060] 142.132.184.134 port 25478 -> 117.247.91.162 port 8000 codec: 8 ms: 20
  410. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  411. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_core_media.c:9089 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Set 2833 dtmf send payload to 101
  412. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_core_media.c:9096 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Set 2833 dtmf receive payload to 101
  413. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_core_media.c:9119 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Set rtp dtmf delay to 40
  414. 2022-05-18 13:43:14.161039 97.23% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/2276576399@astppqa603.astppbilling.org:5060:
  415. v=0
  416. o=FreeSWITCH 1652855916 1652855917 IN IP4 142.132.184.134
  417. s=FreeSWITCH
  418. c=IN IP4 142.132.184.134
  419. t=0 0
  420. m=audio 25478 RTP/AVP 8 101
  421. a=rtpmap:8 PCMA/8000
  422. a=rtpmap:101 telephone-event/8000
  423. a=fmtp:101 0-15
  424. a=ptime:20
  425. a=sendrecv
  426.  
  427. 2022-05-18 13:43:14.161039 97.23% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/2276576399@astppqa603.astppbilling.org:5060] has been answered
  428. 2022-05-18 13:43:14.161039 97.23% [DEBUG] sofia.c:7499 Channel sofia/default/2276576399@astppqa603.astppbilling.org:5060 entering state [completed][200]
  429. EXECUTE [depth=0] sofia/default/2276576399@astppqa603.astppbilling.org:5060 record_session(/usr/local/freeswitch/recordings/d77cdf1c-3270-492d-a8b1-39708f8fa0fb.wav)
  430. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
  431. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/2276576399@astppqa603.astppbilling.org:5060
  432. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_channel.c:3950 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Callstate Change RINGING -> ACTIVE
  433. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/98095494994] Peer UUID: fdf28577-90e0-4dec-ac3e-3adf9f086c20
  434. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/98095494994) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  435. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/98095494994) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 42294)
  436. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_core_state_machine.c:650 (sofia/default/98095494994) State EXCHANGE_MEDIA
  437. 2022-05-18 13:43:14.161039 97.23% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  438. 2022-05-18 13:43:14.161039 97.23% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
  439. 2022-05-18 13:43:14.181034 97.23% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
  440. 2022-05-18 13:43:14.341037 97.10% [DEBUG] switch_ivr_bridge.c:655 sofia/default/98095494994 Bridge execute app sched_hangup(+6000 normal_clearing)
  441. 2022-05-18 13:43:14.361035 97.10% [DEBUG] switch_ivr.c:632 sofia/default/98095494994 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
  442. EXECUTE [depth=0] sofia/default/98095494994 sched_hangup(+6000 normal_clearing)
  443. 2022-05-18 13:43:14.361035 97.10% [DEBUG] switch_scheduler.c:263 Added task 58 switch_ivr_schedule_hangup (fdf28577-90e0-4dec-ac3e-3adf9f086c20) to run at 1652887394
  444. 2022-05-18 13:43:14.421038 97.10% [DEBUG] sofia.c:7499 Channel sofia/default/2276576399@astppqa603.astppbilling.org:5060 entering state [ready][200]
  445. 2022-05-18 13:43:14.681042 97.10% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  446. 2022-05-18 13:43:14.681042 97.10% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
  447. 2022-05-18 13:43:24.281039 97.17% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [213416@astppqa601.astppbilling.org] from ip 117.247.91.162
  448. 2022-05-18 13:43:24.621037 97.17% [WARNING] sofia_reg.c:3223 Can't find user [213416@astppqa601.astppbilling.org] from 117.247.91.162
  449. You must define a domain called 'astppqa601.astppbilling.org' in your directory and add a user with the id="213416" attribute
  450. and you must configure your device to use the proper domain in its authentication credentials.
  451. 2022-05-18 13:43:28.841038 96.83% [NOTICE] switch_channel.c:1123 New Channel sofia/default/'or''='@142.132.184.134 [896b70f4-0c36-47ed-812e-f48adbdf1999]
  452. 2022-05-18 13:43:28.841038 96.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/'or''='@142.132.184.134) Running State Change CS_NEW (Cur 3 Tot 42295)
  453. 2022-05-18 13:43:28.841038 96.83% [INFO] sofia.c:10462 sofia/default/'or''='@142.132.184.134 receiving invite from 45.61.187.10:63280 version: 1.10.7 -release 64bit call-id: 343103107-385941702-576217745
  454. 2022-05-18 13:43:28.841038 96.83% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 45.61.187.10:0.
  455. 2022-05-18 13:43:28.841038 96.83% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [+442080890168@142.132.184.134] from ip 45.61.187.10
  456. 2022-05-18 13:43:28.841038 96.83% [DEBUG] switch_core_state_machine.c:600 (sofia/default/'or''='@142.132.184.134) State NEW
  457. 2022-05-18 13:43:28.841038 96.83% [DEBUG] sofia.c:2419 detaching session 896b70f4-0c36-47ed-812e-f48adbdf1999
  458. 2022-05-18 13:43:28.961036 96.83% [DEBUG] sofia.c:2532 Re-attaching to session 896b70f4-0c36-47ed-812e-f48adbdf1999
  459. 2022-05-18 13:43:28.961036 96.83% [INFO] sofia.c:10462 sofia/default/'or''='@142.132.184.134 receiving invite from 45.61.187.10:63280 version: 1.10.7 -release 64bit call-id: 343103107-385941702-576217745
  460. 2022-05-18 13:43:28.961036 96.83% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 45.61.187.10:0.
  461. 2022-05-18 13:43:28.961036 96.83% [WARNING] sofia_reg.c:3223 Can't find user ['or''='@142.132.184.134] from 45.61.187.10
  462. You must define a domain called '142.132.184.134' in your directory and add a user with the id="'or''='" attribute
  463. and you must configure your device to use the proper domain in its authentication credentials.
  464. 2022-05-18 13:43:28.961036 96.83% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [+442080890168@142.132.184.134] from ip 45.61.187.10
  465. 2022-05-18 13:43:28.961036 96.83% [NOTICE] sofia.c:2417 Hangup sofia/default/'or''='@142.132.184.134 [CS_NEW] [CALL_REJECTED]
  466. 2022-05-18 13:43:28.981039 96.83% [DEBUG] sofia.c:1527 Channel is already hungup.
  467. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/'or''='@142.132.184.134) Running State Change CS_HANGUP (Cur 3 Tot 42295)
  468. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:844 (sofia/default/'or''='@142.132.184.134) Callstate Change DOWN -> HANGUP
  469. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:846 (sofia/default/'or''='@142.132.184.134) State HANGUP
  470. 2022-05-18 13:43:28.981039 96.83% [DEBUG] mod_sofia.c:468 Channel sofia/default/'or''='@142.132.184.134 hanging up, cause: CALL_REJECTED
  471. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:59 sofia/default/'or''='@142.132.184.134 Standard HANGUP, cause: CALL_REJECTED
  472. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:846 (sofia/default/'or''='@142.132.184.134) State HANGUP going to sleep
  473. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:616 (sofia/default/'or''='@142.132.184.134) State Change CS_HANGUP -> CS_REPORTING
  474. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/'or''='@142.132.184.134) Running State Change CS_REPORTING (Cur 3 Tot 42295)
  475. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:932 (sofia/default/'or''='@142.132.184.134) State REPORTING
  476. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:168 sofia/default/'or''='@142.132.184.134 Standard REPORTING, cause: CALL_REJECTED
  477. 2022-05-18 13:43:28.981039 96.83% [DEBUG] switch_core_state_machine.c:932 (sofia/default/'or''='@142.132.184.134) State REPORTING going to sleep
  478. 2022-05-18 13:43:29.001040 96.83% [DEBUG] switch_core_state_machine.c:607 (sofia/default/'or''='@142.132.184.134) State Change CS_REPORTING -> CS_DESTROY
  479. 2022-05-18 13:43:29.001040 96.83% [DEBUG] switch_core_session.c:1753 Session 42295 (sofia/default/'or''='@142.132.184.134) Locked, Waiting on external entities
  480. 2022-05-18 13:43:29.001040 96.83% [NOTICE] switch_core_session.c:1771 Session 42295 (sofia/default/'or''='@142.132.184.134) Ended
  481. 2022-05-18 13:43:29.001040 96.83% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/'or''='@142.132.184.134 [CS_DESTROY]
  482. 2022-05-18 13:43:29.001040 96.83% [DEBUG] switch_core_state_machine.c:735 (sofia/default/'or''='@142.132.184.134) Running State Change CS_DESTROY (Cur 2 Tot 42295)
  483. 2022-05-18 13:43:29.001040 96.83% [DEBUG] switch_core_state_machine.c:745 (sofia/default/'or''='@142.132.184.134) State DESTROY
  484. 2022-05-18 13:43:29.001040 96.83% [DEBUG] mod_sofia.c:379 sofia/default/'or''='@142.132.184.134 SOFIA DESTROY
  485. 2022-05-18 13:43:29.001040 96.83% [DEBUG] switch_core_state_machine.c:175 sofia/default/'or''='@142.132.184.134 Standard DESTROY
  486. 2022-05-18 13:43:29.001040 96.83% [DEBUG] switch_core_state_machine.c:745 (sofia/default/'or''='@142.132.184.134) State DESTROY going to sleep
  487. 2022-05-18 13:43:32.621037 97.27% [NOTICE] sofia_reg.c:459 Registering Prority
  488. 2022-05-18 13:43:32.801041 97.27% [ERR] sofia_reg.c:2677 Prority Failed Registration with status Forbidden [403]. failure #2
  489. 2022-05-18 13:43:33.621042 97.93% [WARNING] sofia_reg.c:516 Prority Failed Registration [403], setting retry to 60 seconds.
  490. 2022-05-18 13:43:54.121041 97.07% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9095175867@astppqa601.astppbilling.org [22b3e90f-c297-463f-a224-73b0af3363dd]
  491. 2022-05-18 13:43:54.121041 97.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9095175867@astppqa601.astppbilling.org) Running State Change CS_NEW (Cur 3 Tot 42296)
  492. 2022-05-18 13:43:54.121041 97.07% [INFO] sofia.c:10462 sofia/default/9095175867@astppqa601.astppbilling.org receiving invite from 117.247.91.162:36510 version: 1.10.7 -release 64bit call-id: YmYzNTMzYjBmYWJmOGI3NjZjYWUxMDNiZTg3YjQ4MWM.
  493. 2022-05-18 13:43:54.121041 97.07% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 117.247.91.162:0.
  494. 2022-05-18 13:43:54.121041 97.07% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [9700056226@astppqa601.astppbilling.org] from ip 117.247.91.162
  495. 2022-05-18 13:43:54.121041 97.07% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9095175867@astppqa601.astppbilling.org) State NEW
  496. 2022-05-18 13:43:54.121041 97.07% [DEBUG] sofia.c:2419 detaching session 22b3e90f-c297-463f-a224-73b0af3363dd
  497. 2022-05-18 13:43:54.601041 96.97% [DEBUG] sofia.c:2532 Re-attaching to session 22b3e90f-c297-463f-a224-73b0af3363dd
  498. 2022-05-18 13:43:54.621071 96.97% [INFO] sofia.c:10462 sofia/default/9095175867@astppqa601.astppbilling.org receiving invite from 117.247.91.162:36510 version: 1.10.7 -release 64bit call-id: YmYzNTMzYjBmYWJmOGI3NjZjYWUxMDNiZTg3YjQ4MWM.
  499. 2022-05-18 13:43:54.621071 96.97% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 117.247.91.162:0.
  500. 2022-05-18 13:43:54.621071 96.97% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
  501. 2022-05-18 13:43:54.621071 96.97% [DEBUG] sofia.c:7499 Channel sofia/default/9095175867@astppqa601.astppbilling.org entering state [received][100]
  502. 2022-05-18 13:43:54.621071 96.97% [DEBUG] sofia.c:7509 Remote SDP:
  503. v=0
  504. o=Z 0 0 IN IP4 117.247.91.162
  505. s=Z
  506. c=IN IP4 117.247.91.162
  507. t=0 0
  508. m=audio 8002 RTP/AVP 3 110 8 0 98 101
  509. a=rtpmap:110 speex/8000
  510. a=rtpmap:98 iLBC/8000
  511. a=fmtp:98 mode=20
  512. a=rtpmap:101 telephone-event/8000
  513. a=fmtp:101 0-15
  514.  
  515. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  516. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  517. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  518. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  519. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  520. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  521. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  522. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  523. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  524. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  525. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  526. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  527. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  528. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9095175867@astppqa601.astppbilling.org PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  529. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_codec.c:111 sofia/default/9095175867@astppqa601.astppbilling.org Original read codec set to PCMA:8
  530. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  531. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_media.c:5973 sofia/default/9095175867@astppqa601.astppbilling.org Set 2833 dtmf send payload to 101 recv payload to 101
  532. 2022-05-18 13:43:54.621071 96.97% [DEBUG] sofia.c:7933 (sofia/default/9095175867@astppqa601.astppbilling.org) State Change CS_NEW -> CS_INIT
  533. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9095175867@astppqa601.astppbilling.org) Running State Change CS_INIT (Cur 3 Tot 42296)
  534. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9095175867@astppqa601.astppbilling.org) State INIT
  535. 2022-05-18 13:43:54.621071 96.97% [DEBUG] mod_sofia.c:97 sofia/default/9095175867@astppqa601.astppbilling.org SOFIA INIT
  536. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:40 sofia/default/9095175867@astppqa601.astppbilling.org Standard INIT
  537. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9095175867@astppqa601.astppbilling.org) State Change CS_INIT -> CS_ROUTING
  538. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9095175867@astppqa601.astppbilling.org) State INIT going to sleep
  539. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9095175867@astppqa601.astppbilling.org) Running State Change CS_ROUTING (Cur 3 Tot 42296)
  540. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_channel.c:2380 (sofia/default/9095175867@astppqa601.astppbilling.org) Callstate Change DOWN -> RINGING
  541. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9095175867@astppqa601.astppbilling.org) State ROUTING
  542. 2022-05-18 13:43:54.621071 96.97% [DEBUG] mod_sofia.c:158 sofia/default/9095175867@astppqa601.astppbilling.org SOFIA ROUTING
  543. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_core_state_machine.c:230 sofia/default/9095175867@astppqa601.astppbilling.org Standard ROUTING
  544. 2022-05-18 13:43:54.621071 96.97% [INFO] mod_dialplan_xml.c:639 Processing 9095175867 <9095175867>->9700056226 in context default
  545. 2022-05-18 13:43:54.621071 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  546. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  547. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')
  548. 2022-05-18 13:43:54.621071 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  549. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 9700056226
  550. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '9700056226' AND status=0 limit 1
  551. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER9095175867
  552. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  553. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT domain,accountid FROM domains WHERE domain = 'astppqa601.astppbilling.org' limit 1
  554. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL DOMAIN] Query :SELECT accountid from sip_devices where accountid = '7' and username = '9700056226' limit 1
  555. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 9095175867 / 9095175867
  556. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="9700056226" limit 1
  557. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
  558. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9095175867]
  559. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "9095175867" AND deleted = 0 limit 1
  560. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^9700056226.*' OR blocked_patterns = '^970005622.*' OR blocked_patterns = '^97000562.*' OR blocked_patterns = '^9700056.*' OR blocked_patterns = '^970005.*' OR blocked_patterns = '^97000.*' OR blocked_patterns = '^9700.*' OR blocked_patterns = '^970.*' OR blocked_patterns = '^97.*' OR blocked_patterns = '^9.*' OR blocked_patterns ='--') AND accountid = 7 limit 1
  561. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="9700056226" AND (accountid = 0 OR status = 1) LIMIT 1
  562. 2022-05-18 13:43:54.641049 96.97% [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 = '^9700056226.*' OR patterns = '^970005622.*' OR patterns = '^97000562.*' OR patterns = '^9700056.*' OR patterns = '^970005.*' OR patterns = '^97000.*' OR patterns = '^9700.*' OR patterns = '^970.*' OR patterns = '^97.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 7 ORDER BY LENGTH(PKGPTR.patterns) DESC
  563. 2022-05-18 13:43:54.641049 96.97% [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 ="9700056226" AND A.accountid = '7' limit 1
  564. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 9700056226
  565. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="9700056226" limit 1
  566. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
  567. 2022-05-18 13:43:54.641049 96.97% [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 = '9700056226' OR number = '970005622' OR number = '97000562' OR number = '9700056' OR number = '970005' OR number = '97000' OR number = '9700' OR number = '970' OR number = '97' OR number = '9' OR number ='--') OR (number = '9700056226*' OR number = '970005622*' OR number = '97000562*' OR number = '9700056*' OR number = '970005*' OR number = '97000*' OR number = '9700*' OR number = '970*' OR number = '97*' OR number = '9*' OR number ='--') OR (number = '9095175867' OR number = '909517586' OR number = '90951758' OR number = '9095175' OR number = '909517' OR number = '90951' OR number = '9095' OR number = '909' OR number = '90' OR number = '9' OR number ='--') OR (number = '9095175867*' OR number = '909517586*' OR number = '90951758*' OR number = '9095175*' OR number = '909517*' OR number = '90951*' OR number = '9095*' OR number = '909*' OR number = '90*' OR number = '9*' OR number ='--')) AND status=0 AND accountid = 0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  568. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  569. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '9700056226' OR routing_prefix = '970005622' OR routing_prefix = '97000562' OR routing_prefix = '9700056' OR routing_prefix = '970005' OR routing_prefix = '97000' OR routing_prefix = '9700' OR routing_prefix = '970' OR routing_prefix = '97' OR routing_prefix = '9' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  570. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  571. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 7
  572. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9095175867
  573. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 475.55
  574. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  575. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 5
  576. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  577. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  578. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
  579. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
  580. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
  581. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^9700056226.*' OR pattern = '^970005622.*' OR pattern = '^97000562.*' OR pattern = '^9700056.*' OR pattern = '^970005.*' OR pattern = '^97000.*' OR pattern = '^9700.*' OR pattern = '^970.*' OR pattern = '^97.*' OR pattern = '^9.*' OR pattern ='--') AND (caller_id_pattern = '^9095175867.*' OR caller_id_pattern = '^909517586.*' OR caller_id_pattern = '^90951758.*' OR caller_id_pattern = '^9095175.*' OR caller_id_pattern = '^909517.*' OR caller_id_pattern = '^90951.*' OR caller_id_pattern = '^9095.*' OR caller_id_pattern = '^909.*' OR caller_id_pattern = '^90.*' OR caller_id_pattern = '^9.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 5 OR accountid=7)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  582. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
  583. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  584. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 4
  585. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  586. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  587. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 1.20000
  588. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Alpesh
  589. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
  590. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 7
  591. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
  592. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  593. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  594. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Shadow_billing_rates]SHADOWBILLING:0|ID:4|CODE:^97.*|DESTINATION:Alpesh|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:1.20000|INC:60|INITIALBLOCK:15|RATEGROUP:5|MARKUP:0|CI:0|ACCID:7!!!
  595. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your475.55 balance Accountid 7 !!!
  596. 2022-05-18 13:43:54.641049 96.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
  597. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
  598. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
  599. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=5 ORDER by id asc
  600. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^9700056226.*' OR pattern = '^970005622.*' OR pattern = '^97000562.*' OR pattern = '^9700056.*' OR pattern = '^970005.*' OR pattern = '^97000.*' OR pattern = '^9700.*' OR pattern = '^970.*' OR pattern = '^97.*' OR pattern = '^9.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (4,1,3) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by FIELD(TR.trunk_id,4,1,3),LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC,TR.effective_date DESC
  601. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  602. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 11
  603. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^97.*
  604. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : Priority_Trunk
  605. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  606. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  607. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^97.*
  608. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
  609. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
  610. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 4
  611. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : Prority
  612. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
  613. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
  614. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
  615. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  616. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
  617. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  618. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 2
  619. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^97.*
  620. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : Baji
  621. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  622. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  623. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^97.*
  624. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
  625. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
  626. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
  627. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : YourProvider
  628. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
  629. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway : failoverQA440
  630. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
  631. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  632. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
  633. 2022-05-18 13:43:54.641049 96.97% [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 = 7 AND status=0 LIMIT 1
  634. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
  635. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  636. 2022-05-18 13:43:54.641049 96.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  637. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
  638. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 3) AND status=0 limit 1
  639. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 4) AND status=0 limit 1
  640. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [sip_codec_for_outbound] Query :SELECT sip_devices.codec as sip_codec  FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="9095175867" limit 1
  641. 2022-05-18 13:43:54.641049 96.97% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="9700056226" LIMIT 1
  642. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  643. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
  644. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 3) AND status=0 limit 1
  645. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 1) AND status=0 limit 1
  646. 2022-05-18 13:43:54.641049 96.97% [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 FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="9700056226" LIMIT 1
  647. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  648. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
  649. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  650. <document type="freeswitch/xml">
  651. <section name="dialplan" description="ASTPP Dialplan">
  652. <context name="default">
  653. <extension name="9700056226">
  654. <condition field="destination_number" expression="9700056226">
  655. <action application="set" data="effective_destination_number=9700056226"/>
  656. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  657. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  658. <action application="set" data="notify_flag=0"/>
  659. <action application="set" data="notify_credit_limit=5.00000"/>
  660. <action application="set" data="type=0"/>
  661. <action application="set" data="callstart=2022-05-18 13:43:54"/>
  662. <action application="set" data="hangup_after_bridge=true"/>
  663. <action application="set" data="continue_on_fail=TRUE"/>
  664. <action application="set" data="account_id=7"/>
  665. <action application="set" data="parent_id=0"/>
  666. <action application="set" data="entity_id=0"/>
  667. <action application="set" data="call_processed=internal"/>
  668. <action application="set" data="call_direction=outbound"/>
  669. <action application="set" data="accountname=default"/>
  670. <action application="set" data="origination_rates=SHADOWBILLING:0|ID:4|CODE:^97.*|DESTINATION:Alpesh|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:1.20000|INC:60|INITIALBLOCK:15|RATEGROUP:5|MARKUP:0|CI:0|ACCID:7"/>
  671. <action application="set" data="original_caller_id_name=9095175867"/>
  672. <action application="set" data="original_caller_id_number=9095175867"/>
  673.  
  674. <action application="export" data="is_recording=1"/>
  675. <action application="export" data="media_bug_answer_req=true"/>
  676. <action application="export" data="record_sample_rate=8000"/>
  677. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
  678. <action application="set" data="effective_caller_id_name=9095175867"/>
  679. <action application="set" data="effective_caller_id_number=9095175867"/>
  680. <action application="set" data="calltype=STANDARD"/>
  681. <action application="set" data="termination_rates=ID:11|CODE:^97.*|DESTINATION:Priority_Trunk|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:4|PROVIDER:3"/>
  682. <action application="set" data="trunk_id=4"/>
  683. <action application="set" data="provider_id=3"/>
  684. <action application="set" data="rate_flag=2"/>
  685. <action application="set" data="force_trunk_flag=0"/>
  686. <action application="export" data="presence_data=trunk_id=4"/>
  687. <action application="set" data="intcall=0"/>
  688. <action application="export" data="presence_id=9700056226"/>
  689. <action application="export" data="presence_data=x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||Priority // ^97.* // Priority_Trunk // 0.00000 // trunk_id=4|||STD|||117.247.91.162"/>
  690. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=0]sofia/gateway/Prority/9700056226"/>
  691. <action application="set" data="effective_caller_id_name=9095175867"/>
  692. <action application="set" data="effective_caller_id_number=9095175867"/>
  693. <action application="set" data="calltype=STANDARD"/>
  694. <action application="set" data="termination_rates=ID:2|CODE:^97.*|DESTINATION:Baji|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:0|INITIALBLOCK:0|TRUNK:1|PROVIDER:3"/>
  695. <action application="set" data="trunk_id=1"/>
  696. <action application="set" data="provider_id=3"/>
  697. <action application="set" data="rate_flag=2"/>
  698. <action application="set" data="force_trunk_flag=0"/>
  699. <action application="export" data="presence_data=trunk_id=1"/>
  700. <action application="set" data="intcall=0"/>
  701. <action application="export" data="presence_id=9700056226"/>
  702. <action application="export" data="presence_data=x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162"/>
  703. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9700056226"/>
  704. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA440/9700056226"/>
  705. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA770/9700056226"/>
  706. </condition>
  707. </extension>
  708. </context>
  709. </section>
  710. </document>
  711. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org parsing [default->9700056226] continue=false
  712. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Regex (PASS) [9700056226] destination_number(9700056226) =~ /9700056226/ break=on-false
  713. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(effective_destination_number=9700056226)
  714. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(bridge_pre_execute_bleg_app=sched_hangup)
  715. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  716. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(notify_flag=0)
  717. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(notify_credit_limit=5.00000)
  718. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(type=0)
  719. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(callstart=2022-05-18 13:43:54)
  720. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(hangup_after_bridge=true)
  721. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(continue_on_fail=TRUE)
  722. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(account_id=7)
  723. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(parent_id=0)
  724. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(entity_id=0)
  725. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(call_processed=internal)
  726. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(call_direction=outbound)
  727. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(accountname=default)
  728. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(origination_rates=SHADOWBILLING:0|ID:4|CODE:^97.*|DESTINATION:Alpesh|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:1.20000|INC:60|INITIALBLOCK:15|RATEGROUP:5|MARKUP:0|CI:0|ACCID:7)
  729. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(original_caller_id_name=9095175867)
  730. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(original_caller_id_number=9095175867)
  731. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(is_recording=1)
  732. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(media_bug_answer_req=true)
  733. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(record_sample_rate=8000)
  734. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
  735. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(effective_caller_id_name=9095175867)
  736. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(effective_caller_id_number=9095175867)
  737. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(calltype=STANDARD)
  738. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(termination_rates=ID:11|CODE:^97.*|DESTINATION:Priority_Trunk|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:4|PROVIDER:3)
  739. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(trunk_id=4)
  740. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(provider_id=3)
  741. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(rate_flag=2)
  742. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(force_trunk_flag=0)
  743. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(presence_data=trunk_id=4)
  744. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(intcall=0)
  745. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(presence_id=9700056226)
  746. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(presence_data=x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||Priority // ^97.* // Priority_Trunk // 0.00000 // trunk_id=4|||STD|||117.247.91.162)
  747. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action bridge({sip_cid_type=none}[leg_timeout=0]sofia/gateway/Prority/9700056226)
  748. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(effective_caller_id_name=9095175867)
  749. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(effective_caller_id_number=9095175867)
  750. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(calltype=STANDARD)
  751. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(termination_rates=ID:2|CODE:^97.*|DESTINATION:Baji|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:0|INITIALBLOCK:0|TRUNK:1|PROVIDER:3)
  752. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(trunk_id=1)
  753. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(provider_id=3)
  754. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(rate_flag=2)
  755. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(force_trunk_flag=0)
  756. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(presence_data=trunk_id=1)
  757. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action set(intcall=0)
  758. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(presence_id=9700056226)
  759. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action export(presence_data=x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162)
  760. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9700056226)
  761. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA440/9700056226)
  762. Dialplan: sofia/default/9095175867@astppqa601.astppbilling.org Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/failoverQA770/9700056226)
  763. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9095175867@astppqa601.astppbilling.org) State Change CS_ROUTING -> CS_EXECUTE
  764. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9095175867@astppqa601.astppbilling.org) State ROUTING going to sleep
  765. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9095175867@astppqa601.astppbilling.org) Running State Change CS_EXECUTE (Cur 3 Tot 42296)
  766. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9095175867@astppqa601.astppbilling.org) State EXECUTE
  767. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_sofia.c:213 sofia/default/9095175867@astppqa601.astppbilling.org SOFIA EXECUTE
  768. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_core_state_machine.c:323 sofia/default/9095175867@astppqa601.astppbilling.org Standard EXECUTE
  769. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(effective_destination_number=9700056226)
  770. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [effective_destination_number]=[9700056226]
  771. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(bridge_pre_execute_bleg_app=sched_hangup)
  772. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [bridge_pre_execute_bleg_app]=[sched_hangup]
  773. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  774. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  775. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(notify_flag=0)
  776. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [notify_flag]=[0]
  777. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(notify_credit_limit=5.00000)
  778. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [notify_credit_limit]=[5.00000]
  779. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(type=0)
  780. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [type]=[0]
  781. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(callstart=2022-05-18 13:43:54)
  782. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [callstart]=[2022-05-18 13:43:54]
  783. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(hangup_after_bridge=true)
  784. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [hangup_after_bridge]=[true]
  785. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(continue_on_fail=TRUE)
  786. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [continue_on_fail]=[TRUE]
  787. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(account_id=7)
  788. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [account_id]=[7]
  789. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(parent_id=0)
  790. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [parent_id]=[0]
  791. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(entity_id=0)
  792. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [entity_id]=[0]
  793. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(call_processed=internal)
  794. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [call_processed]=[internal]
  795. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(call_direction=outbound)
  796. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [call_direction]=[outbound]
  797. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(accountname=default)
  798. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [accountname]=[default]
  799. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(origination_rates=SHADOWBILLING:0|ID:4|CODE:^97.*|DESTINATION:Alpesh|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:1.20000|INC:60|INITIALBLOCK:15|RATEGROUP:5|MARKUP:0|CI:0|ACCID:7)
  800. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [origination_rates]=[SHADOWBILLING:0|ID:4|CODE:^97.*|DESTINATION:Alpesh|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:1.20000|INC:60|INITIALBLOCK:15|RATEGROUP:5|MARKUP:0|CI:0|ACCID:7]
  801. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(original_caller_id_name=9095175867)
  802. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [original_caller_id_name]=[9095175867]
  803. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(original_caller_id_number=9095175867)
  804. 2022-05-18 13:43:54.641049 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [original_caller_id_number]=[9095175867]
  805. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(is_recording=1)
  806. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [is_recording]=[1]
  807. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(media_bug_answer_req=true)
  808. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [media_bug_answer_req]=[true]
  809. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(record_sample_rate=8000)
  810. 2022-05-18 13:43:54.641049 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [record_sample_rate]=[8000]
  811. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(execute_on_answer=record_session /usr/local/freeswitch/recordings/22b3e90f-c297-463f-a224-73b0af3363dd.wav)
  812. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/22b3e90f-c297-463f-a224-73b0af3363dd.wav]
  813. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(effective_caller_id_name=9095175867)
  814. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [effective_caller_id_name]=[9095175867]
  815. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(effective_caller_id_number=9095175867)
  816. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [effective_caller_id_number]=[9095175867]
  817. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(calltype=STANDARD)
  818. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [calltype]=[STANDARD]
  819. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(termination_rates=ID:11|CODE:^97.*|DESTINATION:Priority_Trunk|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:4|PROVIDER:3)
  820. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [termination_rates]=[ID:11|CODE:^97.*|DESTINATION:Priority_Trunk|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:4|PROVIDER:3]
  821. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(trunk_id=4)
  822. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [trunk_id]=[4]
  823. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(provider_id=3)
  824. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [provider_id]=[3]
  825. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(rate_flag=2)
  826. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [rate_flag]=[2]
  827. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(force_trunk_flag=0)
  828. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [force_trunk_flag]=[0]
  829. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(presence_data=trunk_id=4)
  830. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=4]
  831. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(intcall=0)
  832. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [intcall]=[0]
  833. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(presence_id=9700056226)
  834. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[9700056226]
  835. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(presence_data=x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||Priority // ^97.* // Priority_Trunk // 0.00000 // trunk_id=4|||STD|||117.247.91.162)
  836. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||Priority // ^97.* // Priority_Trunk // 0.00000 // trunk_id=4|||STD|||117.247.91.162]
  837. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org bridge({sip_cid_type=none}[leg_timeout=0]sofia/gateway/Prority/9700056226)
  838. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [is_recording]=[1] to event
  839. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
  840. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [record_sample_rate]=[8000] to event
  841. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/22b3e90f-c297-463f-a224-73b0af3363dd.wav] to event
  842. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||Priority // ^97.* // Priority_Trunk // 0.00000 // trunk_id=4|||STD|||117.247.91.162] to event
  843. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_id]=[9700056226] to event
  844. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||Priority // ^97.* // Priority_Trunk // 0.00000 // trunk_id=4|||STD|||117.247.91.162] to event
  845. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  846. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  847. 2022-05-18 13:43:54.661032 96.97% [ERR] mod_sofia.c:4813 Gateway 'Prority' is down!
  848. 2022-05-18 13:43:54.661032 96.97% [NOTICE] mod_sofia.c:5221 Close Channel N/A [CS_NEW]
  849. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:735 () Running State Change CS_DESTROY (Cur 3 Tot 42297)
  850. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:745 (N/A) State DESTROY
  851. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_sofia.c:379 N/A SOFIA DESTROY
  852. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:745 (N/A) State DESTROY going to sleep
  853. 2022-05-18 13:43:54.661032 96.97% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [sofia] cause: [GATEWAY_DOWN]
  854. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 609 [GATEWAY_DOWN]
  855. 2022-05-18 13:43:54.661032 96.97% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: GATEWAY_DOWN
  856. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(effective_caller_id_name=9095175867)
  857. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [effective_caller_id_name]=[9095175867]
  858. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(effective_caller_id_number=9095175867)
  859. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [effective_caller_id_number]=[9095175867]
  860. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(calltype=STANDARD)
  861. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [calltype]=[STANDARD]
  862. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(termination_rates=ID:2|CODE:^97.*|DESTINATION:Baji|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:0|INITIALBLOCK:0|TRUNK:1|PROVIDER:3)
  863. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [termination_rates]=[ID:2|CODE:^97.*|DESTINATION:Baji|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:0|INITIALBLOCK:0|TRUNK:1|PROVIDER:3]
  864. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(trunk_id=1)
  865. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [trunk_id]=[1]
  866. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(provider_id=3)
  867. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [provider_id]=[3]
  868. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(rate_flag=2)
  869. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [rate_flag]=[2]
  870. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(force_trunk_flag=0)
  871. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [force_trunk_flag]=[0]
  872. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(presence_data=trunk_id=1)
  873. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
  874. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org set(intcall=0)
  875. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9095175867@astppqa601.astppbilling.org [intcall]=[0]
  876. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(presence_id=9700056226)
  877. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[9700056226]
  878. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org export(presence_data=x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162)
  879. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162]
  880. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9700056226)
  881. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [is_recording]=[1] to event
  882. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
  883. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [record_sample_rate]=[8000] to event
  884. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/22b3e90f-c297-463f-a224-73b0af3363dd.wav] to event
  885. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162] to event
  886. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_id]=[9700056226] to event
  887. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162] to event
  888. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162] to event
  889. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_id]=[9700056226] to event
  890. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_channel.c:1269 sofia/default/9095175867@astppqa601.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Alpesh(9095175867)|||^97.* // Alpesh // 1.20000 |||YourTrunk // ^97.* // Baji // 0.10000 // trunk_id=1|||STD|||117.247.91.162] to event
  891. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  892. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  893. 2022-05-18 13:43:54.661032 96.97% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9700056226 [a46f3c68-91e6-46ac-a967-528bc45c8e85]
  894. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_sofia.c:5121 (sofia/default/9700056226) State Change CS_NEW -> CS_INIT
  895. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_ivr_originate.c:3151 sofia/default/9700056226 Setting leg timeout to 30
  896. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9700056226) Running State Change CS_INIT (Cur 4 Tot 42298)
  897. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9700056226) State INIT
  898. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_sofia.c:97 sofia/default/9700056226 SOFIA INIT
  899. 2022-05-18 13:43:54.661032 96.97% [INFO] sofia_glue.c:1651 sofia/default/9700056226 sending invite call-id: (null)
  900. 2022-05-18 13:43:54.661032 96.97% [DEBUG] sofia_glue.c:1654 sofia/default/9700056226 sending invite version: 1.10.7 -release 64bit
  901. Local SDP:
  902. v=0
  903. o=FreeSWITCH 1652863618 1652863619 IN IP4 142.132.184.134
  904. s=FreeSWITCH
  905. c=IN IP4 142.132.184.134
  906. t=0 0
  907. m=audio 17816 RTP/AVP 8 18 101
  908. a=rtpmap:8 PCMA/8000
  909. a=rtpmap:18 G729/8000
  910. a=rtpmap:101 telephone-event/8000
  911. a=fmtp:101 0-15
  912. a=ptime:20
  913. a=sendrecv
  914.  
  915. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:40 sofia/default/9700056226 Standard INIT
  916. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9700056226) State Change CS_INIT -> CS_ROUTING
  917. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9700056226) State INIT going to sleep
  918. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9700056226) Running State Change CS_ROUTING (Cur 4 Tot 42298)
  919. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9700056226) State ROUTING
  920. 2022-05-18 13:43:54.661032 96.97% [DEBUG] mod_sofia.c:158 sofia/default/9700056226 SOFIA ROUTING
  921. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_ivr_originate.c:67 (sofia/default/9700056226) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  922. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9700056226) State ROUTING going to sleep
  923. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9700056226) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 42298)
  924. 2022-05-18 13:43:54.661032 96.97% [DEBUG] sofia.c:7499 Channel sofia/default/9700056226 entering state [calling][0]
  925. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9700056226) State CONSUME_MEDIA
  926. 2022-05-18 13:43:54.661032 96.97% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9700056226) State CONSUME_MEDIA going to sleep
  927. 2022-05-18 13:43:54.741034 96.97% [DEBUG] sofia.c:7499 Channel sofia/default/9700056226 entering state [completing][200]
  928. 2022-05-18 13:43:54.741034 96.97% [DEBUG] sofia.c:7509 Remote SDP:
  929. v=0
  930. o=root 907622830 907622830 IN IP4 159.203.131.36
  931. s=Asterisk PBX 16.4.0
  932. c=IN IP4 159.203.131.36
  933. t=0 0
  934. m=audio 19812 RTP/AVP 8 101
  935. a=rtpmap:8 PCMA/8000
  936. a=rtpmap:101 telephone-event/8000
  937. a=fmtp:101 0-16
  938. a=maxptime:150
  939.  
  940. 2022-05-18 13:43:54.741034 96.97% [DEBUG] sofia.c:7499 Channel sofia/default/9700056226 entering state [ready][200]
  941. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  942. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  943. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  944. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  945. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9700056226 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  946. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_codec.c:111 sofia/default/9700056226 Original read codec set to PCMA:8
  947. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  948. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:5973 sofia/default/9700056226 Set 2833 dtmf send payload to 101 recv payload to 101
  949. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9700056226] 142.132.184.134 port 17816 -> 159.203.131.36 port 19812 codec: 8 ms: 20
  950. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  951. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:9089 sofia/default/9700056226 Set 2833 dtmf send payload to 101
  952. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:9096 sofia/default/9700056226 Set 2833 dtmf receive payload to 101
  953. 2022-05-18 13:43:54.741034 96.97% [DEBUG] switch_core_media.c:9119 sofia/default/9700056226 Set rtp dtmf delay to 40
  954. 2022-05-18 13:43:54.741034 96.97% [NOTICE] sofia.c:8683 Channel [sofia/default/9700056226] has been answered
  955. EXECUTE [depth=1] sofia/default/9700056226 record_session(/usr/local/freeswitch/recordings/22b3e90f-c297-463f-a224-73b0af3363dd.wav)
  956. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
  957. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/9700056226
  958. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_channel.c:3950 (sofia/default/9700056226) Callstate Change DOWN -> ACTIVE
  959. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9095175867@astppqa601.astppbilling.org] 142.132.184.134 port 27696 -> 117.247.91.162 port 8002 codec: 8 ms: 20
  960. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  961. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_core_media.c:9089 sofia/default/9095175867@astppqa601.astppbilling.org Set 2833 dtmf send payload to 101
  962. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_core_media.c:9096 sofia/default/9095175867@astppqa601.astppbilling.org Set 2833 dtmf receive payload to 101
  963. 2022-05-18 13:43:54.761040 96.97% [DEBUG] switch_core_media.c:9119 sofia/default/9095175867@astppqa601.astppbilling.org Set rtp dtmf delay to 40
  964. 2022-05-18 13:43:54.761040 96.97% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/9095175867@astppqa601.astppbilling.org:
  965. v=0
  966. o=FreeSWITCH 1652853738 1652853739 IN IP4 142.132.184.134
  967. s=FreeSWITCH
  968. c=IN IP4 142.132.184.134
  969. t=0 0
  970. m=audio 27696 RTP/AVP 8 101
  971. a=rtpmap:8 PCMA/8000
  972. a=rtpmap:101 telephone-event/8000
  973. a=fmtp:101 0-15
  974. a=ptime:20
  975. a=sendrecv
  976.  
  977. 2022-05-18 13:43:54.761040 96.97% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/9095175867@astppqa601.astppbilling.org] has been answered
  978. 2022-05-18 13:43:54.761040 96.97% [DEBUG] sofia.c:7499 Channel sofia/default/9095175867@astppqa601.astppbilling.org entering state [completed][200]
  979. EXECUTE [depth=0] sofia/default/9095175867@astppqa601.astppbilling.org record_session(/usr/local/freeswitch/recordings/22b3e90f-c297-463f-a224-73b0af3363dd.wav)
  980. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
  981. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/9095175867@astppqa601.astppbilling.org
  982. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_channel.c:3950 (sofia/default/9095175867@astppqa601.astppbilling.org) Callstate Change RINGING -> ACTIVE
  983. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/9700056226] Peer UUID: a46f3c68-91e6-46ac-a967-528bc45c8e85
  984. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/9700056226) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  985. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9700056226) Running State Change CS_EXCHANGE_MEDIA (Cur 4 Tot 42298)
  986. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_core_state_machine.c:650 (sofia/default/9700056226) State EXCHANGE_MEDIA
  987. 2022-05-18 13:43:54.781035 96.97% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  988. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
  989. 2022-05-18 13:43:54.781035 96.97% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
  990. 2022-05-18 13:43:54.941036 96.97% [DEBUG] switch_ivr_bridge.c:655 sofia/default/9700056226 Bridge execute app sched_hangup(+6000 normal_clearing)
  991. 2022-05-18 13:43:54.961039 96.97% [DEBUG] switch_ivr.c:632 sofia/default/9700056226 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
  992. EXECUTE [depth=0] sofia/default/9700056226 sched_hangup(+6000 normal_clearing)
  993. 2022-05-18 13:43:54.961039 96.97% [DEBUG] switch_scheduler.c:263 Added task 59 switch_ivr_schedule_hangup (a46f3c68-91e6-46ac-a967-528bc45c8e85) to run at 1652887434
  994. 2022-05-18 13:43:55.061041 96.97% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
  995. 2022-05-18 13:43:55.061041 96.97% [DEBUG] sofia.c:7499 Channel sofia/default/9095175867@astppqa601.astppbilling.org entering state [ready][200]
  996. 2022-05-18 13:43:55.161040 96.97% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  997. 2022-05-18 13:43:55.161040 96.97% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
  998. 2022-05-18 13:43:55.281034 96.90% [INFO] switch_rtp.c:7894 Auto Changing audio port from 117.247.91.162:8002 to 117.247.91.162:8000
  999. 2022-05-18 13:44:04.141038 96.20% [NOTICE] switch_channel.c:1123 New Channel sofia/default/'or''='@142.132.184.134 [89e35902-eab6-4678-92a7-c6daa00cf4e4]
  1000. 2022-05-18 13:44:04.141038 96.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/'or''='@142.132.184.134) Running State Change CS_NEW (Cur 5 Tot 42299)
  1001. 2022-05-18 13:44:04.141038 96.20% [INFO] sofia.c:10462 sofia/default/'or''='@142.132.184.134 receiving invite from 45.61.187.10:56389 version: 1.10.7 -release 64bit call-id: 2097666300-234308641-1369425116
  1002. 2022-05-18 13:44:04.141038 96.20% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 45.61.187.10:0.
  1003. 2022-05-18 13:44:04.141038 96.20% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [900442080890168@142.132.184.134] from ip 45.61.187.10
  1004. 2022-05-18 13:44:04.141038 96.20% [DEBUG] switch_core_state_machine.c:600 (sofia/default/'or''='@142.132.184.134) State NEW
  1005. 2022-05-18 13:44:04.141038 96.20% [DEBUG] sofia.c:2419 detaching session 89e35902-eab6-4678-92a7-c6daa00cf4e4
  1006. 2022-05-18 13:44:04.261040 95.97% [DEBUG] sofia.c:2532 Re-attaching to session 89e35902-eab6-4678-92a7-c6daa00cf4e4
  1007. 2022-05-18 13:44:04.261040 95.97% [INFO] sofia.c:10462 sofia/default/'or''='@142.132.184.134 receiving invite from 45.61.187.10:56389 version: 1.10.7 -release 64bit call-id: 2097666300-234308641-1369425116
  1008. 2022-05-18 13:44:04.261040 95.97% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 45.61.187.10:0.
  1009. 2022-05-18 13:44:04.261040 95.97% [WARNING] sofia_reg.c:3223 Can't find user ['or''='@142.132.184.134] from 45.61.187.10
  1010. You must define a domain called '142.132.184.134' in your directory and add a user with the id="'or''='" attribute
  1011. and you must configure your device to use the proper domain in its authentication credentials.
  1012. 2022-05-18 13:44:04.261040 95.97% [WARNING] sofia_reg.c:1806 SIP auth failure (INVITE) on sofia profile 'default' for [900442080890168@142.132.184.134] from ip 45.61.187.10
  1013. 2022-05-18 13:44:04.261040 95.97% [NOTICE] sofia.c:2417 Hangup sofia/default/'or''='@142.132.184.134 [CS_NEW] [CALL_REJECTED]
  1014. 2022-05-18 13:44:04.281040 95.97% [DEBUG] sofia.c:1527 Channel is already hungup.
  1015. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/'or''='@142.132.184.134) Running State Change CS_HANGUP (Cur 5 Tot 42299)
  1016. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:844 (sofia/default/'or''='@142.132.184.134) Callstate Change DOWN -> HANGUP
  1017. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:846 (sofia/default/'or''='@142.132.184.134) State HANGUP
  1018. 2022-05-18 13:44:04.281040 95.97% [DEBUG] mod_sofia.c:468 Channel sofia/default/'or''='@142.132.184.134 hanging up, cause: CALL_REJECTED
  1019. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:59 sofia/default/'or''='@142.132.184.134 Standard HANGUP, cause: CALL_REJECTED
  1020. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:846 (sofia/default/'or''='@142.132.184.134) State HANGUP going to sleep
  1021. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:616 (sofia/default/'or''='@142.132.184.134) State Change CS_HANGUP -> CS_REPORTING
  1022. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/'or''='@142.132.184.134) Running State Change CS_REPORTING (Cur 5 Tot 42299)
  1023. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:932 (sofia/default/'or''='@142.132.184.134) State REPORTING
  1024. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:168 sofia/default/'or''='@142.132.184.134 Standard REPORTING, cause: CALL_REJECTED
  1025. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:932 (sofia/default/'or''='@142.132.184.134) State REPORTING going to sleep
  1026. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:607 (sofia/default/'or''='@142.132.184.134) State Change CS_REPORTING -> CS_DESTROY
  1027. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_session.c:1753 Session 42299 (sofia/default/'or''='@142.132.184.134) Locked, Waiting on external entities
  1028. 2022-05-18 13:44:04.281040 95.97% [NOTICE] switch_core_session.c:1771 Session 42299 (sofia/default/'or''='@142.132.184.134) Ended
  1029. 2022-05-18 13:44:04.281040 95.97% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/'or''='@142.132.184.134 [CS_DESTROY]
  1030. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:735 (sofia/default/'or''='@142.132.184.134) Running State Change CS_DESTROY (Cur 4 Tot 42299)
  1031. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:745 (sofia/default/'or''='@142.132.184.134) State DESTROY
  1032. 2022-05-18 13:44:04.281040 95.97% [DEBUG] mod_sofia.c:379 sofia/default/'or''='@142.132.184.134 SOFIA DESTROY
  1033. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:175 sofia/default/'or''='@142.132.184.134 Standard DESTROY
  1034. 2022-05-18 13:44:04.281040 95.97% [DEBUG] switch_core_state_machine.c:745 (sofia/default/'or''='@142.132.184.134) State DESTROY going to sleep
  1035. 2022-05-18 13:44:07.241054 96.07% [NOTICE] sofia.c:1065 Hangup sofia/default/2276576399@astppqa603.astppbilling.org:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  1036. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/2276576399@astppqa603.astppbilling.org:5060]
  1037. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_bridge.c:820 sofia/default/2276576399@astppqa603.astppbilling.org:5060 ending bridge by request from write function
  1038. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/98095494994]
  1039. 2022-05-18 13:44:07.241054 96.07% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/98095494994 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1040. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:650 (sofia/default/98095494994) State EXCHANGE_MEDIA going to sleep
  1041. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/98095494994) Running State Change CS_HANGUP (Cur 4 Tot 42299)
  1042. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_async.c:1596 Stop recording file /usr/local/freeswitch/recordings/d77cdf1c-3270-492d-a8b1-39708f8fa0fb.wav
  1043. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_bridge.c:1892 sofia/default/98095494994 skip receive message [UNBRIDGE] (channel is hungup already)
  1044. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
  1045. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_bridge.c:1895 sofia/default/2276576399@astppqa603.astppbilling.org:5060 skip receive message [UNBRIDGE] (channel is hungup already)
  1046. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_session.c:2983 sofia/default/2276576399@astppqa603.astppbilling.org:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
  1047. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:647 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State EXECUTE going to sleep
  1048. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Running State Change CS_HANGUP (Cur 4 Tot 42299)
  1049. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_async.c:1596 Stop recording file /usr/local/freeswitch/recordings/d77cdf1c-3270-492d-a8b1-39708f8fa0fb.wav
  1050. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
  1051. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_media_bug.c:1328 Removing BUG from sofia/default/98095494994
  1052. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/98095494994) Callstate Change ACTIVE -> HANGUP
  1053. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/98095494994) State HANGUP
  1054. 2022-05-18 13:44:07.241054 96.07% [DEBUG] mod_sofia.c:462 sofia/default/98095494994 Overriding SIP cause 480 with 200 from the other leg
  1055. 2022-05-18 13:44:07.241054 96.07% [DEBUG] mod_sofia.c:468 Channel sofia/default/98095494994 hanging up, cause: NORMAL_CLEARING
  1056. 2022-05-18 13:44:07.241054 96.07% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/98095494994
  1057. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/98095494994 Standard HANGUP, cause: NORMAL_CLEARING
  1058. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/98095494994) State HANGUP going to sleep
  1059. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/98095494994) State Change CS_HANGUP -> CS_REPORTING
  1060. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_media_bug.c:1328 Removing BUG from sofia/default/2276576399@astppqa603.astppbilling.org:5060
  1061. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/98095494994) Running State Change CS_REPORTING (Cur 4 Tot 42299)
  1062. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/98095494994) State REPORTING
  1063. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Callstate Change ACTIVE -> HANGUP
  1064. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State HANGUP
  1065. 2022-05-18 13:44:07.241054 96.07% [DEBUG] mod_sofia.c:468 Channel sofia/default/2276576399@astppqa603.astppbilling.org:5060 hanging up, cause: NORMAL_CLEARING
  1066. 2022-05-18 13:44:07.241054 96.07% [INFO] mod_json_cdr.c:271 Process [fdf28577-90e0-4dec-ac3e-3adf9f086c20.cdr.json]
  1067. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:59 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Standard HANGUP, cause: NORMAL_CLEARING
  1068. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State HANGUP going to sleep
  1069. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State Change CS_HANGUP -> CS_REPORTING
  1070. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Running State Change CS_REPORTING (Cur 4 Tot 42299)
  1071. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State REPORTING
  1072. 2022-05-18 13:44:07.241054 96.07% [INFO] mod_json_cdr.c:271 Process [d77cdf1c-3270-492d-a8b1-39708f8fa0fb.cdr.json]
  1073. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:168 sofia/default/98095494994 Standard REPORTING, cause: NORMAL_CLEARING
  1074. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:932 (sofia/default/98095494994) State REPORTING going to sleep
  1075. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:607 (sofia/default/98095494994) State Change CS_REPORTING -> CS_DESTROY
  1076. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_session.c:1753 Session 42294 (sofia/default/98095494994) Locked, Waiting on external entities
  1077. 2022-05-18 13:44:07.241054 96.07% [NOTICE] switch_core_session.c:1771 Session 42294 (sofia/default/98095494994) Ended
  1078. 2022-05-18 13:44:07.241054 96.07% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/98095494994 [CS_DESTROY]
  1079. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:735 (sofia/default/98095494994) Running State Change CS_DESTROY (Cur 3 Tot 42299)
  1080. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/98095494994) State DESTROY
  1081. 2022-05-18 13:44:07.241054 96.07% [DEBUG] mod_sofia.c:379 sofia/default/98095494994 SOFIA DESTROY
  1082. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:175 sofia/default/98095494994 Standard DESTROY
  1083. 2022-05-18 13:44:07.241054 96.07% [DEBUG] switch_core_state_machine.c:745 (sofia/default/98095494994) State DESTROY going to sleep
  1084. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_state_machine.c:168 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Standard REPORTING, cause: NORMAL_CLEARING
  1085. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State REPORTING going to sleep
  1086. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State Change CS_REPORTING -> CS_DESTROY
  1087. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_session.c:1753 Session 42293 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Locked, Waiting on external entities
  1088. 2022-05-18 13:44:07.261031 96.10% [NOTICE] switch_core_session.c:1771 Session 42293 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Ended
  1089. 2022-05-18 13:44:07.261031 96.10% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2276576399@astppqa603.astppbilling.org:5060 [CS_DESTROY]
  1090. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) Running State Change CS_DESTROY (Cur 2 Tot 42299)
  1091. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State DESTROY
  1092. 2022-05-18 13:44:07.261031 96.10% [DEBUG] mod_sofia.c:379 sofia/default/2276576399@astppqa603.astppbilling.org:5060 SOFIA DESTROY
  1093. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_state_machine.c:175 sofia/default/2276576399@astppqa603.astppbilling.org:5060 Standard DESTROY
  1094. 2022-05-18 13:44:07.261031 96.10% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2276576399@astppqa603.astppbilling.org:5060) State DESTROY going to sleep
  1095. 2022-05-18 13:44:07.461035 96.10% [DEBUG] switch_scheduler.c:147 Deleting task 58 switch_ivr_schedule_hangup (fdf28577-90e0-4dec-ac3e-3adf9f086c20)
  1096. freeswitch@devangASTPP>
  1097. freeswitch@devangASTPP>
  1098. freeswitch@devangASTPP> /exit
  1099.  
  1100. You have new mail in /var/mail/root
  1101. root@devangASTPP:~#

Reply to "shadow billing not applied in reseller's customer"