FreeSWITCH Pastebin

DST based caller id Localization issue force apply

From Prashant Kumar, 2 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/84a51567
Embed
Download Paste or View Raw
  1. freeswitch@devangASTPP>
  2. 2022-07-04 12:35:10.296435 94.30% [NOTICE] sofia.c:1065 Hangup sofia/default/9999998@astppqa606.astppbilling.org:5074 [CS_EXECUTE] [NORMAL_CLEARING]
  3. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/9999998@astppqa606.astppbilling.org:5074]
  4. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_ivr_bridge.c:820 sofia/default/9999998@astppqa606.astppbilling.org:5074 ending bridge by request from write function
  5. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/9110560540]
  6. 2022-07-04 12:35:10.296435 94.30% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/9110560540 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  7. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110560540) State EXCHANGE_MEDIA going to sleep
  8. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_HANGUP (Cur 2 Tot 49)
  9. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9110560540) Callstate Change ACTIVE -> HANGUP
  10. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_ivr_bridge.c:1892 sofia/default/9110560540 skip receive message [UNBRIDGE] (channel is hungup already)
  11. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_ivr_bridge.c:1895 sofia/default/9999998@astppqa606.astppbilling.org:5074 skip receive message [UNBRIDGE] (channel is hungup already)
  12. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9110560540) State HANGUP
  13. 2022-07-04 12:35:10.296435 94.30% [DEBUG] mod_sofia.c:462 sofia/default/9110560540 Overriding SIP cause 480 with 200 from the other leg
  14. 2022-07-04 12:35:10.296435 94.30% [DEBUG] mod_sofia.c:468 Channel sofia/default/9110560540 hanging up, cause: NORMAL_CLEARING
  15. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_session.c:2983 sofia/default/9999998@astppqa606.astppbilling.org:5074 skip receive message [PHONE_EVENT] (channel is hungup already)
  16. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State EXECUTE going to sleep
  17. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_HANGUP (Cur 2 Tot 49)
  18. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change ACTIVE -> HANGUP
  19. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State HANGUP
  20. 2022-07-04 12:35:10.296435 94.30% [DEBUG] mod_sofia.c:468 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 hanging up, cause: NORMAL_CLEARING
  21. 2022-07-04 12:35:10.296435 94.30% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/9110560540
  22. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:59 sofia/default/9110560540 Standard HANGUP, cause: NORMAL_CLEARING
  23. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9110560540) State HANGUP going to sleep
  24. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9110560540) State Change CS_HANGUP -> CS_REPORTING
  25. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_REPORTING (Cur 2 Tot 49)
  26. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9110560540) State REPORTING
  27. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:59 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard HANGUP, cause: NORMAL_CLEARING
  28. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State HANGUP going to sleep
  29. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_HANGUP -> CS_REPORTING
  30. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_REPORTING (Cur 2 Tot 49)
  31. 2022-07-04 12:35:10.296435 94.30% [INFO] mod_json_cdr.c:271 Process [ccc981f1-8388-4bf8-a789-aef93e7256b2.cdr.json]
  32. 2022-07-04 12:35:10.296435 94.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State REPORTING
  33. 2022-07-04 12:35:10.316416 94.30% [INFO] mod_json_cdr.c:271 Process [b307fa43-8adb-4ff5-8b49-3c3d6d755aa0.cdr.json]
  34. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_state_machine.c:168 sofia/default/9110560540 Standard REPORTING, cause: NORMAL_CLEARING
  35. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9110560540) State REPORTING going to sleep
  36. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9110560540) State Change CS_REPORTING -> CS_DESTROY
  37. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_session.c:1753 Session 49 (sofia/default/9110560540) Locked, Waiting on external entities
  38. 2022-07-04 12:35:10.316416 94.30% [NOTICE] switch_core_session.c:1771 Session 49 (sofia/default/9110560540) Ended
  39. 2022-07-04 12:35:10.316416 94.30% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9110560540 [CS_DESTROY]
  40. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9110560540) Running State Change CS_DESTROY (Cur 1 Tot 49)
  41. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9110560540) State DESTROY
  42. 2022-07-04 12:35:10.316416 94.30% [DEBUG] mod_sofia.c:379 sofia/default/9110560540 SOFIA DESTROY
  43. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_state_machine.c:175 sofia/default/9110560540 Standard DESTROY
  44. 2022-07-04 12:35:10.316416 94.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9110560540) State DESTROY going to sleep
  45. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_state_machine.c:168 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard REPORTING, cause: NORMAL_CLEARING
  46. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State REPORTING going to sleep
  47. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_REPORTING -> CS_DESTROY
  48. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_session.c:1753 Session 48 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Locked, Waiting on external entities
  49. 2022-07-04 12:35:10.336416 94.30% [NOTICE] switch_core_session.c:1771 Session 48 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Ended
  50. 2022-07-04 12:35:10.336416 94.30% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 [CS_DESTROY]
  51. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_DESTROY (Cur 0 Tot 49)
  52. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State DESTROY
  53. 2022-07-04 12:35:10.336416 94.30% [DEBUG] mod_sofia.c:379 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA DESTROY
  54. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_state_machine.c:175 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard DESTROY
  55. 2022-07-04 12:35:10.336416 94.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State DESTROY going to sleep
  56. 2022-07-04 12:35:10.636444 94.60% [DEBUG] switch_scheduler.c:147 Deleting task 20 switch_ivr_schedule_hangup (ccc981f1-8388-4bf8-a789-aef93e7256b2)
  57. freeswitch@devangASTPP>
  58. freeswitch@devangASTPP>
  59. freeswitch@devangASTPP>
  60. freeswitch@devangASTPP>
  61. freeswitch@devangASTPP>
  62. freeswitch@devangASTPP>
  63. freeswitch@devangASTPP>
  64. 2022-07-04 12:35:26.036450 94.20% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 [cc7985b4-ce60-4be0-a5c4-fcfc03bcdaa0]
  65. 2022-07-04 12:35:26.036450 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_NEW (Cur 1 Tot 50)
  66. 2022-07-04 12:35:26.036450 94.20% [INFO] sofia.c:10462 sofia/default/9999998@astppqa606.astppbilling.org:5074 receiving invite from 103.240.35.46:49504 version: 1.10.7 -release 64bit call-id: MjljNTIyMTY0NjRmYmQ4NzFjNDIwY2ZlMTU5OWI1MmQ.
  67. 2022-07-04 12:35:26.036450 94.20% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  68. 2022-07-04 12:35:26.036450 94.20% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [110560540@astppqa606.astppbilling.org] from ip 103.240.35.46
  69. 2022-07-04 12:35:26.056470 94.20% [DEBUG] sofia.c:2419 detaching session cc7985b4-ce60-4be0-a5c4-fcfc03bcdaa0
  70. 2022-07-04 12:35:26.056470 94.20% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State NEW
  71. 2022-07-04 12:35:26.376452 94.20% [DEBUG] sofia.c:2532 Re-attaching to session cc7985b4-ce60-4be0-a5c4-fcfc03bcdaa0
  72. 2022-07-04 12:35:26.396446 94.20% [INFO] sofia.c:10462 sofia/default/9999998@astppqa606.astppbilling.org:5074 receiving invite from 103.240.35.46:49504 version: 1.10.7 -release 64bit call-id: MjljNTIyMTY0NjRmYmQ4NzFjNDIwY2ZlMTU5OWI1MmQ.
  73. 2022-07-04 12:35:26.396446 94.20% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  74. 2022-07-04 12:35:26.396446 94.20% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
  75. 2022-07-04 12:35:26.396446 94.20% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [received][100]
  76. 2022-07-04 12:35:26.396446 94.20% [DEBUG] sofia.c:7509 Remote SDP:
  77. v=0
  78. o=Z 0 0 IN IP4 103.240.35.46
  79. s=Z
  80. c=IN IP4 103.240.35.46
  81. t=0 0
  82. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  83. a=rtpmap:110 speex/8000
  84. a=rtpmap:98 iLBC/8000
  85. a=fmtp:98 mode=20
  86. a=rtpmap:101 telephone-event/8000
  87. a=fmtp:101 0-15
  88.  
  89. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  90. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  91. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  92. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  93. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  94. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  95. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  96. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  97. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  98. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  99. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  100. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  101. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  102. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9999998@astppqa606.astppbilling.org:5074 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  103. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_codec.c:111 sofia/default/9999998@astppqa606.astppbilling.org:5074 Original read codec set to PCMA:8
  104. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  105. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_media.c:5973 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf send payload to 101 recv payload to 101
  106. 2022-07-04 12:35:26.396446 94.20% [DEBUG] sofia.c:7933 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_NEW -> CS_INIT
  107. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_INIT (Cur 1 Tot 50)
  108. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State INIT
  109. 2022-07-04 12:35:26.396446 94.20% [DEBUG] mod_sofia.c:97 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA INIT
  110. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:40 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard INIT
  111. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_INIT -> CS_ROUTING
  112. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State INIT going to sleep
  113. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_ROUTING (Cur 1 Tot 50)
  114. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_channel.c:2380 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change DOWN -> RINGING
  115. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State ROUTING
  116. 2022-07-04 12:35:26.396446 94.20% [DEBUG] mod_sofia.c:158 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA ROUTING
  117. 2022-07-04 12:35:26.396446 94.20% [DEBUG] switch_core_state_machine.c:230 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard ROUTING
  118. 2022-07-04 12:35:26.396446 94.20% [INFO] mod_dialplan_xml.c:639 Processing 9999998 <9999998>->110560540 in context default
  119. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
  120. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  121. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  122. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  123. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  124. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 110560540
  125. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '110560540' limit 1
  126. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
  127. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '110560540' and status=0 LIMIT 1
  128. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER9999998
  129. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  130. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '110560540' limit 1
  131. 2022-07-04 12:35:26.416427 94.20% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
  132. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : Prashant / 9992200017
  133. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 540
  134. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 110560 AND status=0 limit 1
  135. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  136. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="110560540"  AND domains.domain="astppqa606.astppbilling.org" limit 1
  137. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
  138. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9999998]
  139. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "9999998" AND deleted = 0 limit 1
  140. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^110560540.*' OR blocked_patterns = '^11056054.*' OR blocked_patterns = '^1105605.*' OR blocked_patterns = '^110560.*' OR blocked_patterns = '^11056.*' OR blocked_patterns = '^1105.*' OR blocked_patterns = '^110.*' OR blocked_patterns = '^11.*' OR blocked_patterns = '^1.*' OR blocked_patterns ='--') AND accountid = 49 limit 1
  141. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
  142. 2022-07-04 12:35:26.416427 94.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 5
  143. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 5 AND status=0 limit 1
  144. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/91/*/*".....110560540
  145. 2022-07-04 12:35:26.416427 94.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 110560540
  146. 2022-07-04 12:35:26.416427 94.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 9110560540
  147. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"111/+918871343676/*/*".....9992200017DBCLI9110560540
  148. 2022-07-04 12:35:26.416427 94.20% [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 = '^9110560540.*' OR patterns = '^911056054.*' OR patterns = '^91105605.*' OR patterns = '^9110560.*' OR patterns = '^911056.*' OR patterns = '^91105.*' OR patterns = '^9110.*' OR patterns = '^911.*' OR patterns = '^91.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 49 ORDER BY LENGTH(PKGPTR.patterns) DESC
  149. 2022-07-04 12:35:26.416427 94.20% [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 ="9110560540" AND A.accountid = '49' limit 1
  150. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 9110560540
  151. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  152. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="9110560540"  AND domains.domain="astppqa606.astppbilling.org" limit 1
  153. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
  154. 2022-07-04 12:35:26.416427 94.20% [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 = '9110560540' OR number = '911056054' OR number = '91105605' OR number = '9110560' OR number = '911056' OR number = '91105' OR number = '9110' OR number = '911' OR number = '91' OR number = '9' OR number ='--') OR (number = '9110560540*' OR number = '911056054*' OR number = '91105605*' OR number = '9110560*' OR number = '911056*' OR number = '91105*' OR number = '9110*' OR number = '911*' OR number = '91*' OR number = '9*' OR number ='--') OR (number = '9992200017' OR number = '999220001' OR number = '99922000' OR number = '9992200' OR number = '999220' OR number = '99922' OR number = '9992' OR number = '999' OR number = '99' OR number = '9' OR number ='--') OR (number = '9992200017*' OR number = '999220001*' OR number = '99922000*' OR number = '9992200*' OR number = '999220*' OR number = '99922*' OR number = '9992*' OR number = '999*' OR number = '99*' OR number = '9*' OR number ='--')) AND status=0 AND accountid = 0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  155. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  156. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '9110560540' OR routing_prefix = '911056054' OR routing_prefix = '91105605' OR routing_prefix = '9110560' OR routing_prefix = '911056' OR routing_prefix = '91105' OR routing_prefix = '9110' OR routing_prefix = '911' OR routing_prefix = '91' OR routing_prefix = '9' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  157. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  158. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 49
  159. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9999998
  160. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 39984.5
  161. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  162. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 1
  163. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  164. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  165. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  166. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  167. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
  168. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  169. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  170. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  171. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  172. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  173. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]0 GOT___SUCCESS2022-07-04 12:35:26
  174. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=pattern::^91.*
  175. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=caller_id_pattern::^.*
  176. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]0 GOT___SUCCESS2022-07-04 12:35:26
  177. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=pattern::^91.*
  178. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=caller_id_pattern::^.*
  179. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
  180. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  181. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 18
  182. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  183. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  184. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.60000
  185. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : QA
  186. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 108
  187. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 49
  188. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
  189. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  190. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  191. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your39984.5 balance Accountid 49 !!!
  192. 2022-07-04 12:35:26.416427 94.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 1000 mins!
  193. 2022-07-04 12:35:26.416427 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 1000 minutes
  194. 2022-07-04 12:35:26.416427 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
  195. 2022-07-04 12:35:26.416427 94.20% [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
  196. 2022-07-04 12:35:26.416427 94.20% [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 = '^9110560540.*' OR pattern = '^911056054.*' OR pattern = '^91105605.*' OR pattern = '^9110560.*' OR pattern = '^911056.*' OR pattern = '^91105.*' OR pattern = '^9110.*' OR pattern = '^911.*' OR pattern = '^91.*' 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
  197. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  198. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 2
  199. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^91.*
  200. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination :
  201. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  202. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  203. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^91.*
  204. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
  205. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
  206. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
  207. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : YourProvider
  208. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
  209. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
  210. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
  211. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  212. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
  213. 2022-07-04 12:35:26.436428 94.20% [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 = 49 AND status=0 LIMIT 1
  214. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 1000
  215. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  216. 2022-07-04 12:35:26.436428 94.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  217. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  218. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  219. 2022-07-04 12:35:26.436428 94.20% [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
  220. 2022-07-04 12:35:26.436428 94.20% [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
  221. 2022-07-04 12:35:26.436428 94.20% [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 ="110560540" LIMIT 1
  222. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  223. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
  224. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  225. <document type="freeswitch/xml">
  226. <section name="dialplan" description="ASTPP Dialplan">
  227. <context name="default">
  228. <extension name="9110560540">
  229. <condition field="destination_number" expression="110560540">
  230. <action application="set" data="effective_destination_number=9110560540"/>
  231. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  232. <action application="set" data="bridge_pre_execute_bleg_data=+60000 normal_clearing"/>
  233. <action application="set" data="notify_flag=0"/>
  234. <action application="set" data="notify_credit_limit=5.00000"/>
  235. <action application="set" data="type=0"/>
  236. <action application="set" data="callstart=2022-07-04 12:35:26"/>
  237. <action application="set" data="hangup_after_bridge=true"/>
  238. <action application="set" data="continue_on_fail=TRUE"/>
  239. <action application="set" data="account_id=49"/>
  240. <action application="set" data="parent_id=0"/>
  241. <action application="set" data="entity_id=0"/>
  242. <action application="set" data="call_processed=internal"/>
  243. <action application="set" data="call_direction=outbound"/>
  244. <action application="set" data="accountname=default"/>
  245. <action application="set" data="filename1=astpp.pbx.lua"/>
  246. <action application="set" data="origination_rates=ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49"/>
  247. <action application="set" data="original_caller_id_name=QA_force"/>
  248. <action application="set" data="original_caller_id_number=111"/>
  249.  
  250. <action application="set" data="effective_caller_id_name=QA_force"/>
  251. <action application="set" data="effective_caller_id_number=111"/>
  252. <action application="set" data="calltype=STANDARD"/>
  253. <action application="set" data="termination_rates=ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3"/>
  254. <action application="set" data="trunk_id=1"/>
  255. <action application="set" data="provider_id=3"/>
  256. <action application="set" data="rate_flag=0"/>
  257. <action application="set" data="quality_trunk_seq=1"/>
  258. <action application="set" data="force_trunk_flag=0"/>
  259. <action application="export" data="presence_data=trunk_id=1"/>
  260. <action application="set" data="intcall=0"/>
  261. <action application="export" data="presence_id=9110560540"/>
  262. <action application="export" data="presence_data=x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46"/>
  263. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9110560540"/>
  264. </condition>
  265. </extension>
  266. </context>
  267. </section>
  268. </document>
  269. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 parsing [default->9110560540] continue=false
  270. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Regex (PASS) [9110560540] destination_number(110560540) =~ /110560540/ break=on-false
  271. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_destination_number=9110560540)
  272. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  273. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  274. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(notify_flag=0)
  275. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(notify_credit_limit=5.00000)
  276. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(type=0)
  277. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(callstart=2022-07-04 12:35:26)
  278. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(hangup_after_bridge=true)
  279. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(continue_on_fail=TRUE)
  280. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(account_id=49)
  281. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(parent_id=0)
  282. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(entity_id=0)
  283. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(call_processed=internal)
  284. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(call_direction=outbound)
  285. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(accountname=default)
  286. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(filename1=astpp.pbx.lua)
  287. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(origination_rates=ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49)
  288. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(original_caller_id_name=QA_force)
  289. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(original_caller_id_number=111)
  290. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_caller_id_name=QA_force)
  291. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_caller_id_number=111)
  292. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(calltype=STANDARD)
  293. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(termination_rates=ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3)
  294. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(trunk_id=1)
  295. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(provider_id=3)
  296. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(rate_flag=0)
  297. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(quality_trunk_seq=1)
  298. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(force_trunk_flag=0)
  299. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_data=trunk_id=1)
  300. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(intcall=0)
  301. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_id=9110560540)
  302. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_data=x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46)
  303. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9110560540)
  304. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_ROUTING -> CS_EXECUTE
  305. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State ROUTING going to sleep
  306. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_EXECUTE (Cur 1 Tot 50)
  307. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State EXECUTE
  308. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_sofia.c:213 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA EXECUTE
  309. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:323 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard EXECUTE
  310. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_destination_number=9110560540)
  311. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_destination_number]=[9110560540]
  312. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(bridge_pre_execute_bleg_app=sched_hangup)
  313. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [bridge_pre_execute_bleg_app]=[sched_hangup]
  314. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  315. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [bridge_pre_execute_bleg_data]=[+60000 normal_clearing]
  316. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(notify_flag=0)
  317. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [notify_flag]=[0]
  318. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(notify_credit_limit=5.00000)
  319. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [notify_credit_limit]=[5.00000]
  320. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(type=0)
  321. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [type]=[0]
  322. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(callstart=2022-07-04 12:35:26)
  323. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [callstart]=[2022-07-04 12:35:26]
  324. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(hangup_after_bridge=true)
  325. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [hangup_after_bridge]=[true]
  326. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(continue_on_fail=TRUE)
  327. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [continue_on_fail]=[TRUE]
  328. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(account_id=49)
  329. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [account_id]=[49]
  330. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(parent_id=0)
  331. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [parent_id]=[0]
  332. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(entity_id=0)
  333. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [entity_id]=[0]
  334. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(call_processed=internal)
  335. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [call_processed]=[internal]
  336. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(call_direction=outbound)
  337. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [call_direction]=[outbound]
  338. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(accountname=default)
  339. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [accountname]=[default]
  340. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(filename1=astpp.pbx.lua)
  341. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [filename1]=[astpp.pbx.lua]
  342. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(origination_rates=ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49)
  343. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [origination_rates]=[ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49]
  344. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(original_caller_id_name=QA_force)
  345. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [original_caller_id_name]=[QA_force]
  346. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(original_caller_id_number=111)
  347. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [original_caller_id_number]=[111]
  348. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_caller_id_name=QA_force)
  349. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_caller_id_name]=[QA_force]
  350. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_caller_id_number=111)
  351. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_caller_id_number]=[111]
  352. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(calltype=STANDARD)
  353. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [calltype]=[STANDARD]
  354. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(termination_rates=ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3)
  355. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [termination_rates]=[ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3]
  356. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(trunk_id=1)
  357. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [trunk_id]=[1]
  358. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(provider_id=3)
  359. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [provider_id]=[3]
  360. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(rate_flag=0)
  361. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [rate_flag]=[0]
  362. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(quality_trunk_seq=1)
  363. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [quality_trunk_seq]=[1]
  364. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(force_trunk_flag=0)
  365. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [force_trunk_flag]=[0]
  366. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_data=trunk_id=1)
  367. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
  368. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(intcall=0)
  369. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [intcall]=[0]
  370. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_id=9110560540)
  371. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[9110560540]
  372. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_data=x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46)
  373. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46]
  374. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9110560540)
  375. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_data]=[x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46] to event
  376. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_id]=[9110560540] to event
  377. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_data]=[x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46] to event
  378. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  379. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  380. 2022-07-04 12:35:26.436428 94.20% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9110560540 [fae4fa5c-1e2e-4dd9-b2cc-5d53222c7403]
  381. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_sofia.c:5121 (sofia/default/9110560540) State Change CS_NEW -> CS_INIT
  382. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_ivr_originate.c:3151 sofia/default/9110560540 Setting leg timeout to 30
  383. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_INIT (Cur 2 Tot 51)
  384. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9110560540) State INIT
  385. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_sofia.c:97 sofia/default/9110560540 SOFIA INIT
  386. 2022-07-04 12:35:26.436428 94.20% [INFO] sofia_glue.c:1651 sofia/default/9110560540 sending invite call-id: (null)
  387. 2022-07-04 12:35:26.436428 94.20% [DEBUG] sofia_glue.c:1654 sofia/default/9110560540 sending invite version: 1.10.7 -release 64bit
  388. Local SDP:
  389. v=0
  390. o=FreeSWITCH 1656919702 1656919703 IN IP4 142.132.184.134
  391. s=FreeSWITCH
  392. c=IN IP4 142.132.184.134
  393. t=0 0
  394. m=audio 18424 RTP/AVP 8 18 101
  395. a=rtpmap:8 PCMA/8000
  396. a=rtpmap:18 G729/8000
  397. a=rtpmap:101 telephone-event/8000
  398. a=fmtp:101 0-15
  399. a=ptime:20
  400. a=sendrecv
  401.  
  402. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:40 sofia/default/9110560540 Standard INIT
  403. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9110560540) State Change CS_INIT -> CS_ROUTING
  404. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9110560540) State INIT going to sleep
  405. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_ROUTING (Cur 2 Tot 51)
  406. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9110560540) State ROUTING
  407. 2022-07-04 12:35:26.436428 94.20% [DEBUG] mod_sofia.c:158 sofia/default/9110560540 SOFIA ROUTING
  408. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_ivr_originate.c:67 (sofia/default/9110560540) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  409. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9110560540) State ROUTING going to sleep
  410. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 51)
  411. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9110560540) State CONSUME_MEDIA
  412. 2022-07-04 12:35:26.436428 94.20% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9110560540) State CONSUME_MEDIA going to sleep
  413. 2022-07-04 12:35:26.436428 94.20% [DEBUG] sofia.c:7499 Channel sofia/default/9110560540 entering state [calling][0]
  414. 2022-07-04 12:35:26.516427 94.20% [DEBUG] sofia.c:7499 Channel sofia/default/9110560540 entering state [completing][200]
  415. 2022-07-04 12:35:26.516427 94.20% [DEBUG] sofia.c:7509 Remote SDP:
  416. v=0
  417. o=root 286481642 286481642 IN IP4 159.203.131.36
  418. s=Asterisk PBX 16.4.0
  419. c=IN IP4 159.203.131.36
  420. t=0 0
  421. m=audio 18582 RTP/AVP 8 101
  422. a=rtpmap:8 PCMA/8000
  423. a=rtpmap:101 telephone-event/8000
  424. a=fmtp:101 0-16
  425. a=maxptime:150
  426.  
  427. 2022-07-04 12:35:26.516427 94.20% [DEBUG] sofia.c:7499 Channel sofia/default/9110560540 entering state [ready][200]
  428. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  429. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  430. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  431. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  432. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9110560540 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  433. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_codec.c:111 sofia/default/9110560540 Original read codec set to PCMA:8
  434. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  435. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:5973 sofia/default/9110560540 Set 2833 dtmf send payload to 101 recv payload to 101
  436. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9110560540] 142.132.184.134 port 18424 -> 159.203.131.36 port 18582 codec: 8 ms: 20
  437. 2022-07-04 12:35:26.516427 94.20% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  438. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_media.c:9089 sofia/default/9110560540 Set 2833 dtmf send payload to 101
  439. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_media.c:9096 sofia/default/9110560540 Set 2833 dtmf receive payload to 101
  440. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_media.c:9119 sofia/default/9110560540 Set rtp dtmf delay to 40
  441. 2022-07-04 12:35:26.536423 94.20% [NOTICE] sofia.c:8683 Channel [sofia/default/9110560540] has been answered
  442. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_channel.c:3950 (sofia/default/9110560540) Callstate Change DOWN -> ACTIVE
  443. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9999998@astppqa606.astppbilling.org:5074] 142.132.184.134 port 25026 -> 103.240.35.46 port 8000 codec: 8 ms: 20
  444. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  445. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_media.c:9089 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf send payload to 101
  446. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_media.c:9096 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf receive payload to 101
  447. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_media.c:9119 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set rtp dtmf delay to 40
  448. 2022-07-04 12:35:26.536423 94.20% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/9999998@astppqa606.astppbilling.org:5074:
  449. v=0
  450. o=FreeSWITCH 1656913100 1656913101 IN IP4 142.132.184.134
  451. s=FreeSWITCH
  452. c=IN IP4 142.132.184.134
  453. t=0 0
  454. m=audio 25026 RTP/AVP 8 101
  455. a=rtpmap:8 PCMA/8000
  456. a=rtpmap:101 telephone-event/8000
  457. a=fmtp:101 0-15
  458. a=ptime:20
  459. a=sendrecv
  460.  
  461. 2022-07-04 12:35:26.536423 94.20% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/9999998@astppqa606.astppbilling.org:5074] has been answered
  462. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_channel.c:3950 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change RINGING -> ACTIVE
  463. 2022-07-04 12:35:26.536423 94.20% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [completed][200]
  464. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/9110560540] Peer UUID: fae4fa5c-1e2e-4dd9-b2cc-5d53222c7403
  465. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/9110560540) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  466. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 51)
  467. 2022-07-04 12:35:26.536423 94.20% [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110560540) State EXCHANGE_MEDIA
  468. 2022-07-04 12:35:26.536423 94.20% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  469. 2022-07-04 12:35:26.696426 94.40% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [ready][200]
  470. 2022-07-04 12:35:26.716439 94.40% [DEBUG] switch_ivr_bridge.c:655 sofia/default/9110560540 Bridge execute app sched_hangup(+60000 normal_clearing)
  471. 2022-07-04 12:35:26.736427 94.40% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  472. 2022-07-04 12:35:26.736427 94.40% [DEBUG] switch_ivr.c:632 sofia/default/9110560540 Command Execute [depth=0] sched_hangup(+60000 normal_clearing)
  473. EXECUTE [depth=0] sofia/default/9110560540 sched_hangup(+60000 normal_clearing)
  474. 2022-07-04 12:35:26.736427 94.40% [DEBUG] switch_scheduler.c:263 Added task 21 switch_ivr_schedule_hangup (fae4fa5c-1e2e-4dd9-b2cc-5d53222c7403) to run at 1656998126
  475. 2022-07-04 12:35:26.856449 94.40% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  476. 2022-07-04 12:35:58.496436 94.63% [NOTICE] sofia.c:1065 Hangup sofia/default/9999998@astppqa606.astppbilling.org:5074 [CS_EXECUTE] [NORMAL_CLEARING]
  477. 2022-07-04 12:35:58.496436 94.63% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/9999998@astppqa606.astppbilling.org:5074]
  478. 2022-07-04 12:35:58.496436 94.63% [DEBUG] switch_ivr_bridge.c:820 sofia/default/9999998@astppqa606.astppbilling.org:5074 ending bridge by request from write function
  479. 2022-07-04 12:35:58.496436 94.63% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/9110560540]
  480. 2022-07-04 12:35:58.496436 94.63% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/9110560540 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  481. 2022-07-04 12:35:58.496436 94.63% [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110560540) State EXCHANGE_MEDIA going to sleep
  482. 2022-07-04 12:35:58.496436 94.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_HANGUP (Cur 2 Tot 51)
  483. 2022-07-04 12:35:58.496436 94.63% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9110560540) Callstate Change ACTIVE -> HANGUP
  484. 2022-07-04 12:35:58.496436 94.63% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9110560540) State HANGUP
  485. 2022-07-04 12:35:58.516439 94.63% [DEBUG] mod_sofia.c:462 sofia/default/9110560540 Overriding SIP cause 480 with 200 from the other leg
  486. 2022-07-04 12:35:58.516439 94.63% [DEBUG] mod_sofia.c:468 Channel sofia/default/9110560540 hanging up, cause: NORMAL_CLEARING
  487. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_ivr_bridge.c:1892 sofia/default/9110560540 skip receive message [UNBRIDGE] (channel is hungup already)
  488. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_ivr_bridge.c:1895 sofia/default/9999998@astppqa606.astppbilling.org:5074 skip receive message [UNBRIDGE] (channel is hungup already)
  489. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_session.c:2983 sofia/default/9999998@astppqa606.astppbilling.org:5074 skip receive message [PHONE_EVENT] (channel is hungup already)
  490. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State EXECUTE going to sleep
  491. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_HANGUP (Cur 2 Tot 51)
  492. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change ACTIVE -> HANGUP
  493. 2022-07-04 12:35:58.516439 94.63% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/9110560540
  494. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State HANGUP
  495. 2022-07-04 12:35:58.516439 94.63% [DEBUG] mod_sofia.c:468 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 hanging up, cause: NORMAL_CLEARING
  496. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:59 sofia/default/9110560540 Standard HANGUP, cause: NORMAL_CLEARING
  497. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9110560540) State HANGUP going to sleep
  498. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9110560540) State Change CS_HANGUP -> CS_REPORTING
  499. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_REPORTING (Cur 2 Tot 51)
  500. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9110560540) State REPORTING
  501. 2022-07-04 12:35:58.516439 94.63% [INFO] mod_json_cdr.c:271 Process [fae4fa5c-1e2e-4dd9-b2cc-5d53222c7403.cdr.json]
  502. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:59 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard HANGUP, cause: NORMAL_CLEARING
  503. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State HANGUP going to sleep
  504. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_HANGUP -> CS_REPORTING
  505. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_REPORTING (Cur 2 Tot 51)
  506. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State REPORTING
  507. 2022-07-04 12:35:58.516439 94.63% [INFO] mod_json_cdr.c:271 Process [cc7985b4-ce60-4be0-a5c4-fcfc03bcdaa0.cdr.json]
  508. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:168 sofia/default/9110560540 Standard REPORTING, cause: NORMAL_CLEARING
  509. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9110560540) State REPORTING going to sleep
  510. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9110560540) State Change CS_REPORTING -> CS_DESTROY
  511. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_session.c:1753 Session 51 (sofia/default/9110560540) Locked, Waiting on external entities
  512. 2022-07-04 12:35:58.516439 94.63% [NOTICE] switch_core_session.c:1771 Session 51 (sofia/default/9110560540) Ended
  513. 2022-07-04 12:35:58.516439 94.63% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9110560540 [CS_DESTROY]
  514. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9110560540) Running State Change CS_DESTROY (Cur 1 Tot 51)
  515. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9110560540) State DESTROY
  516. 2022-07-04 12:35:58.516439 94.63% [DEBUG] mod_sofia.c:379 sofia/default/9110560540 SOFIA DESTROY
  517. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:175 sofia/default/9110560540 Standard DESTROY
  518. 2022-07-04 12:35:58.516439 94.63% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9110560540) State DESTROY going to sleep
  519. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_state_machine.c:168 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard REPORTING, cause: NORMAL_CLEARING
  520. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State REPORTING going to sleep
  521. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_REPORTING -> CS_DESTROY
  522. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_session.c:1753 Session 50 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Locked, Waiting on external entities
  523. 2022-07-04 12:35:58.536426 94.63% [NOTICE] switch_core_session.c:1771 Session 50 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Ended
  524. 2022-07-04 12:35:58.536426 94.63% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 [CS_DESTROY]
  525. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_DESTROY (Cur 0 Tot 51)
  526. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State DESTROY
  527. 2022-07-04 12:35:58.536426 94.63% [DEBUG] mod_sofia.c:379 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA DESTROY
  528. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_state_machine.c:175 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard DESTROY
  529. 2022-07-04 12:35:58.536426 94.63% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State DESTROY going to sleep
  530. 2022-07-04 12:35:58.756448 94.40% [DEBUG] switch_scheduler.c:147 Deleting task 21 switch_ivr_schedule_hangup (fae4fa5c-1e2e-4dd9-b2cc-5d53222c7403)
  531. 2022-07-04 12:37:45.756419 93.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 [d9636acc-7b26-43fb-ae02-2f5ef27d3981]
  532. 2022-07-04 12:37:45.756419 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_NEW (Cur 1 Tot 52)
  533. 2022-07-04 12:37:45.756419 93.90% [INFO] sofia.c:10462 sofia/default/9999998@astppqa606.astppbilling.org:5074 receiving invite from 103.240.35.46:49504 version: 1.10.7 -release 64bit call-id: NmIzYjUxNjljZTc2NTJlZmYwYTc4MDA0YmVmYzhlN2Y.
  534. 2022-07-04 12:37:45.756419 93.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  535. 2022-07-04 12:37:45.756419 93.90% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [110560540@astppqa606.astppbilling.org] from ip 103.240.35.46
  536. 2022-07-04 12:37:45.756419 93.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State NEW
  537. 2022-07-04 12:37:45.756419 93.90% [DEBUG] sofia.c:2419 detaching session d9636acc-7b26-43fb-ae02-2f5ef27d3981
  538. 2022-07-04 12:37:46.076426 93.90% [DEBUG] sofia.c:2532 Re-attaching to session d9636acc-7b26-43fb-ae02-2f5ef27d3981
  539. 2022-07-04 12:37:46.096450 93.90% [INFO] sofia.c:10462 sofia/default/9999998@astppqa606.astppbilling.org:5074 receiving invite from 103.240.35.46:49504 version: 1.10.7 -release 64bit call-id: NmIzYjUxNjljZTc2NTJlZmYwYTc4MDA0YmVmYzhlN2Y.
  540. 2022-07-04 12:37:46.096450 93.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  541. 2022-07-04 12:37:46.096450 93.90% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
  542. 2022-07-04 12:37:46.096450 93.90% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [received][100]
  543. 2022-07-04 12:37:46.096450 93.90% [DEBUG] sofia.c:7509 Remote SDP:
  544. v=0
  545. o=Z 0 0 IN IP4 103.240.35.46
  546. s=Z
  547. c=IN IP4 103.240.35.46
  548. t=0 0
  549. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  550. a=rtpmap:110 speex/8000
  551. a=rtpmap:98 iLBC/8000
  552. a=fmtp:98 mode=20
  553. a=rtpmap:101 telephone-event/8000
  554. a=fmtp:101 0-15
  555.  
  556. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  557. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  558. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  559. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  560. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  561. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  562. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  563. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  564. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  565. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  566. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  567. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  568. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  569. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9999998@astppqa606.astppbilling.org:5074 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  570. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_codec.c:111 sofia/default/9999998@astppqa606.astppbilling.org:5074 Original read codec set to PCMA:8
  571. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  572. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_media.c:5973 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf send payload to 101 recv payload to 101
  573. 2022-07-04 12:37:46.096450 93.90% [DEBUG] sofia.c:7933 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_NEW -> CS_INIT
  574. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_INIT (Cur 1 Tot 52)
  575. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State INIT
  576. 2022-07-04 12:37:46.096450 93.90% [DEBUG] mod_sofia.c:97 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA INIT
  577. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard INIT
  578. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_INIT -> CS_ROUTING
  579. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State INIT going to sleep
  580. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_ROUTING (Cur 1 Tot 52)
  581. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_channel.c:2380 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change DOWN -> RINGING
  582. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State ROUTING
  583. 2022-07-04 12:37:46.096450 93.90% [DEBUG] mod_sofia.c:158 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA ROUTING
  584. 2022-07-04 12:37:46.096450 93.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard ROUTING
  585. 2022-07-04 12:37:46.096450 93.90% [INFO] mod_dialplan_xml.c:639 Processing 9999998 <9999998>->110560540 in context default
  586. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
  587. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  588. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  589. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  590. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  591. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 110560540
  592. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '110560540' limit 1
  593. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
  594. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '110560540' and status=0 LIMIT 1
  595. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER9999998
  596. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  597. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '110560540' limit 1
  598. 2022-07-04 12:37:46.116424 93.90% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
  599. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : Prashant / 9992200017
  600. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 540
  601. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 110560 AND status=0 limit 1
  602. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  603. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="110560540"  AND domains.domain="astppqa606.astppbilling.org" limit 1
  604. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
  605. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9999998]
  606. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "9999998" AND deleted = 0 limit 1
  607. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^110560540.*' OR blocked_patterns = '^11056054.*' OR blocked_patterns = '^1105605.*' OR blocked_patterns = '^110560.*' OR blocked_patterns = '^11056.*' OR blocked_patterns = '^1105.*' OR blocked_patterns = '^110.*' OR blocked_patterns = '^11.*' OR blocked_patterns = '^1.*' OR blocked_patterns ='--') AND accountid = 49 limit 1
  608. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
  609. 2022-07-04 12:37:46.116424 93.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 5
  610. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 5 AND status=0 limit 1
  611. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"1/91/*/*".....110560540
  612. 2022-07-04 12:37:46.116424 93.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 110560540
  613. 2022-07-04 12:37:46.116424 93.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 9110560540
  614. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"11/+918871343676/*/*".....9992200017DBCLI9110560540
  615. 2022-07-04 12:37:46.116424 93.90% [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 = '^9110560540.*' OR patterns = '^911056054.*' OR patterns = '^91105605.*' OR patterns = '^9110560.*' OR patterns = '^911056.*' OR patterns = '^91105.*' OR patterns = '^9110.*' OR patterns = '^911.*' OR patterns = '^91.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 49 ORDER BY LENGTH(PKGPTR.patterns) DESC
  616. 2022-07-04 12:37:46.116424 93.90% [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 ="9110560540" AND A.accountid = '49' limit 1
  617. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 9110560540
  618. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  619. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="9110560540"  AND domains.domain="astppqa606.astppbilling.org" limit 1
  620. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
  621. 2022-07-04 12:37:46.116424 93.90% [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 = '9110560540' OR number = '911056054' OR number = '91105605' OR number = '9110560' OR number = '911056' OR number = '91105' OR number = '9110' OR number = '911' OR number = '91' OR number = '9' OR number ='--') OR (number = '9110560540*' OR number = '911056054*' OR number = '91105605*' OR number = '9110560*' OR number = '911056*' OR number = '91105*' OR number = '9110*' OR number = '911*' OR number = '91*' OR number = '9*' OR number ='--') OR (number = '9992200017' OR number = '999220001' OR number = '99922000' OR number = '9992200' OR number = '999220' OR number = '99922' OR number = '9992' OR number = '999' OR number = '99' OR number = '9' OR number ='--') OR (number = '9992200017*' OR number = '999220001*' OR number = '99922000*' OR number = '9992200*' OR number = '999220*' OR number = '99922*' OR number = '9992*' OR number = '999*' OR number = '99*' OR number = '9*' OR number ='--')) AND status=0 AND accountid = 0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  622. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  623. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '9110560540' OR routing_prefix = '911056054' OR routing_prefix = '91105605' OR routing_prefix = '9110560' OR routing_prefix = '911056' OR routing_prefix = '91105' OR routing_prefix = '9110' OR routing_prefix = '911' OR routing_prefix = '91' OR routing_prefix = '9' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  624. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  625. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 49
  626. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9999998
  627. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 39983.9
  628. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  629. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 1
  630. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  631. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  632. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  633. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  634. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
  635. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  636. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  637. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  638. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  639. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  640. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]0 GOT___SUCCESS2022-07-04 12:37:46
  641. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=pattern::^91.*
  642. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=caller_id_pattern::^.*
  643. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]0 GOT___SUCCESS2022-07-04 12:37:46
  644. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=pattern::^91.*
  645. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=caller_id_pattern::^.*
  646. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
  647. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  648. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 18
  649. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  650. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  651. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.60000
  652. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : QA
  653. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 108
  654. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 49
  655. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
  656. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  657. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  658. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your39983.9 balance Accountid 49 !!!
  659. 2022-07-04 12:37:46.116424 93.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 1000 mins!
  660. 2022-07-04 12:37:46.116424 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 1000 minutes
  661. 2022-07-04 12:37:46.116424 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
  662. 2022-07-04 12:37:46.116424 93.90% [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
  663. 2022-07-04 12:37:46.116424 93.90% [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 = '^9110560540.*' OR pattern = '^911056054.*' OR pattern = '^91105605.*' OR pattern = '^9110560.*' OR pattern = '^911056.*' OR pattern = '^91105.*' OR pattern = '^9110.*' OR pattern = '^911.*' OR pattern = '^91.*' 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
  664. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  665. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 2
  666. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^91.*
  667. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination :
  668. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  669. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  670. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^91.*
  671. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
  672. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
  673. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
  674. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : YourProvider
  675. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
  676. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
  677. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
  678. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  679. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
  680. 2022-07-04 12:37:46.136422 93.90% [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 = 49 AND status=0 LIMIT 1
  681. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 1000
  682. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  683. 2022-07-04 12:37:46.136422 93.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  684. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  685. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  686. 2022-07-04 12:37:46.136422 93.90% [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
  687. 2022-07-04 12:37:46.136422 93.90% [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
  688. 2022-07-04 12:37:46.136422 93.90% [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 ="110560540" LIMIT 1
  689. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  690. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
  691. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  692. <document type="freeswitch/xml">
  693. <section name="dialplan" description="ASTPP Dialplan">
  694. <context name="default">
  695. <extension name="9110560540">
  696. <condition field="destination_number" expression="110560540">
  697. <action application="set" data="effective_destination_number=9110560540"/>
  698. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  699. <action application="set" data="bridge_pre_execute_bleg_data=+60000 normal_clearing"/>
  700. <action application="set" data="notify_flag=0"/>
  701. <action application="set" data="notify_credit_limit=5.00000"/>
  702. <action application="set" data="type=0"/>
  703. <action application="set" data="callstart=2022-07-04 12:37:46"/>
  704. <action application="set" data="hangup_after_bridge=true"/>
  705. <action application="set" data="continue_on_fail=TRUE"/>
  706. <action application="set" data="account_id=49"/>
  707. <action application="set" data="parent_id=0"/>
  708. <action application="set" data="entity_id=0"/>
  709. <action application="set" data="call_processed=internal"/>
  710. <action application="set" data="call_direction=outbound"/>
  711. <action application="set" data="accountname=default"/>
  712. <action application="set" data="filename1=astpp.pbx.lua"/>
  713. <action application="set" data="origination_rates=ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49"/>
  714. <action application="set" data="original_caller_id_name=QA_force"/>
  715. <action application="set" data="original_caller_id_number=11"/>
  716.  
  717. <action application="set" data="effective_caller_id_name=QA_force"/>
  718. <action application="set" data="effective_caller_id_number=11"/>
  719. <action application="set" data="calltype=STANDARD"/>
  720. <action application="set" data="termination_rates=ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3"/>
  721. <action application="set" data="trunk_id=1"/>
  722. <action application="set" data="provider_id=3"/>
  723. <action application="set" data="rate_flag=0"/>
  724. <action application="set" data="quality_trunk_seq=1"/>
  725. <action application="set" data="force_trunk_flag=0"/>
  726. <action application="export" data="presence_data=trunk_id=1"/>
  727. <action application="set" data="intcall=0"/>
  728. <action application="export" data="presence_id=9110560540"/>
  729. <action application="export" data="presence_data=x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46"/>
  730. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9110560540"/>
  731. </condition>
  732. </extension>
  733. </context>
  734. </section>
  735. </document>
  736. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 parsing [default->9110560540] continue=false
  737. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Regex (PASS) [9110560540] destination_number(110560540) =~ /110560540/ break=on-false
  738. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_destination_number=9110560540)
  739. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  740. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  741. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(notify_flag=0)
  742. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(notify_credit_limit=5.00000)
  743. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(type=0)
  744. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(callstart=2022-07-04 12:37:46)
  745. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(hangup_after_bridge=true)
  746. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(continue_on_fail=TRUE)
  747. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(account_id=49)
  748. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(parent_id=0)
  749. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(entity_id=0)
  750. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(call_processed=internal)
  751. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(call_direction=outbound)
  752. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(accountname=default)
  753. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(filename1=astpp.pbx.lua)
  754. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(origination_rates=ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49)
  755. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(original_caller_id_name=QA_force)
  756. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(original_caller_id_number=11)
  757. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_caller_id_name=QA_force)
  758. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_caller_id_number=11)
  759. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(calltype=STANDARD)
  760. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(termination_rates=ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3)
  761. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(trunk_id=1)
  762. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(provider_id=3)
  763. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(rate_flag=0)
  764. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(quality_trunk_seq=1)
  765. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(force_trunk_flag=0)
  766. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_data=trunk_id=1)
  767. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(intcall=0)
  768. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_id=9110560540)
  769. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_data=x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46)
  770. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9110560540)
  771. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_ROUTING -> CS_EXECUTE
  772. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State ROUTING going to sleep
  773. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_EXECUTE (Cur 1 Tot 52)
  774. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State EXECUTE
  775. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_sofia.c:213 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA EXECUTE
  776. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:323 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard EXECUTE
  777. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_destination_number=9110560540)
  778. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_destination_number]=[9110560540]
  779. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(bridge_pre_execute_bleg_app=sched_hangup)
  780. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [bridge_pre_execute_bleg_app]=[sched_hangup]
  781. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  782. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [bridge_pre_execute_bleg_data]=[+60000 normal_clearing]
  783. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(notify_flag=0)
  784. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [notify_flag]=[0]
  785. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(notify_credit_limit=5.00000)
  786. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [notify_credit_limit]=[5.00000]
  787. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(type=0)
  788. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [type]=[0]
  789. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(callstart=2022-07-04 12:37:46)
  790. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [callstart]=[2022-07-04 12:37:46]
  791. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(hangup_after_bridge=true)
  792. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [hangup_after_bridge]=[true]
  793. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(continue_on_fail=TRUE)
  794. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [continue_on_fail]=[TRUE]
  795. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(account_id=49)
  796. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [account_id]=[49]
  797. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(parent_id=0)
  798. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [parent_id]=[0]
  799. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(entity_id=0)
  800. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [entity_id]=[0]
  801. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(call_processed=internal)
  802. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [call_processed]=[internal]
  803. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(call_direction=outbound)
  804. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [call_direction]=[outbound]
  805. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(accountname=default)
  806. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [accountname]=[default]
  807. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(filename1=astpp.pbx.lua)
  808. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [filename1]=[astpp.pbx.lua]
  809. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(origination_rates=ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49)
  810. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [origination_rates]=[ID:18|CODE:^91.*|DESTINATION:QA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.60000|INC:60|INITIALBLOCK:60|RATEGROUP:1|MARKUP:0|CI:108|ACCID:49]
  811. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(original_caller_id_name=QA_force)
  812. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [original_caller_id_name]=[QA_force]
  813. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(original_caller_id_number=11)
  814. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [original_caller_id_number]=[11]
  815. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_caller_id_name=QA_force)
  816. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_caller_id_name]=[QA_force]
  817. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_caller_id_number=11)
  818. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_caller_id_number]=[11]
  819. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(calltype=STANDARD)
  820. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [calltype]=[STANDARD]
  821. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(termination_rates=ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3)
  822. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [termination_rates]=[ID:2|CODE:^91.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.30000|INC:60|INITIALBLOCK:60|TRUNK:1|PROVIDER:3]
  823. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(trunk_id=1)
  824. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [trunk_id]=[1]
  825. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(provider_id=3)
  826. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [provider_id]=[3]
  827. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(rate_flag=0)
  828. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [rate_flag]=[0]
  829. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(quality_trunk_seq=1)
  830. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [quality_trunk_seq]=[1]
  831. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(force_trunk_flag=0)
  832. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [force_trunk_flag]=[0]
  833. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_data=trunk_id=1)
  834. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
  835. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(intcall=0)
  836. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [intcall]=[0]
  837. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_id=9110560540)
  838. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[9110560540]
  839. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_data=x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46)
  840. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46]
  841. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/9110560540)
  842. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_data]=[x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46] to event
  843. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_id]=[9110560540] to event
  844. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_data]=[x|||PK admin's Cust 420(9999998)|||^91.* // QA // 0.60000 |||YourTrunk // ^91.* //  // 0.30000 // trunk_id=1|||STD|||103.240.35.46] to event
  845. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  846. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  847. 2022-07-04 12:37:46.136422 93.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9110560540 [8dc58363-0a24-46a3-8d2b-71810d2f575e]
  848. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_sofia.c:5121 (sofia/default/9110560540) State Change CS_NEW -> CS_INIT
  849. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_ivr_originate.c:3151 sofia/default/9110560540 Setting leg timeout to 30
  850. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_INIT (Cur 2 Tot 53)
  851. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9110560540) State INIT
  852. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_sofia.c:97 sofia/default/9110560540 SOFIA INIT
  853. 2022-07-04 12:37:46.136422 93.90% [INFO] sofia_glue.c:1651 sofia/default/9110560540 sending invite call-id: (null)
  854. 2022-07-04 12:37:46.136422 93.90% [DEBUG] sofia_glue.c:1654 sofia/default/9110560540 sending invite version: 1.10.7 -release 64bit
  855. Local SDP:
  856. v=0
  857. o=FreeSWITCH 1656911386 1656911387 IN IP4 142.132.184.134
  858. s=FreeSWITCH
  859. c=IN IP4 142.132.184.134
  860. t=0 0
  861. m=audio 26880 RTP/AVP 8 18 101
  862. a=rtpmap:8 PCMA/8000
  863. a=rtpmap:18 G729/8000
  864. a=rtpmap:101 telephone-event/8000
  865. a=fmtp:101 0-15
  866. a=ptime:20
  867. a=sendrecv
  868.  
  869. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/9110560540 Standard INIT
  870. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9110560540) State Change CS_INIT -> CS_ROUTING
  871. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9110560540) State INIT going to sleep
  872. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_ROUTING (Cur 2 Tot 53)
  873. 2022-07-04 12:37:46.136422 93.90% [DEBUG] sofia.c:7499 Channel sofia/default/9110560540 entering state [calling][0]
  874. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9110560540) State ROUTING
  875. 2022-07-04 12:37:46.136422 93.90% [DEBUG] mod_sofia.c:158 sofia/default/9110560540 SOFIA ROUTING
  876. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_ivr_originate.c:67 (sofia/default/9110560540) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  877. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9110560540) State ROUTING going to sleep
  878. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 53)
  879. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9110560540) State CONSUME_MEDIA
  880. 2022-07-04 12:37:46.136422 93.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/9110560540) State CONSUME_MEDIA going to sleep
  881. 2022-07-04 12:37:46.216444 93.90% [DEBUG] sofia.c:7499 Channel sofia/default/9110560540 entering state [completing][200]
  882. 2022-07-04 12:37:46.216444 93.90% [DEBUG] sofia.c:7509 Remote SDP:
  883. v=0
  884. o=root 1097543485 1097543485 IN IP4 159.203.131.36
  885. s=Asterisk PBX 16.4.0
  886. c=IN IP4 159.203.131.36
  887. t=0 0
  888. m=audio 13552 RTP/AVP 8 101
  889. a=rtpmap:8 PCMA/8000
  890. a=rtpmap:101 telephone-event/8000
  891. a=fmtp:101 0-16
  892. a=maxptime:150
  893.  
  894. 2022-07-04 12:37:46.236418 93.90% [DEBUG] sofia.c:7499 Channel sofia/default/9110560540 entering state [ready][200]
  895. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  896. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  897. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  898. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  899. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9110560540 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  900. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_codec.c:111 sofia/default/9110560540 Original read codec set to PCMA:8
  901. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  902. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:5973 sofia/default/9110560540 Set 2833 dtmf send payload to 101 recv payload to 101
  903. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9110560540] 142.132.184.134 port 26880 -> 159.203.131.36 port 13552 codec: 8 ms: 20
  904. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  905. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:9089 sofia/default/9110560540 Set 2833 dtmf send payload to 101
  906. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:9096 sofia/default/9110560540 Set 2833 dtmf receive payload to 101
  907. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:9119 sofia/default/9110560540 Set rtp dtmf delay to 40
  908. 2022-07-04 12:37:46.236418 93.90% [NOTICE] sofia.c:8683 Channel [sofia/default/9110560540] has been answered
  909. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_channel.c:3950 (sofia/default/9110560540) Callstate Change DOWN -> ACTIVE
  910. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9999998@astppqa606.astppbilling.org:5074] 142.132.184.134 port 32298 -> 103.240.35.46 port 8000 codec: 8 ms: 20
  911. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  912. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:9089 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf send payload to 101
  913. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:9096 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf receive payload to 101
  914. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_core_media.c:9119 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set rtp dtmf delay to 40
  915. 2022-07-04 12:37:46.236418 93.90% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/9999998@astppqa606.astppbilling.org:5074:
  916. v=0
  917. o=FreeSWITCH 1656905968 1656905969 IN IP4 142.132.184.134
  918. s=FreeSWITCH
  919. c=IN IP4 142.132.184.134
  920. t=0 0
  921. m=audio 32298 RTP/AVP 8 101
  922. a=rtpmap:8 PCMA/8000
  923. a=rtpmap:101 telephone-event/8000
  924. a=fmtp:101 0-15
  925. a=ptime:20
  926. a=sendrecv
  927.  
  928. 2022-07-04 12:37:46.236418 93.90% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/9999998@astppqa606.astppbilling.org:5074] has been answered
  929. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_channel.c:3950 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change RINGING -> ACTIVE
  930. 2022-07-04 12:37:46.236418 93.90% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [completed][200]
  931. 2022-07-04 12:37:46.236418 93.90% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/9110560540] Peer UUID: 8dc58363-0a24-46a3-8d2b-71810d2f575e
  932. 2022-07-04 12:37:46.256424 93.90% [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/9110560540) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  933. 2022-07-04 12:37:46.256424 93.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 53)
  934. 2022-07-04 12:37:46.256424 93.90% [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110560540) State EXCHANGE_MEDIA
  935. 2022-07-04 12:37:46.256424 93.90% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  936. 2022-07-04 12:37:46.396429 93.90% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [ready][200]
  937. 2022-07-04 12:37:46.436421 93.90% [DEBUG] switch_ivr_bridge.c:655 sofia/default/9110560540 Bridge execute app sched_hangup(+60000 normal_clearing)
  938. 2022-07-04 12:37:46.436421 93.90% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  939. 2022-07-04 12:37:46.456422 93.90% [DEBUG] switch_ivr.c:632 sofia/default/9110560540 Command Execute [depth=0] sched_hangup(+60000 normal_clearing)
  940. EXECUTE [depth=0] sofia/default/9110560540 sched_hangup(+60000 normal_clearing)
  941. 2022-07-04 12:37:46.456422 93.90% [DEBUG] switch_scheduler.c:263 Added task 22 switch_ivr_schedule_hangup (8dc58363-0a24-46a3-8d2b-71810d2f575e) to run at 1656998266
  942. 2022-07-04 12:37:46.576419 93.90% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  943. 2022-07-04 12:37:46.896430 93.43% [NOTICE] sofia.c:1065 Hangup sofia/default/9999998@astppqa606.astppbilling.org:5074 [CS_EXECUTE] [NORMAL_CLEARING]
  944. 2022-07-04 12:37:46.896430 93.43% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/9999998@astppqa606.astppbilling.org:5074]
  945. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_ivr_bridge.c:820 sofia/default/9999998@astppqa606.astppbilling.org:5074 ending bridge by request from write function
  946. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/9110560540]
  947. 2022-07-04 12:37:46.916439 93.43% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/9110560540 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  948. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110560540) State EXCHANGE_MEDIA going to sleep
  949. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_HANGUP (Cur 2 Tot 53)
  950. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9110560540) Callstate Change ACTIVE -> HANGUP
  951. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9110560540) State HANGUP
  952. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_ivr_bridge.c:1892 sofia/default/9110560540 skip receive message [UNBRIDGE] (channel is hungup already)
  953. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_ivr_bridge.c:1895 sofia/default/9999998@astppqa606.astppbilling.org:5074 skip receive message [UNBRIDGE] (channel is hungup already)
  954. 2022-07-04 12:37:46.916439 93.43% [DEBUG] mod_sofia.c:462 sofia/default/9110560540 Overriding SIP cause 480 with 200 from the other leg
  955. 2022-07-04 12:37:46.916439 93.43% [DEBUG] mod_sofia.c:468 Channel sofia/default/9110560540 hanging up, cause: NORMAL_CLEARING
  956. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_session.c:2983 sofia/default/9999998@astppqa606.astppbilling.org:5074 skip receive message [PHONE_EVENT] (channel is hungup already)
  957. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State EXECUTE going to sleep
  958. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_HANGUP (Cur 2 Tot 53)
  959. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:844 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change ACTIVE -> HANGUP
  960. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State HANGUP
  961. 2022-07-04 12:37:46.916439 93.43% [DEBUG] mod_sofia.c:468 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 hanging up, cause: NORMAL_CLEARING
  962. 2022-07-04 12:37:46.916439 93.43% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/9110560540
  963. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:59 sofia/default/9110560540 Standard HANGUP, cause: NORMAL_CLEARING
  964. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9110560540) State HANGUP going to sleep
  965. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9110560540) State Change CS_HANGUP -> CS_REPORTING
  966. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9110560540) Running State Change CS_REPORTING (Cur 2 Tot 53)
  967. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9110560540) State REPORTING
  968. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:59 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard HANGUP, cause: NORMAL_CLEARING
  969. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State HANGUP going to sleep
  970. 2022-07-04 12:37:46.916439 93.43% [INFO] mod_json_cdr.c:271 Process [8dc58363-0a24-46a3-8d2b-71810d2f575e.cdr.json]
  971. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:616 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_HANGUP -> CS_REPORTING
  972. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_REPORTING (Cur 2 Tot 53)
  973. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State REPORTING
  974. 2022-07-04 12:37:46.916439 93.43% [INFO] mod_json_cdr.c:271 Process [d9636acc-7b26-43fb-ae02-2f5ef27d3981.cdr.json]
  975. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:168 sofia/default/9110560540 Standard REPORTING, cause: NORMAL_CLEARING
  976. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9110560540) State REPORTING going to sleep
  977. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9110560540) State Change CS_REPORTING -> CS_DESTROY
  978. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_session.c:1753 Session 53 (sofia/default/9110560540) Locked, Waiting on external entities
  979. 2022-07-04 12:37:46.916439 93.43% [NOTICE] switch_core_session.c:1771 Session 53 (sofia/default/9110560540) Ended
  980. 2022-07-04 12:37:46.916439 93.43% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9110560540 [CS_DESTROY]
  981. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9110560540) Running State Change CS_DESTROY (Cur 1 Tot 53)
  982. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9110560540) State DESTROY
  983. 2022-07-04 12:37:46.916439 93.43% [DEBUG] mod_sofia.c:379 sofia/default/9110560540 SOFIA DESTROY
  984. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:175 sofia/default/9110560540 Standard DESTROY
  985. 2022-07-04 12:37:46.916439 93.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9110560540) State DESTROY going to sleep
  986. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_state_machine.c:168 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard REPORTING, cause: NORMAL_CLEARING
  987. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State REPORTING going to sleep
  988. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_state_machine.c:607 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_REPORTING -> CS_DESTROY
  989. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_session.c:1753 Session 52 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Locked, Waiting on external entities
  990. 2022-07-04 12:37:46.936417 93.43% [NOTICE] switch_core_session.c:1771 Session 52 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Ended
  991. 2022-07-04 12:37:46.936417 93.43% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 [CS_DESTROY]
  992. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_state_machine.c:735 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_DESTROY (Cur 0 Tot 53)
  993. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State DESTROY
  994. 2022-07-04 12:37:46.936417 93.43% [DEBUG] mod_sofia.c:379 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA DESTROY
  995. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_state_machine.c:175 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard DESTROY
  996. 2022-07-04 12:37:46.936417 93.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State DESTROY going to sleep
  997. 2022-07-04 12:37:46.956415 93.43% [DEBUG] switch_scheduler.c:147 Deleting task 22 switch_ivr_schedule_hangup (8dc58363-0a24-46a3-8d2b-71810d2f575e)
  998. 2022-07-04 12:38:24.556462 90.97% [NOTICE] switch_channel.c:1123 New Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 [075dd272-bf0f-4ec4-82dd-2015008f70e6]
  999. 2022-07-04 12:38:24.556462 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_NEW (Cur 1 Tot 54)
  1000. 2022-07-04 12:38:24.556462 90.97% [INFO] sofia.c:10462 sofia/default/9999998@astppqa606.astppbilling.org:5074 receiving invite from 103.240.35.46:49504 version: 1.10.7 -release 64bit call-id: NTRlMThhYWQwZGJkMzE0NTM2ZmRkMTJmNDI1ODNkMjU.
  1001. 2022-07-04 12:38:24.556462 90.97% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  1002. 2022-07-04 12:38:24.556462 90.97% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [110560540@astppqa606.astppbilling.org] from ip 103.240.35.46
  1003. 2022-07-04 12:38:24.556462 90.97% [DEBUG] switch_core_state_machine.c:600 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State NEW
  1004. 2022-07-04 12:38:24.556462 90.97% [DEBUG] sofia.c:2419 detaching session 075dd272-bf0f-4ec4-82dd-2015008f70e6
  1005. 2022-07-04 12:38:24.896446 90.97% [DEBUG] sofia.c:2532 Re-attaching to session 075dd272-bf0f-4ec4-82dd-2015008f70e6
  1006. 2022-07-04 12:38:24.896446 90.97% [INFO] sofia.c:10462 sofia/default/9999998@astppqa606.astppbilling.org:5074 receiving invite from 103.240.35.46:49504 version: 1.10.7 -release 64bit call-id: NTRlMThhYWQwZGJkMzE0NTM2ZmRkMTJmNDI1ODNkMjU.
  1007. 2022-07-04 12:38:24.896446 90.97% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
  1008. 2022-07-04 12:38:24.916447 90.97% [DEBUG] sofia.c:11672 Setting NAT mode based on via received
  1009. 2022-07-04 12:38:24.916447 90.97% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [received][100]
  1010. 2022-07-04 12:38:24.916447 90.97% [DEBUG] sofia.c:7509 Remote SDP:
  1011. v=0
  1012. o=Z 0 0 IN IP4 103.240.35.46
  1013. s=Z
  1014. c=IN IP4 103.240.35.46
  1015. t=0 0
  1016. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  1017. a=rtpmap:110 speex/8000
  1018. a=rtpmap:98 iLBC/8000
  1019. a=fmtp:98 mode=20
  1020. a=rtpmap:101 telephone-event/8000
  1021. a=fmtp:101 0-15
  1022.  
  1023. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  1024. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  1025. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  1026. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  1027. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1028. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1029. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1030. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1031. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1032. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1033. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  1034. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  1035. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  1036. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/9999998@astppqa606.astppbilling.org:5074 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1037. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_codec.c:111 sofia/default/9999998@astppqa606.astppbilling.org:5074 Original read codec set to PCMA:8
  1038. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  1039. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_media.c:5973 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf send payload to 101 recv payload to 101
  1040. 2022-07-04 12:38:24.916447 90.97% [DEBUG] sofia.c:7933 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_NEW -> CS_INIT
  1041. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_INIT (Cur 1 Tot 54)
  1042. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State INIT
  1043. 2022-07-04 12:38:24.916447 90.97% [DEBUG] mod_sofia.c:97 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA INIT
  1044. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:40 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard INIT
  1045. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:48 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_INIT -> CS_ROUTING
  1046. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State INIT going to sleep
  1047. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_ROUTING (Cur 1 Tot 54)
  1048. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_channel.c:2380 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change DOWN -> RINGING
  1049. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State ROUTING
  1050. 2022-07-04 12:38:24.916447 90.97% [DEBUG] mod_sofia.c:158 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA ROUTING
  1051. 2022-07-04 12:38:24.916447 90.97% [DEBUG] switch_core_state_machine.c:230 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard ROUTING
  1052. 2022-07-04 12:38:24.916447 90.97% [INFO] mod_dialplan_xml.c:639 Processing 9999998 <9999998>->110560540 in context default
  1053. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
  1054. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  1055. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  1056. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1057. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  1058. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 110560540
  1059. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '110560540' limit 1
  1060. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
  1061. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '110560540' and status=0 LIMIT 1
  1062. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER9999998
  1063. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1064. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '110560540' limit 1
  1065. 2022-07-04 12:38:24.936432 90.97% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
  1066. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : Prashant / 9992200017
  1067. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 540
  1068. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 110560 AND status=0 limit 1
  1069. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  1070. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="110560540"  AND domains.domain="astppqa606.astppbilling.org" limit 1
  1071. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
  1072. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9999998]
  1073. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "9999998" AND deleted = 0 limit 1
  1074. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^110560540.*' OR blocked_patterns = '^11056054.*' OR blocked_patterns = '^1105605.*' OR blocked_patterns = '^110560.*' OR blocked_patterns = '^11056.*' OR blocked_patterns = '^1105.*' OR blocked_patterns = '^110.*' OR blocked_patterns = '^11.*' OR blocked_patterns = '^1.*' OR blocked_patterns ='--') AND accountid = 49 limit 1
  1075. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
  1076. 2022-07-04 12:38:24.936432 90.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 5
  1077. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 5 AND status=0 limit 1
  1078. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"*/*/*/*".....110560540
  1079. 2022-07-04 12:38:24.936432 90.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 110560540
  1080. 2022-07-04 12:38:24.936432 90.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 10560540
  1081. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"11/+918871343676/*/*".....9992200017DBCLI10560540
  1082. 2022-07-04 12:38:24.936432 90.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 = '^10560540.*' OR patterns = '^1056054.*' OR patterns = '^105605.*' OR patterns = '^10560.*' OR patterns = '^1056.*' OR patterns = '^105.*' OR patterns = '^10.*' OR patterns = '^1.*' OR patterns ='--') AND accountid = 49 ORDER BY LENGTH(PKGPTR.patterns) DESC
  1083. 2022-07-04 12:38:24.936432 90.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 ="10560540" AND A.accountid = '49' limit 1
  1084. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 10560540
  1085. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  1086. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="10560540"  AND domains.domain="astppqa606.astppbilling.org" limit 1
  1087. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
  1088. 2022-07-04 12:38:24.936432 90.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 = '10560540' OR number = '1056054' OR number = '105605' OR number = '10560' OR number = '1056' OR number = '105' OR number = '10' OR number = '1' OR number ='--') OR (number = '10560540*' OR number = '1056054*' OR number = '105605*' OR number = '10560*' OR number = '1056*' OR number = '105*' OR number = '10*' OR number = '1*' OR number ='--') OR (number = '9992200017' OR number = '999220001' OR number = '99922000' OR number = '9992200' OR number = '999220' OR number = '99922' OR number = '9992' OR number = '999' OR number = '99' OR number = '9' OR number ='--') OR (number = '9992200017*' OR number = '999220001*' OR number = '99922000*' OR number = '9992200*' OR number = '999220*' OR number = '99922*' OR number = '9992*' OR number = '999*' OR number = '99*' OR number = '9*' OR number ='--')) AND status=0 AND accountid = 0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  1089. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  1090. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '10560540' OR routing_prefix = '1056054' OR routing_prefix = '105605' OR routing_prefix = '10560' OR routing_prefix = '1056' OR routing_prefix = '105' OR routing_prefix = '10' OR routing_prefix = '1' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  1091. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  1092. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 49
  1093. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9999998
  1094. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 39983.9
  1095. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  1096. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 1
  1097. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  1098. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  1099. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  1100. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  1101. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
  1102. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  1103. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  1104. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  1105. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  1106. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :my-rates-1
  1107. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]0 GOT___SUCCESS2022-07-04 12:38:24
  1108. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=pattern::^91.*
  1109. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=caller_id_pattern::^.*
  1110. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]0 GOT___SUCCESS2022-07-04 12:38:24
  1111. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=pattern::^1.*
  1112. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=caller_id_pattern::^.*
  1113. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]0 GOT___SUCCESS2022-07-04 12:38:24
  1114. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=pattern::^1.*
  1115. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [ROUTES_CACHING]1=GOT___SUCCESS=caller_id_pattern::^.*
  1116. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
  1117. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  1118. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 1
  1119. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  1120. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  1121. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.20000
  1122. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : USA
  1123. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
  1124. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 49
  1125. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 0
  1126. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  1127. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  1128. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your39983.9 balance Accountid 49 !!!
  1129. 2022-07-04 12:38:24.936432 90.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 1000 mins!
  1130. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 1000 minutes
  1131. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [DIALPLAN] User Rate ID : 1
  1132. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :0
  1133. 2022-07-04 12:38:24.936432 90.97% [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
  1134. 2022-07-04 12:38:24.936432 90.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 = '^10560540.*' OR pattern = '^1056054.*' OR pattern = '^105605.*' OR pattern = '^10560.*' OR pattern = '^1056.*' OR pattern = '^105.*' OR pattern = '^10.*' OR pattern = '^1.*' 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
  1135. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  1136. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 1
  1137. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^1.*
  1138. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : USA
  1139. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  1140. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  1141. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^1.*
  1142. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
  1143. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
  1144. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
  1145. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : YourProvider
  1146. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
  1147. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
  1148. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
  1149. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  1150. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
  1151. 2022-07-04 12:38:24.936432 90.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 = 49 AND status=0 LIMIT 1
  1152. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 1000
  1153. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1154. 2022-07-04 12:38:24.936432 90.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  1155. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
  1156. 2022-07-04 12:38:24.936432 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
  1157. 2022-07-04 12:38:24.936432 90.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
  1158. 2022-07-04 12:38:24.956417 90.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
  1159. 2022-07-04 12:38:24.956417 90.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 ="110560540" LIMIT 1
  1160. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1161. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
  1162. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  1163. <document type="freeswitch/xml">
  1164. <section name="dialplan" description="ASTPP Dialplan">
  1165. <context name="default">
  1166. <extension name="10560540">
  1167. <condition field="destination_number" expression="110560540">
  1168. <action application="set" data="effective_destination_number=10560540"/>
  1169. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  1170. <action application="set" data="bridge_pre_execute_bleg_data=+60000 normal_clearing"/>
  1171. <action application="set" data="notify_flag=0"/>
  1172. <action application="set" data="notify_credit_limit=5.00000"/>
  1173. <action application="set" data="type=0"/>
  1174. <action application="set" data="callstart=2022-07-04 12:38:24"/>
  1175. <action application="set" data="hangup_after_bridge=true"/>
  1176. <action application="set" data="continue_on_fail=TRUE"/>
  1177. <action application="set" data="account_id=49"/>
  1178. <action application="set" data="parent_id=0"/>
  1179. <action application="set" data="entity_id=0"/>
  1180. <action application="set" data="call_processed=internal"/>
  1181. <action application="set" data="call_direction=outbound"/>
  1182. <action application="set" data="accountname=default"/>
  1183. <action application="set" data="filename1=astpp.pbx.lua"/>
  1184. <action application="set" data="origination_rates=ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.20000|INC:60|INITIALBLOCK:30|RATEGROUP:1|MARKUP:0|CI:0|ACCID:49"/>
  1185. <action application="set" data="original_caller_id_name=QA_force"/>
  1186. <action application="set" data="original_caller_id_number=11"/>
  1187.  
  1188. <action application="set" data="effective_caller_id_name=QA_force"/>
  1189. <action application="set" data="effective_caller_id_number=11"/>
  1190. <action application="set" data="calltype=STANDARD"/>
  1191. <action application="set" data="termination_rates=ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:60|INITIALBLOCK:30|TRUNK:1|PROVIDER:3"/>
  1192. <action application="set" data="trunk_id=1"/>
  1193. <action application="set" data="provider_id=3"/>
  1194. <action application="set" data="rate_flag=0"/>
  1195. <action application="set" data="quality_trunk_seq=1"/>
  1196. <action application="set" data="force_trunk_flag=1"/>
  1197. <action application="export" data="presence_data=trunk_id=1"/>
  1198. <action application="set" data="intcall=0"/>
  1199. <action application="export" data="presence_id=10560540"/>
  1200. <action application="export" data="presence_data=x|||PK admin's Cust 420(9999998)|||^1.* // USA // 0.20000 |||YourTrunk // ^1.* // USA // 0.10000 // trunk_id=1|||STD|||103.240.35.46"/>
  1201. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/10560540"/>
  1202. </condition>
  1203. </extension>
  1204. </context>
  1205. </section>
  1206. </document>
  1207. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 parsing [default->10560540] continue=false
  1208. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Regex (PASS) [10560540] destination_number(110560540) =~ /110560540/ break=on-false
  1209. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_destination_number=10560540)
  1210. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  1211. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  1212. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(notify_flag=0)
  1213. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(notify_credit_limit=5.00000)
  1214. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(type=0)
  1215. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(callstart=2022-07-04 12:38:24)
  1216. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(hangup_after_bridge=true)
  1217. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(continue_on_fail=TRUE)
  1218. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(account_id=49)
  1219. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(parent_id=0)
  1220. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(entity_id=0)
  1221. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(call_processed=internal)
  1222. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(call_direction=outbound)
  1223. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(accountname=default)
  1224. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(filename1=astpp.pbx.lua)
  1225. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(origination_rates=ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.20000|INC:60|INITIALBLOCK:30|RATEGROUP:1|MARKUP:0|CI:0|ACCID:49)
  1226. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(original_caller_id_name=QA_force)
  1227. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(original_caller_id_number=11)
  1228. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_caller_id_name=QA_force)
  1229. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(effective_caller_id_number=11)
  1230. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(calltype=STANDARD)
  1231. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(termination_rates=ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:60|INITIALBLOCK:30|TRUNK:1|PROVIDER:3)
  1232. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(trunk_id=1)
  1233. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(provider_id=3)
  1234. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(rate_flag=0)
  1235. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(quality_trunk_seq=1)
  1236. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(force_trunk_flag=1)
  1237. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_data=trunk_id=1)
  1238. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action set(intcall=0)
  1239. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_id=10560540)
  1240. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action export(presence_data=x|||PK admin's Cust 420(9999998)|||^1.* // USA // 0.20000 |||YourTrunk // ^1.* // USA // 0.10000 // trunk_id=1|||STD|||103.240.35.46)
  1241. Dialplan: sofia/default/9999998@astppqa606.astppbilling.org:5074 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/10560540)
  1242. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:281 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State Change CS_ROUTING -> CS_EXECUTE
  1243. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State ROUTING going to sleep
  1244. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Running State Change CS_EXECUTE (Cur 1 Tot 54)
  1245. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:647 (sofia/default/9999998@astppqa606.astppbilling.org:5074) State EXECUTE
  1246. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_sofia.c:213 sofia/default/9999998@astppqa606.astppbilling.org:5074 SOFIA EXECUTE
  1247. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:323 sofia/default/9999998@astppqa606.astppbilling.org:5074 Standard EXECUTE
  1248. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_destination_number=10560540)
  1249. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_destination_number]=[10560540]
  1250. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(bridge_pre_execute_bleg_app=sched_hangup)
  1251. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [bridge_pre_execute_bleg_app]=[sched_hangup]
  1252. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(bridge_pre_execute_bleg_data=+60000 normal_clearing)
  1253. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [bridge_pre_execute_bleg_data]=[+60000 normal_clearing]
  1254. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(notify_flag=0)
  1255. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [notify_flag]=[0]
  1256. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(notify_credit_limit=5.00000)
  1257. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [notify_credit_limit]=[5.00000]
  1258. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(type=0)
  1259. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [type]=[0]
  1260. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(callstart=2022-07-04 12:38:24)
  1261. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [callstart]=[2022-07-04 12:38:24]
  1262. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(hangup_after_bridge=true)
  1263. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [hangup_after_bridge]=[true]
  1264. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(continue_on_fail=TRUE)
  1265. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [continue_on_fail]=[TRUE]
  1266. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(account_id=49)
  1267. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [account_id]=[49]
  1268. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(parent_id=0)
  1269. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [parent_id]=[0]
  1270. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(entity_id=0)
  1271. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [entity_id]=[0]
  1272. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(call_processed=internal)
  1273. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [call_processed]=[internal]
  1274. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(call_direction=outbound)
  1275. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [call_direction]=[outbound]
  1276. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(accountname=default)
  1277. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [accountname]=[default]
  1278. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(filename1=astpp.pbx.lua)
  1279. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [filename1]=[astpp.pbx.lua]
  1280. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(origination_rates=ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.20000|INC:60|INITIALBLOCK:30|RATEGROUP:1|MARKUP:0|CI:0|ACCID:49)
  1281. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [origination_rates]=[ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.20000|INC:60|INITIALBLOCK:30|RATEGROUP:1|MARKUP:0|CI:0|ACCID:49]
  1282. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(original_caller_id_name=QA_force)
  1283. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [original_caller_id_name]=[QA_force]
  1284. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(original_caller_id_number=11)
  1285. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [original_caller_id_number]=[11]
  1286. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_caller_id_name=QA_force)
  1287. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_caller_id_name]=[QA_force]
  1288. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(effective_caller_id_number=11)
  1289. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [effective_caller_id_number]=[11]
  1290. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(calltype=STANDARD)
  1291. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [calltype]=[STANDARD]
  1292. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(termination_rates=ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:60|INITIALBLOCK:30|TRUNK:1|PROVIDER:3)
  1293. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [termination_rates]=[ID:1|CODE:^1.*|DESTINATION:USA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.10000|INC:60|INITIALBLOCK:30|TRUNK:1|PROVIDER:3]
  1294. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(trunk_id=1)
  1295. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [trunk_id]=[1]
  1296. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(provider_id=3)
  1297. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [provider_id]=[3]
  1298. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(rate_flag=0)
  1299. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [rate_flag]=[0]
  1300. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(quality_trunk_seq=1)
  1301. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [quality_trunk_seq]=[1]
  1302. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(force_trunk_flag=1)
  1303. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [force_trunk_flag]=[1]
  1304. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_data=trunk_id=1)
  1305. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
  1306. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 set(intcall=0)
  1307. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_dptools.c:1685 SET sofia/default/9999998@astppqa606.astppbilling.org:5074 [intcall]=[0]
  1308. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_id=10560540)
  1309. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[10560540]
  1310. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 export(presence_data=x|||PK admin's Cust 420(9999998)|||^1.* // USA // 0.20000 |||YourTrunk // ^1.* // USA // 0.10000 // trunk_id=1|||STD|||103.240.35.46)
  1311. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||PK admin's Cust 420(9999998)|||^1.* // USA // 0.20000 |||YourTrunk // ^1.* // USA // 0.10000 // trunk_id=1|||STD|||103.240.35.46]
  1312. EXECUTE [depth=0] sofia/default/9999998@astppqa606.astppbilling.org:5074 bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/10560540)
  1313. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_data]=[x|||PK admin's Cust 420(9999998)|||^1.* // USA // 0.20000 |||YourTrunk // ^1.* // USA // 0.10000 // trunk_id=1|||STD|||103.240.35.46] to event
  1314. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_id]=[10560540] to event
  1315. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_channel.c:1269 sofia/default/9999998@astppqa606.astppbilling.org:5074 EXPORTING[export_vars] [presence_data]=[x|||PK admin's Cust 420(9999998)|||^1.* // USA // 0.20000 |||YourTrunk // ^1.* // USA // 0.10000 // trunk_id=1|||STD|||103.240.35.46] to event
  1316. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
  1317. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
  1318. 2022-07-04 12:38:24.956417 90.97% [NOTICE] switch_channel.c:1123 New Channel sofia/default/10560540 [e4e196de-7616-4495-a961-4916944c867d]
  1319. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_sofia.c:5121 (sofia/default/10560540) State Change CS_NEW -> CS_INIT
  1320. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_ivr_originate.c:3151 sofia/default/10560540 Setting leg timeout to 30
  1321. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/10560540) Running State Change CS_INIT (Cur 2 Tot 55)
  1322. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/10560540) State INIT
  1323. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_sofia.c:97 sofia/default/10560540 SOFIA INIT
  1324. 2022-07-04 12:38:24.956417 90.97% [INFO] sofia_glue.c:1651 sofia/default/10560540 sending invite call-id: (null)
  1325. 2022-07-04 12:38:24.956417 90.97% [DEBUG] sofia_glue.c:1654 sofia/default/10560540 sending invite version: 1.10.7 -release 64bit
  1326. Local SDP:
  1327. v=0
  1328. o=FreeSWITCH 1656909064 1656909065 IN IP4 142.132.184.134
  1329. s=FreeSWITCH
  1330. c=IN IP4 142.132.184.134
  1331. t=0 0
  1332. m=audio 29240 RTP/AVP 8 18 101
  1333. a=rtpmap:8 PCMA/8000
  1334. a=rtpmap:18 G729/8000
  1335. a=rtpmap:101 telephone-event/8000
  1336. a=fmtp:101 0-15
  1337. a=ptime:20
  1338. a=sendrecv
  1339.  
  1340. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:40 sofia/default/10560540 Standard INIT
  1341. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:48 (sofia/default/10560540) State Change CS_INIT -> CS_ROUTING
  1342. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:624 (sofia/default/10560540) State INIT going to sleep
  1343. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/10560540) Running State Change CS_ROUTING (Cur 2 Tot 55)
  1344. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/10560540) State ROUTING
  1345. 2022-07-04 12:38:24.956417 90.97% [DEBUG] mod_sofia.c:158 sofia/default/10560540 SOFIA ROUTING
  1346. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_ivr_originate.c:67 (sofia/default/10560540) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1347. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:640 (sofia/default/10560540) State ROUTING going to sleep
  1348. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/10560540) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 55)
  1349. 2022-07-04 12:38:24.956417 90.97% [DEBUG] sofia.c:7499 Channel sofia/default/10560540 entering state [calling][0]
  1350. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:659 (sofia/default/10560540) State CONSUME_MEDIA
  1351. 2022-07-04 12:38:24.956417 90.97% [DEBUG] switch_core_state_machine.c:659 (sofia/default/10560540) State CONSUME_MEDIA going to sleep
  1352. 2022-07-04 12:38:25.036423 90.97% [DEBUG] sofia.c:7499 Channel sofia/default/10560540 entering state [completing][200]
  1353. 2022-07-04 12:38:25.036423 90.97% [DEBUG] sofia.c:7509 Remote SDP:
  1354. v=0
  1355. o=root 1075444853 1075444853 IN IP4 159.203.131.36
  1356. s=Asterisk PBX 16.4.0
  1357. c=IN IP4 159.203.131.36
  1358. t=0 0
  1359. m=audio 18398 RTP/AVP 8 101
  1360. a=rtpmap:8 PCMA/8000
  1361. a=rtpmap:101 telephone-event/8000
  1362. a=fmtp:101 0-16
  1363. a=maxptime:150
  1364.  
  1365. 2022-07-04 12:38:25.036423 90.97% [DEBUG] sofia.c:7499 Channel sofia/default/10560540 entering state [ready][200]
  1366. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  1367. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1368. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  1369. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  1370. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/10560540 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1371. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_codec.c:111 sofia/default/10560540 Original read codec set to PCMA:8
  1372. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  1373. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:5973 sofia/default/10560540 Set 2833 dtmf send payload to 101 recv payload to 101
  1374. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/10560540] 142.132.184.134 port 29240 -> 159.203.131.36 port 18398 codec: 8 ms: 20
  1375. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  1376. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:9089 sofia/default/10560540 Set 2833 dtmf send payload to 101
  1377. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:9096 sofia/default/10560540 Set 2833 dtmf receive payload to 101
  1378. 2022-07-04 12:38:25.036423 90.97% [DEBUG] switch_core_media.c:9119 sofia/default/10560540 Set rtp dtmf delay to 40
  1379. 2022-07-04 12:38:25.036423 90.97% [NOTICE] sofia.c:8683 Channel [sofia/default/10560540] has been answered
  1380. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_channel.c:3950 (sofia/default/10560540) Callstate Change DOWN -> ACTIVE
  1381. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/9999998@astppqa606.astppbilling.org:5074] 142.132.184.134 port 19206 -> 103.240.35.46 port 8000 codec: 8 ms: 20
  1382. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
  1383. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_core_media.c:9089 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf send payload to 101
  1384. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_core_media.c:9096 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set 2833 dtmf receive payload to 101
  1385. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_core_media.c:9119 sofia/default/9999998@astppqa606.astppbilling.org:5074 Set rtp dtmf delay to 40
  1386. 2022-07-04 12:38:25.056425 90.97% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/9999998@astppqa606.astppbilling.org:5074:
  1387. v=0
  1388. o=FreeSWITCH 1656919099 1656919100 IN IP4 142.132.184.134
  1389. s=FreeSWITCH
  1390. c=IN IP4 142.132.184.134
  1391. t=0 0
  1392. m=audio 19206 RTP/AVP 8 101
  1393. a=rtpmap:8 PCMA/8000
  1394. a=rtpmap:101 telephone-event/8000
  1395. a=fmtp:101 0-15
  1396. a=ptime:20
  1397. a=sendrecv
  1398.  
  1399. 2022-07-04 12:38:25.056425 90.97% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/9999998@astppqa606.astppbilling.org:5074] has been answered
  1400. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_channel.c:3950 (sofia/default/9999998@astppqa606.astppbilling.org:5074) Callstate Change RINGING -> ACTIVE
  1401. 2022-07-04 12:38:25.056425 90.97% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [completed][200]
  1402. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/10560540] Peer UUID: e4e196de-7616-4495-a961-4916944c867d
  1403. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/10560540) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1404. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_core_state_machine.c:581 (sofia/default/10560540) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 55)
  1405. 2022-07-04 12:38:25.056425 90.97% [DEBUG] switch_core_state_machine.c:650 (sofia/default/10560540) State EXCHANGE_MEDIA
  1406. 2022-07-04 12:38:25.056425 90.97% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  1407. 2022-07-04 12:38:25.236425 90.97% [DEBUG] sofia.c:7499 Channel sofia/default/9999998@astppqa606.astppbilling.org:5074 entering state [ready][200]
  1408. 2022-07-04 12:38:25.236425 90.97% [DEBUG] switch_ivr_bridge.c:655 sofia/default/10560540 Bridge execute app sched_hangup(+60000 normal_clearing)
  1409. 2022-07-04 12:38:25.256430 90.97% [DEBUG] switch_ivr.c:632 sofia/default/10560540 Command Execute [depth=0] sched_hangup(+60000 normal_clearing)
  1410. EXECUTE [depth=0] sofia/default/10560540 sched_hangup(+60000 normal_clearing)
  1411. 2022-07-04 12:38:25.256430 90.97% [DEBUG] switch_scheduler.c:263 Added task 23 switch_ivr_schedule_hangup (e4e196de-7616-4495-a961-4916944c867d) to run at 1656998305
  1412. 2022-07-04 12:38:25.276437 90.97% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  1413. 2022-07-04 12:38:25.416418 90.97% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
  1414. freeswitch@devangASTPP>
  1415. freeswitch@devangASTPP>
  1416. freeswitch@devangASTPP>
  1417. freeswitch@devangASTPP

Reply to "DST based caller id Localization issue force apply"