FreeSWITCH Pastebin

test

From test, 4 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/8b438b73
Embed
Download Paste or View Raw
  1. span style="color: goldenrod; font-weight: bold;"> 2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:829 sofia/default/+233278910821 ending bridge by request from read function
  2. 2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233278910821]
  3. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA going to sleep
  4. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_HANGUP (Cur 22 Tot 4674736)
  5. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233278910821) Callstate Change EARLY -> HANGUP
  6. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP
  7. 2020-03-16 07:59:12.014649 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233278910821 hanging up, cause: NO_USER_RESPONSE
  8. 2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:823 sofia/default/+233278910821 ending bridge by request from write function
  9. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233278910821 Standard HANGUP, cause: NO_USER_RESPONSE
  10. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP going to sleep
  11. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233278910821) State Change CS_HANGUP -> CS_REPORTING
  12. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_REPORTING (Cur 22 Tot 4674736)
  13. 2020-03-16 07:59:12.014649 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING
  14. 2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233242431111@196.44.101.68:5060]
  15. 2020-03-16 07:59:12.014649 [INFO] mod_json_cdr.c:271 Process [bf638467-cfba-408c-812b-67515ac8a216.cdr.json]
  16. 2020-03-16 07:59:12.014649 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/+233278910821 skip receive message [UNBRIDGE] (channel is hungup already)
  17. 2020-03-16 07:59:12.014649 [NOTICE] switch_core_state_machine.c:386 sofia/default/+233242431111@196.44.101.68:5060 has executed the last dialplan instruction, hanging up.
  18. 2020-03-16 07:59:12.014649 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/+233242431111@196.44.101.68:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  19. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE going to sleep
  20. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_HANGUP (Cur 22 Tot 4674736)
  21. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change EARLY -> HANGUP
  22. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP
  23. 2020-03-16 07:59:12.034597 [DEBUG] mod_sofia.c:447 sofia/default/+233242431111@196.44.101.68:5060 Overriding SIP cause 480 with 480 from the other leg
  24. 2020-03-16 07:59:12.034597 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233242431111@196.44.101.68:5060 hanging up, cause: NORMAL_CLEARING
  25. 2020-03-16 07:59:12.034597 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
  26. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233242431111@196.44.101.68:5060 Standard HANGUP, cause: NORMAL_CLEARING
  27. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP going to sleep
  28. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_HANGUP -> CS_REPORTING
  29. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_REPORTING (Cur 22 Tot 4674736)
  30. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING
  31. 2020-03-16 07:59:12.034597 [INFO] mod_json_cdr.c:271 Process [8fc5b222-56b8-4eda-8417-b85cc663451f.cdr.json]
  32. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233278910821 Standard REPORTING, cause: NO_USER_RESPONSE
  33. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING going to sleep
  34. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233278910821) State Change CS_REPORTING -> CS_DESTROY
  35. 2020-03-16 07:59:12.034597 [DEBUG] switch_core_session.c:1726 Session 4674727 (sofia/default/+233278910821) Locked, Waiting on external entities
  36. 2020-03-16 07:59:12.034597 [NOTICE] switch_core_session.c:1744 Session 4674727 (sofia/default/+233278910821) Ended
  37. 2020-03-16 07:59:12.034597 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233278910821 [CS_DESTROY]
  38. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233278910821) Running State Change CS_DESTROY (Cur 21 Tot 4674736)
  39. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY
  40. 2020-03-16 07:59:12.054601 [DEBUG] mod_sofia.c:364 sofia/default/+233278910821 SOFIA DESTROY
  41. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233278910821 Standard DESTROY
  42. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY going to sleep
  43. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233242431111@196.44.101.68:5060 Standard REPORTING, cause: NORMAL_CLEARING
  44. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING going to sleep
  45. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_REPORTING -> CS_DESTROY
  46. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_session.c:1726 Session 4674726 (sofia/default/+233242431111@196.44.101.68:5060) Locked, Waiting on external entities
  47. 2020-03-16 07:59:12.054601 [NOTICE] switch_core_session.c:1744 Session 4674726 (sofia/default/+233242431111@196.44.101.68:5060) Ended
  48. 2020-03-16 07:59:12.054601 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233242431111@196.44.101.68:5060 [CS_DESTROY]
  49. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_DESTROY (Cur 20 Tot 4674736)
  50. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY
  51. 2020-03-16 07:59:12.054601 [DEBUG] mod_sofia.c:364 sofia/default/+233242431111@196.44.101.68:5060 SOFIA DESTROY
  52. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233242431111@196.44.101.68:5060 Standard DESTROY
  53. 2020-03-16 07:59:12.054601 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY going to sleep
  54. 2020-03-16 07:59:12.334581 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233242431111@196.44.101.68:5060 [ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2]
  55. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_NEW (Cur 21 Tot 4674737)
  56. 2020-03-16 07:59:12.334581 [DEBUG] sofia.c:10255 sofia/default/+233242431111@196.44.101.68:5060 receiving invite from 197.251.229.242:1025 version: 1.10.2 -release-13-f7bdd3845a 64bit
  57. 2020-03-16 07:59:12.334581 [DEBUG] sofia.c:10349 verifying acl "default" for ip/port 197.251.229.242:0.
  58. 2020-03-16 07:59:12.334581 [DEBUG] sofia.c:10378 IP 197.251.229.242 Approved by acl "default[]". Access Granted.
  59. 2020-03-16 07:59:12.334581 [DEBUG] sofia.c:11452 Setting NAT mode based on via port
  60. 2020-03-16 07:59:12.334581 [DEBUG] sofia.c:7301 Channel sofia/default/+233242431111@196.44.101.68:5060 entering state [received][100]
  61. 2020-03-16 07:59:12.334581 [DEBUG] sofia.c:7311 Remote SDP:
  62. v=0
  63. o=3cxPS 405404647424 207215394817 IN IP4 197.251.229.242
  64. s=3cxPS Audio call
  65. c=IN IP4 197.251.229.242
  66. t=0 0
  67. m=audio 9030 RTP/SAVP 0 8 18 101
  68. a=rtpmap:0 PCMU/8000
  69. a=rtpmap:8 PCMA/8000
  70. a=rtpmap:18 G729/8000
  71. a=fmtp:18 annexb=no
  72. a=rtpmap:101 telephone-event/8000
  73. a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1
  74. a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:Ci72RZdgckx3BvzPUpU+8LBFxvR/c5cBM5Q4w4X0
  75. m=audio 9030 RTP/AVP 0 8 18 101
  76. a=rtpmap:0 PCMU/8000
  77. a=rtpmap:8 PCMA/8000
  78. a=rtpmap:18 G729/8000
  79. a=fmtp:18 annexb=no
  80. a=rtpmap:101 telephone-event/8000
  81.  
  82. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AEAD_AES_256_GCM_8]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
  83. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AEAD_AES_128_GCM_8]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
  84. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_256_CM_HMAC_SHA1_80]alias=[AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
  85. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_192_CM_HMAC_SHA1_80]alias=[AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
  86. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_CM_128_HMAC_SHA1_80]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
  87. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1822 Found suite AES_CM_128_HMAC_SHA1_80
  88. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1892 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:CupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1]
  89. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:1215 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:6YMmvJXhb18M+MflfSXZ+IUKx34vSMtS1bkUQ+wn]
  90. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  91. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  92. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  93. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  94. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  95. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  96. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  97. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  98. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  99. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  100. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  101. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  102. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
  103. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233242431111@196.44.101.68:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
  104. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_codec.c:111 sofia/default/+233242431111@196.44.101.68:5060 Original read codec set to G729:18
  105. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
  106. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_media.c:5909 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  107. 2020-03-16 07:59:12.334581 [DEBUG] sofia.c:7735 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_NEW -> CS_INIT
  108. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:604 (sofia/default/+233242431111@196.44.101.68:5060) State NEW
  109. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_INIT (Cur 21 Tot 4674737)
  110. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT
  111. 2020-03-16 07:59:12.334581 [DEBUG] mod_sofia.c:93 sofia/default/+233242431111@196.44.101.68:5060 SOFIA INIT
  112. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233242431111@196.44.101.68:5060 Standard INIT
  113. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_INIT -> CS_ROUTING
  114. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT going to sleep
  115. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_ROUTING (Cur 21 Tot 4674737)
  116. 2020-03-16 07:59:12.334581 [DEBUG] switch_channel.c:2332 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change DOWN -> RINGING
  117. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING
  118. 2020-03-16 07:59:12.334581 [DEBUG] mod_sofia.c:154 sofia/default/+233242431111@196.44.101.68:5060 SOFIA ROUTING
  119. 2020-03-16 07:59:12.334581 [DEBUG] switch_core_state_machine.c:236 sofia/default/+233242431111@196.44.101.68:5060 Standard ROUTING
  120. 2020-03-16 07:59:12.334581 [INFO] mod_dialplan_xml.c:637 Processing +233242431111 <+233242431111>->233278910821 in context default
  121. 2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  122. 2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  123. 2020-03-16 07:59:12.354642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
  124. Event-Name: REQUEST_PARAMS
  125. Core-UUID: 021f676c-1b86-4c5d-ab5d-83652fc892b0
  126. FreeSWITCH-Hostname: rack-astpp
  127. FreeSWITCH-Switchname: rack-astpp
  128. FreeSWITCH-IPv4: 196.44.101.68
  129. FreeSWITCH-IPv6: %3A%3A1
  130. Event-Date-Local: 2020-03-16%2007%3A59%3A12
  131. Event-Date-GMT: Mon,%2016%20Mar%202020%2007%3A59%3A12%20GMT
  132. Event-Date-Timestamp: 1584345552334581
  133. Event-Calling-File: mod_dialplan_xml.c
  134. Event-Calling-Function: dialplan_xml_locate
  135. Event-Calling-Line-Number: 608
  136. Event-Sequence: 281836657
  137. Channel-State: CS_ROUTING
  138. Channel-Call-State: RINGING
  139. Channel-State-Number: 2
  140. Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  141. Unique-ID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
  142. Call-Direction: inbound
  143. Presence-Call-Direction: inbound
  144. Channel-HIT-Dialplan: true
  145. Channel-Presence-ID: %2B233242431111%40196.44.101.68
  146. Channel-Call-UUID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
  147. Answer-State: ringing
  148. Channel-Read-Codec-Name: G729
  149. Channel-Read-Codec-Rate: 8000
  150. Channel-Read-Codec-Bit-Rate: 8000
  151. Channel-Write-Codec-Name: G729
  152. Channel-Write-Codec-Rate: 8000
  153. Channel-Write-Codec-Bit-Rate: 8000
  154. Caller-Direction: inbound
  155. Caller-Logical-Direction: inbound
  156. Caller-Username: %2B233242431111
  157. Caller-Dialplan: XML
  158. Caller-Caller-ID-Name: %2B233242431111
  159. Caller-Caller-ID-Number: %2B233242431111
  160. Caller-Orig-Caller-ID-Name: %2B233242431111
  161. Caller-Orig-Caller-ID-Number: %2B233242431111
  162. Caller-Network-Addr: 197.251.229.242
  163. Caller-ANI: %2B233242431111
  164. Caller-Destination-Number: 233278910821
  165. Caller-Unique-ID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
  166. Caller-Source: mod_sofia
  167. Caller-Context: default
  168. Caller-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  169. Caller-Profile-Index: 1
  170. Caller-Profile-Created-Time: 1584345552334581
  171. Caller-Channel-Created-Time: 1584345552334581
  172. Caller-Channel-Answered-Time: 0
  173. Caller-Channel-Progress-Time: 0
  174. Caller-Channel-Progress-Media-Time: 0
  175. Caller-Channel-Hangup-Time: 0
  176. Caller-Channel-Transfer-Time: 0
  177. Caller-Channel-Resurrect-Time: 0
  178. Caller-Channel-Bridged-Time: 0
  179. Caller-Channel-Last-Hold: 0
  180. Caller-Channel-Hold-Accum: 0
  181. Caller-Screen-Bit: true
  182. Caller-Privacy-Hide-Name: false
  183. Caller-Privacy-Hide-Number: false
  184. variable_direction: inbound
  185. variable_uuid: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
  186. variable_session_id: 4674737
  187. variable_sip_from_user: %2B233242431111
  188. variable_sip_from_port: 5060
  189. variable_sip_from_uri: %2B233242431111%40196.44.101.68%3A5060
  190. variable_sip_from_host: 196.44.101.68
  191. variable_video_media_flow: disabled
  192. variable_text_media_flow: disabled
  193. variable_channel_name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  194. variable_sip_call_id: wP2JVVhYg-Wt4ibzjESbzg..
  195. variable_sip_local_network_addr: 196.44.101.68
  196. variable_sip_network_ip: 197.251.229.242
  197. variable_sip_network_port: 1025
  198. variable_sip_invite_stamp: 1584345552334581
  199. variable_sip_received_ip: 197.251.229.242
  200. variable_sip_received_port: 1025
  201. variable_sip_via_protocol: udp
  202. variable_sip_authorized: true
  203. variable_sip_acl_authed_by: default
  204. variable_sip_from_user_stripped: 233242431111
  205. variable_sip_from_tag: 9730ec1a
  206. variable_sofia_profile_name: default
  207. variable_sofia_profile_url: sip%3Amod_sofia%40196.44.101.68%3A5060
  208. variable_recovery_profile_name: default
  209. variable_sip_Remote-Party-ID: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Bparty%3Dcalling
  210. variable_sip_cid_type: rpid
  211. variable_sip_full_via: SIP/2.0/UDP%20197.251.229.242%3A5060%3Bbranch%3Dz9hG4bK-524287-1---291d2253dc300f75%3Brport%3D1025
  212. variable_sip_from_display: %2B233242431111
  213. variable_sip_full_from: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Btag%3D9730ec1a
  214. variable_sip_full_to: %3Csip%3A233278910821%40196.44.101.68%3A5060%3E
  215. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REGISTER,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20INFO,%20MESSAGE,%20UPDATE
  216. variable_sip_req_user: 233278910821
  217. variable_sip_req_port: 5060
  218. variable_sip_req_uri: 233278910821%40196.44.101.68%3A5060
  219. variable_sip_req_host: 196.44.101.68
  220. variable_sip_to_user: 233278910821
  221. variable_sip_to_port: 5060
  222. variable_sip_to_uri: 233278910821%40196.44.101.68%3A5060
  223. variable_sip_to_host: 196.44.101.68
  224. variable_sip_contact_user: %2B233242431111
  225. variable_sip_contact_port: 5060
  226. variable_sip_contact_uri: %2B233242431111%40197.251.229.242%3A5060
  227. variable_sip_contact_host: 197.251.229.242
  228. variable_sip_user_agent: 3CXPhoneSystem%2015.5.15502.6%20(15502)
  229. variable_sip_via_host: 197.251.229.242
  230. variable_sip_via_port: 5060
  231. variable_sip_via_rport: 1025
  232. variable_max_forwards: 70
  233. variable_presence_id: %2B233242431111%40196.44.101.68
  234. variable_sip_nat_detected: true
  235. variable_switch_r_sdp: v%3D0%0D%0Ao%3D3cxPS%20405404647424%20207215394817%20IN%20IP4%20197.251.229.242%0D%0As%3D3cxPS%20Audio%20call%0D%0Ac%3DIN%20IP4%20197.251.229.242%0D%0At%3D0%200%0D%0Am%3Daudio%209030%20RTP/SAVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dcrypto%3A1%20AES_CM_128_HMAC_SHA1_80%20inline%3ACupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1%0D%0Aa%3Dcrypto%3A2%20AES_CM_128_HMAC_SHA1_32%20inline%3ACi72RZdgckx3BvzPUpU%2B8LBFxvR/c5cBM5Q4w4X0%0D%0Am%3Daudio%209030%20RTP/AVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0A
  236. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b
  237. variable_rtp_use_codec_string: G729,PCMA,PCMU
  238. variable_remote_video_media_flow: inactive
  239. variable_remote_text_media_flow: inactive
  240. variable_remote_audio_media_flow: sendrecv
  241. variable_audio_media_flow: sendrecv
  242. variable_rtp_secure_media_negotiated: AES_CM_128_HMAC_SHA1_80
  243. variable_srtp_remote_audio_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3ACupwgC7nhku2GRWVq0ISseZPCeFdlkBncROGyqI1
  244. variable_srtp_remote_audio_crypto_type: AES_CM_128_HMAC_SHA1_80
  245. variable_rtp_has_crypto: AES_CM_128_HMAC_SHA1_80
  246. variable_rtp_last_audio_local_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3A6YMmvJXhb18M%2BMflfSXZ%2BIUKx34vSMtS1bkUQ%2Bwn
  247. variable_remote_media_ip: 197.251.229.242
  248. variable_remote_media_port: 9030
  249. variable_rtp_audio_recv_pt: 18
  250. variable_rtp_use_codec_name: G729
  251. variable_rtp_use_codec_fmtp: annexb%3Dno
  252. variable_rtp_use_codec_rate: 8000
  253. variable_rtp_use_codec_ptime: 20
  254. variable_rtp_use_codec_channels: 1
  255. variable_rtp_last_audio_codec_string: G729%408000h%4020i%401c
  256. variable_read_codec: G729
  257. variable_original_read_codec: G729
  258. variable_read_rate: 8000
  259. variable_original_read_rate: 8000
  260. variable_write_codec: G729
  261. variable_write_rate: 8000
  262. variable_dtmf_type: rfc2833
  263. variable_endpoint_disposition: RECEIVED
  264. variable_call_uuid: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
  265. Hunt-Direction: inbound
  266. Hunt-Logical-Direction: inbound
  267. Hunt-Username: %2B233242431111
  268. Hunt-Dialplan: XML
  269. Hunt-Caller-ID-Name: %2B233242431111
  270. Hunt-Caller-ID-Number: %2B233242431111
  271. Hunt-Orig-Caller-ID-Name: %2B233242431111
  272. Hunt-Orig-Caller-ID-Number: %2B233242431111
  273. Hunt-Network-Addr: 197.251.229.242
  274. Hunt-ANI: %2B233242431111
  275. Hunt-Destination-Number: 233278910821
  276. Hunt-Unique-ID: ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2
  277. Hunt-Source: mod_sofia
  278. Hunt-Context: default
  279. Hunt-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  280. Hunt-Profile-Index: 1
  281. Hunt-Profile-Created-Time: 1584345552334581
  282. Hunt-Channel-Created-Time: 1584345552334581
  283. Hunt-Channel-Answered-Time: 0
  284. Hunt-Channel-Progress-Time: 0
  285. Hunt-Channel-Progress-Media-Time: 0
  286. Hunt-Channel-Hangup-Time: 0
  287. Hunt-Channel-Transfer-Time: 0
  288. Hunt-Channel-Resurrect-Time: 0
  289. Hunt-Channel-Bridged-Time: 0
  290. Hunt-Channel-Last-Hold: 0
  291. Hunt-Channel-Hold-Accum: 0
  292. Hunt-Screen-Bit: true
  293. Hunt-Privacy-Hide-Name: false
  294. Hunt-Privacy-Hide-Number: false
  295.  
  296.  
  297. 2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name:
  298. 2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value:
  299. 2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name:
  300. 2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
  301. 2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 233278910821
  302. 2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '233278910821' AND status=0 limit 1
  303. 2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : +233242431111 / +233242431111
  304. 2020-03-16 07:59:12.354642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  305. 2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  306. 2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
  307. 2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
  308. 2020-03-16 07:59:12.354642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : outbound
  309. 2020-03-16 07:59:12.354642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("197.251.229.242") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "233278910821"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  310. 2020-03-16 07:59:12.374642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 6425492662]
  311. 2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "6425492662" AND deleted = 0 limit 1
  312. 2020-03-16 07:59:12.374642 [WARNING] switch_core_state_machine.c:688 74301954-09ed-408c-8322-28f2adfa25b2 sofia/default/100@196.44.101.68 Abandoned
  313. 2020-03-16 07:59:12.374642 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/100@196.44.101.68 [CS_NEW] [WRONG_CALL_STATE]
  314. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:585 (sofia/default/100@196.44.101.68) Running State Change CS_HANGUP (Cur 21 Tot 4674737)
  315. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:848 (sofia/default/100@196.44.101.68) Callstate Change DOWN -> HANGUP
  316. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@196.44.101.68) State HANGUP
  317. 2020-03-16 07:59:12.374642 [DEBUG] mod_sofia.c:453 Channel sofia/default/100@196.44.101.68 hanging up, cause: WRONG_CALL_STATE
  318. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:60 sofia/default/100@196.44.101.68 Standard HANGUP, cause: WRONG_CALL_STATE
  319. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:850 (sofia/default/100@196.44.101.68) State HANGUP going to sleep
  320. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:620 (sofia/default/100@196.44.101.68) State Change CS_HANGUP -> CS_REPORTING
  321. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:585 (sofia/default/100@196.44.101.68) Running State Change CS_REPORTING (Cur 21 Tot 4674737)
  322. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:936 (sofia/default/100@196.44.101.68) State REPORTING
  323. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:174 sofia/default/100@196.44.101.68 Standard REPORTING, cause: WRONG_CALL_STATE
  324. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:936 (sofia/default/100@196.44.101.68) State REPORTING going to sleep
  325. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:611 (sofia/default/100@196.44.101.68) State Change CS_REPORTING -> CS_DESTROY
  326. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_session.c:1726 Session 4674723 (sofia/default/100@196.44.101.68) Locked, Waiting on external entities
  327. 2020-03-16 07:59:12.374642 [NOTICE] switch_core_session.c:1744 Session 4674723 (sofia/default/100@196.44.101.68) Ended
  328. 2020-03-16 07:59:12.374642 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/100@196.44.101.68 [CS_DESTROY]
  329. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:739 (sofia/default/100@196.44.101.68) Running State Change CS_DESTROY (Cur 20 Tot 4674737)
  330. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:749 (sofia/default/100@196.44.101.68) State DESTROY
  331. 2020-03-16 07:59:12.374642 [DEBUG] mod_sofia.c:364 sofia/default/100@196.44.101.68 SOFIA DESTROY
  332. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:181 sofia/default/100@196.44.101.68 Standard DESTROY
  333. 2020-03-16 07:59:12.374642 [DEBUG] switch_core_state_machine.c:749 (sofia/default/100@196.44.101.68) State DESTROY going to sleep
  334. 2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^233278910821.*' OR blocked_patterns = '^23327891082.*' OR blocked_patterns = '^2332789108.*' OR blocked_patterns = '^233278910.*' OR blocked_patterns = '^23327891.*' OR blocked_patterns = '^2332789.*' OR blocked_patterns = '^233278.*' OR blocked_patterns = '^23327.*' OR blocked_patterns = '^2332.*' OR blocked_patterns = '^233.*' OR blocked_patterns = '^23.*' OR blocked_patterns = '^2.*' OR blocked_patterns ='--') AND accountid = 1367 limit 1
  335. 2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [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 ="233278910821" AND A.accountid = '1367' limit 1
  336. 2020-03-16 07:59:12.374642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 233278910821
  337. 2020-03-16 07:59:12.374642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  338. 2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  339. 2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
  340. 2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
  341. 2020-03-16 07:59:12.374642 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : outbound
  342. 2020-03-16 07:59:12.374642 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  343. 2020-03-16 07:59:12.374642 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  344. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="233278910821" AND (accountid = 0 OR status = 1) LIMIT 1
  345. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^233278910821.*' OR patterns = '^23327891082.*' OR patterns = '^2332789108.*' OR patterns = '^233278910.*' OR patterns = '^23327891.*' OR patterns = '^2332789.*' OR patterns = '^233278.*' OR patterns = '^23327.*' OR patterns = '^2332.*' OR patterns = '^233.*' OR patterns = '^23.*' OR patterns = '^2.*' OR patterns ='--') AND accountid = 1367 ORDER BY LENGTH(PKGPTR.patterns) DESC
  346. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '233278910821' OR number = '23327891082' OR number = '2332789108' OR number = '233278910' OR number = '23327891' OR number = '2332789' OR number = '233278' OR number = '23327' OR number = '2332' OR number = '233' OR number = '23' OR number = '2' OR number ='--') OR (number = '233278910821*' OR number = '23327891082*' OR number = '2332789108*' OR number = '233278910*' OR number = '23327891*' OR number = '2332789*' OR number = '233278*' OR number = '23327*' OR number = '2332*' OR number = '233*' OR number = '23*' OR number = '2*' OR number ='--') OR (number = '+233242431111' OR number = '+23324243111' OR number = '+2332424311' OR number = '+233242431' OR number = '+23324243' OR number = '+2332424' OR number = '+233242' OR number = '+23324' OR number = '+2332' OR number = '+233' OR number = '+23' OR number = '+2' OR number = '+' OR number ='--') OR (number = '+233242431111*' OR number = '+23324243111*' OR number = '+2332424311*' OR number = '+233242431*' OR number = '+23324243*' OR number = '+2332424*' OR number = '+233242*' OR number = '+23324*' OR number = '+2332*' OR number = '+233*' OR number = '+23*' OR number = '+2*' OR number = '+*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  347. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
  348. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Caller Id : +233242431111
  349. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] CLI Pool Id : 1
  350. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Query :Select count(*) as count from dids where number='+233242431111' and status=0
  351. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_COUNT] COUNT: 0
  352. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CIDPOOL] Query :Select number from dids where status=0 and accountid='1367' order by RAND() limit 1
  353. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '233278910821' OR routing_prefix = '23327891082' OR routing_prefix = '2332789108' OR routing_prefix = '233278910' OR routing_prefix = '23327891' OR routing_prefix = '2332789' OR routing_prefix = '233278' OR routing_prefix = '23327' OR routing_prefix = '2332' OR routing_prefix = '233' OR routing_prefix = '23' OR routing_prefix = '2' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  354. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
  355. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 1367
  356. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 6425492662
  357. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 1347.2088
  358. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  359. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 40
  360. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 1
  361. 2020-03-16 07:59:12.394601 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
  362. 2020-03-16 07:59:12.394601 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH1: 2
  363. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 2 AND status=0 limit 1
  364. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
  365. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :outbound
  366. 2020-03-16 07:59:12.394601 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 40 OR accountid=1367)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
  367. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: outbound
  368. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information ===================
  369. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 1144850
  370. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
  371. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0
  372. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.16000
  373. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : GH-MOB-TIGO
  374. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 0
  375. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 1367
  376. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID: 0
  377. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Routing type:
  378. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================
  379. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your1347.2088 balance Accountid 1367 !!!
  380. 2020-03-16 07:59:12.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 24 mins!
  381. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 24 minutes
  382. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [DIALPLAN] User Rate ID : 1144850
  383. 2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id  :0
  384. 2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=40 ORDER by id asc
  385. 2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,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 = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (7) ORDER by TR.cost ASC,TR.precedence ASC, TK.precedence
  386. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information ===================
  387. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 548686
  388. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^233.*
  389. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : Ghana Mob
  390. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
  391. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0
  392. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^233.*
  393. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip :
  394. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : +
  395. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 7
  396. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : MTN_LOCAL
  397. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable :
  398. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway :
  399. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 17
  400. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0
  401. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : MTN_LOCAL
  402. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES=======================
  403. 2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1367 AND status=0 LIMIT 1
  404. 2020-03-16 07:59:12.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 24
  405. 2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
  406. 2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate FROM localization WHERE id=(SELECT localization_id from accounts where id = 17) AND status=0 limit 1
  407. 2020-03-16 07:59:12.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 233278910821
  408. 2020-03-16 07:59:12.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : +233278910821
  409. 2020-03-16 07:59:12.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:
  410. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  411. <document type="freeswitch/xml">
  412. <section name="dialplan" description="ASTPP Dialplan">
  413. <context name="default">
  414. <extension name="233278910821">
  415. <condition field="destination_number" expression="233278910821">
  416. <action application="set" data="effective_destination_number=233278910821"/>
  417. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  418. <action application="set" data="bridge_pre_execute_bleg_data=+1440 normal_clearing"/>
  419. <action application="set" data="callstart=2020-03-16 07:59:12"/>
  420. <action application="set" data="hangup_after_bridge=true"/>
  421. <action application="set" data="continue_on_fail=TRUE"/>
  422. <action application="set" data="account_id=1367"/>
  423. <action application="set" data="parent_id=0"/>
  424. <action application="set" data="entity_id=0"/>
  425. <action application="set" data="call_processed=internal"/>
  426. <action application="set" data="call_direction=outbound"/>
  427. <action application="set" data="accountname=PEG IP"/>
  428. <action application="export" data="t38_passthru=true"/>
  429. <action application="set" data="fax_enable_t38=true"/>
  430. <action application="set" data="fax_enable_t38_request=true"/>
  431. <action application="set" data="origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367"/>
  432. <action application="set" data="original_caller_id_name=+233242431111"/>
  433. <action application="set" data="original_caller_id_number=0242436881"/>
  434. <action application="limit" data="db 6425492662 user_6425492662 90 !SWITCH_CONGESTION"/>
  435.  
  436. <action application="set" data="effective_caller_id_name=+233242431111"/>
  437. <action application="set" data="effective_caller_id_number=+233242431111"/>
  438. <action application="set" data="calltype=STANDARD"/>
  439. <action application="set" data="termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17"/>
  440. <action application="set" data="trunk_id=7"/>
  441. <action application="set" data="provider_id=17"/>
  442. <action application="set" data="rate_flag=1"/>
  443. <action application="set" data="force_trunk_flag=1144850"/>
  444. <action application="export" data="presence_data=trunk_id=7"/>
  445. <action application="set" data="intcall=0"/>
  446. <action application="export" data="presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242"/>
  447. <action application="bridge" data="[leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821"/>
  448. </condition>
  449. </extension>
  450. </context>
  451. </section>
  452. </document>
  453. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 parsing [default->233278910821] continue=false
  454. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Regex (PASS) [233278910821] destination_number(233278910821) =~ /233278910821/ break=on-false
  455. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_destination_number=233278910821)
  456. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  457. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_data=+1440 normal_clearing)
  458. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(callstart=2020-03-16 07:59:12)
  459. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(hangup_after_bridge=true)
  460. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(continue_on_fail=TRUE)
  461. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(account_id=1367)
  462. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(parent_id=0)
  463. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(entity_id=0)
  464. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_processed=internal)
  465. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_direction=outbound)
  466. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(accountname=PEG IP)
  467. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(t38_passthru=true)
  468. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38=true)
  469. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38_request=true)
  470. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367)
  471. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_name=+233242431111)
  472. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_number=0242436881)
  473. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION)
  474. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_name=+233242431111)
  475. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_number=+233242431111)
  476. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(calltype=STANDARD)
  477. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17)
  478. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(trunk_id=7)
  479. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(provider_id=17)
  480. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(rate_flag=1)
  481. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(force_trunk_flag=1144850)
  482. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=trunk_id=7)
  483. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(intcall=0)
  484. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242)
  485. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821)
  486. 2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:287 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_ROUTING -> CS_EXECUTE
  487. 2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING going to sleep
  488. 2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_EXECUTE (Cur 20 Tot 4674737)
  489. 2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE
  490. 2020-03-16 07:59:12.414599 [DEBUG] mod_sofia.c:209 sofia/default/+233242431111@196.44.101.68:5060 SOFIA EXECUTE
  491. 2020-03-16 07:59:12.414599 [DEBUG] switch_core_state_machine.c:329 sofia/default/+233242431111@196.44.101.68:5060 Standard EXECUTE
  492. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_destination_number=233278910821)
  493. 2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_destination_number]=[233278910821]
  494. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
  495. 2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
  496. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_data=+1440 normal_clearing)
  497. 2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_data]=[+1440 normal_clearing]
  498. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(callstart=2020-03-16 07:59:12)
  499. 2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [callstart]=[2020-03-16 07:59:12]
  500. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(hangup_after_bridge=true)
  501. 2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [hangup_after_bridge]=[true]
  502. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(continue_on_fail=TRUE)
  503. 2020-03-16 07:59:12.414599 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [continue_on_fail]=[TRUE]
  504. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(account_id=1367)
  505. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [account_id]=[1367]
  506. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(parent_id=0)
  507. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [parent_id]=[0]
  508. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(entity_id=0)
  509. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [entity_id]=[0]
  510. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_processed=internal)
  511. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_processed]=[internal]
  512. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_direction=outbound)
  513. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_direction]=[outbound]
  514. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(accountname=PEG IP)
  515. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [accountname]=[PEG IP]
  516. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(t38_passthru=true)
  517. 2020-03-16 07:59:12.434587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [t38_passthru]=[true]
  518. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38=true)
  519. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38]=[true]
  520. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38_request=true)
  521. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38_request]=[true]
  522. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367)
  523. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [origination_rates]=[ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367]
  524. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_name=+233242431111)
  525. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_name]=[+233242431111]
  526. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_number=0242436881)
  527. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_number]=[0242436881]
  528. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION)
  529. 2020-03-16 07:59:12.434587 [DEBUG] switch_limit.c:124 incr called: 6425492662_user_6425492662 max:90, interval:0
  530. 2020-03-16 07:59:12.434587 [DEBUG] mod_db.c:194 Usage for 6425492662_user_6425492662 is now 1/90
  531. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_name=+233242431111)
  532. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_name]=[+233242431111]
  533. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_number=+233242431111)
  534. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_number]=[+233242431111]
  535. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(calltype=STANDARD)
  536. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [calltype]=[STANDARD]
  537. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17)
  538. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [termination_rates]=[ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17]
  539. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(trunk_id=7)
  540. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [trunk_id]=[7]
  541. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(provider_id=17)
  542. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [provider_id]=[17]
  543. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(rate_flag=1)
  544. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [rate_flag]=[1]
  545. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(force_trunk_flag=1144850)
  546. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [force_trunk_flag]=[1144850]
  547. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=trunk_id=7)
  548. 2020-03-16 07:59:12.434587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=7]
  549. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(intcall=0)
  550. 2020-03-16 07:59:12.434587 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [intcall]=[0]
  551. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242)
  552. 2020-03-16 07:59:12.434587 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242]
  553. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821)
  554. 2020-03-16 07:59:12.454586 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [t38_passthru]=[true] to event
  555. 2020-03-16 07:59:12.454586 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
  556. 2020-03-16 07:59:12.454586 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
  557. 2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables
  558. 2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables
  559. 2020-03-16 07:59:12.454586 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233278910821 [e11f0fab-6f98-4aec-8a48-d077ef91c8af]
  560. 2020-03-16 07:59:12.454586 [DEBUG] mod_sofia.c:5089 (sofia/default/+233278910821) State Change CS_NEW -> CS_INIT
  561. 2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:3067 sofia/default/+233278910821 Setting leg timeout to 30
  562. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_INIT (Cur 21 Tot 4674738)
  563. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT
  564. 2020-03-16 07:59:12.454586 [DEBUG] mod_sofia.c:93 sofia/default/+233278910821 SOFIA INIT
  565. 2020-03-16 07:59:12.454586 [DEBUG] sofia_glue.c:1618 sofia/default/+233278910821 sending invite version: 1.10.2 -release-13-f7bdd3845a 64bit
  566. Local SDP:
  567. v=0
  568. o=FreeSWITCH 1584314652 1584314653 IN IP4 196.44.101.68
  569. s=FreeSWITCH
  570. c=IN IP4 196.44.101.68
  571. t=0 0
  572. m=audio 30900 RTP/AVP 18 8 0 101
  573. a=rtpmap:18 G729/8000
  574. a=fmtp:18 annexb=no
  575. a=rtpmap:8 PCMA/8000
  576. a=rtpmap:0 PCMU/8000
  577. a=rtpmap:101 telephone-event/8000
  578. a=fmtp:101 0-16
  579. a=ptime:20
  580. a=sendrecv
  581.  
  582. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233278910821 Standard INIT
  583. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233278910821) State Change CS_INIT -> CS_ROUTING
  584. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT going to sleep
  585. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_ROUTING (Cur 21 Tot 4674738)
  586. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING
  587. 2020-03-16 07:59:12.454586 [DEBUG] mod_sofia.c:154 sofia/default/+233278910821 SOFIA ROUTING
  588. 2020-03-16 07:59:12.454586 [DEBUG] switch_ivr_originate.c:67 (sofia/default/+233278910821) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  589. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING going to sleep
  590. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_CONSUME_MEDIA (Cur 21 Tot 4674738)
  591. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA
  592. 2020-03-16 07:59:12.454586 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA going to sleep
  593. 2020-03-16 07:59:12.454586 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [calling][0]
  594. 2020-03-16 07:59:12.494637 [DEBUG] switch_scheduler.c:147 Deleting task 1409251 switch_ivr_schedule_hangup (bf638467-cfba-408c-812b-67515ac8a216)
  595. 2020-03-16 07:59:12.634655 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [proceeding][183]
  596. 2020-03-16 07:59:12.634655 [DEBUG] sofia.c:7311 Remote SDP:
  597. v=0
  598. o=- 10025417 10025417 IN IP4 196.201.34.20
  599. s=-
  600. c=IN IP4 196.201.34.70
  601. t=0 0
  602. a=sendrecv
  603. m=audio 27302 RTP/AVP 18 101
  604. c=IN IP4 196.201.34.70
  605. a=rtpmap:18 G729/8000
  606. a=fmtp:18 annexb=no
  607. a=rtpmap:101 telephone-event/8000
  608. a=fmtp:101 0-15
  609. a=maxptime:40
  610.  
  611. 2020-03-16 07:59:12.634655 [NOTICE] sofia.c:7314 Pre-Answer sofia/default/+233278910821!
  612. 2020-03-16 07:59:12.634655 [DEBUG] switch_channel.c:3565 (sofia/default/+233278910821) Callstate Change DOWN -> EARLY
  613. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[G729:18:8000:20:8000:1]
  614. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  615. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMA:8:8000:20:64000:1]
  616. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMU:0:8000:20:64000:1]
  617. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
  618. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233278910821 G729/8000 20 ms 160 samples 8000 bits 1 channels
  619. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_codec.c:111 sofia/default/+233278910821 Original read codec set to G729:18
  620. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
  621. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:5909 sofia/default/+233278910821 Set 2833 dtmf send payload to 101 recv payload to 101
  622. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233278910821] 196.44.101.68 port 30900 -> 196.201.34.70 port 27302 codec: 18 ms: 20
  623. 2020-03-16 07:59:12.634655 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  624. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:8973 sofia/default/+233278910821 Set 2833 dtmf send payload to 101
  625. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:8980 sofia/default/+233278910821 Set 2833 dtmf receive payload to 101
  626. 2020-03-16 07:59:12.634655 [DEBUG] switch_core_media.c:9003 sofia/default/+233278910821 Set rtp dtmf delay to 40
  627. 2020-03-16 07:59:12.654640 [INFO] switch_ivr_originate.c:3758 Sending early media
  628. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233242431111@196.44.101.68:5060] 196.44.101.68 port 27810 -> 197.251.229.242 port 9030 codec: 18 ms: 20
  629. 2020-03-16 07:59:12.654640 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  630. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:8973 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101
  631. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:8980 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf receive payload to 101
  632. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_media.c:9003 sofia/default/+233242431111@196.44.101.68:5060 Set rtp dtmf delay to 40
  633. 2020-03-16 07:59:12.654640 [INFO] switch_rtp.c:4212 Activating audio Secure RTP SEND
  634. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  635. 2020-03-16 07:59:12.654640 [INFO] switch_rtp.c:4190 Activating audio Secure RTP RECV
  636. 2020-03-16 07:59:12.654640 [DEBUG] mod_sofia.c:2593 Ring SDP:
  637. v=0
  638. o=FreeSWITCH 1584317742 1584317743 IN IP4 196.44.101.68
  639. s=FreeSWITCH
  640. c=IN IP4 196.44.101.68
  641. t=0 0
  642. m=audio 27810 RTP/SAVP 18 101
  643. a=rtpmap:18 G729/8000
  644. a=fmtp:18 annexb=no
  645. a=rtpmap:101 telephone-event/8000
  646. a=fmtp:101 0-16
  647. a=ptime:20
  648. a=sendrecv
  649. a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:6YMmvJXhb18M+MflfSXZ+IUKx34vSMtS1bkUQ+wn
  650. m=audio 0 RTP/AVP 19
  651.  
  652. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  653. 2020-03-16 07:59:12.654640 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/+233242431111@196.44.101.68:5060!
  654. 2020-03-16 07:59:12.654640 [DEBUG] switch_channel.c:3565 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change RINGING -> EARLY
  655. 2020-03-16 07:59:12.654640 [DEBUG] switch_ivr_originate.c:3809 Originate Resulted in Success: [sofia/default/+233278910821]
  656. 2020-03-16 07:59:12.654640 [DEBUG] switch_ivr_bridge.c:1796 (sofia/default/+233278910821) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  657. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_EXCHANGE_MEDIA (Cur 21 Tot 4674738)
  658. 2020-03-16 07:59:12.654640 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA
  659. 2020-03-16 07:59:12.654640 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
  660. 2020-03-16 07:59:12.694653 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
  661. 2020-03-16 07:59:12.834640 [DEBUG] switch_ivr_bridge.c:658 sofia/default/+233278910821 Bridge execute app sched_hangup(+1440 normal_clearing)
  662. 2020-03-16 07:59:12.854643 [DEBUG] switch_ivr.c:632 sofia/default/+233278910821 Command Execute [depth=0] sched_hangup(+1440 normal_clearing)
  663. EXECUTE [depth=0] sofia/default/+233278910821 sched_hangup(+1440 normal_clearing)
  664. 2020-03-16 07:59:12.854643 [DEBUG] switch_scheduler.c:252 Added task 1409255 switch_ivr_schedule_hangup (e11f0fab-6f98-4aec-8a48-d077ef91c8af) to run at 1584346992
  665. 2020-03-16 07:59:13.274646 [DEBUG] sofia.c:7301 Channel sofia/default/+233242431111@196.44.101.68:5060 entering state [early][183]
  666. 2020-03-16 07:59:13.654590 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [terminated][500]
  667. 2020-03-16 07:59:13.654590 [NOTICE] sofia.c:8534 Hangup sofia/default/+233278910821 [CS_EXCHANGE_MEDIA] [NORMAL_TEMPORARY_FAILURE]
  668. 2020-03-16 07:59:13.654590 [DEBUG] switch_ivr_bridge.c:829 sofia/default/+233278910821 ending bridge by request from read function
  669. 2020-03-16 07:59:13.654590 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233278910821]
  670. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA going to sleep
  671. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_HANGUP (Cur 21 Tot 4674738)
  672. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233278910821) Callstate Change EARLY -> HANGUP
  673. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP
  674. 2020-03-16 07:59:13.654590 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233278910821 hanging up, cause: NORMAL_TEMPORARY_FAILURE
  675. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233278910821 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
  676. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233278910821) State HANGUP going to sleep
  677. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233278910821) State Change CS_HANGUP -> CS_REPORTING
  678. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_REPORTING (Cur 21 Tot 4674738)
  679. 2020-03-16 07:59:13.654590 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING
  680. 2020-03-16 07:59:13.654590 [INFO] mod_json_cdr.c:271 Process [e11f0fab-6f98-4aec-8a48-d077ef91c8af.cdr.json]
  681. 2020-03-16 07:59:13.674599 [DEBUG] switch_ivr_bridge.c:915 BRIDGE THREAD DONE [sofia/default/+233242431111@196.44.101.68:5060]
  682. 2020-03-16 07:59:13.674599 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/+233278910821 skip receive message [UNBRIDGE] (channel is hungup already)
  683. 2020-03-16 07:59:13.674599 [NOTICE] switch_core_state_machine.c:386 sofia/default/+233242431111@196.44.101.68:5060 has executed the last dialplan instruction, hanging up.
  684. 2020-03-16 07:59:13.674599 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/+233242431111@196.44.101.68:5060 [CS_EXECUTE] [NORMAL_CLEARING]
  685. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE going to sleep
  686. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_HANGUP (Cur 21 Tot 4674738)
  687. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:848 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change EARLY -> HANGUP
  688. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP
  689. 2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:447 sofia/default/+233242431111@196.44.101.68:5060 Overriding SIP cause 480 with 500 from the other leg
  690. 2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:453 Channel sofia/default/+233242431111@196.44.101.68:5060 hanging up, cause: NORMAL_CLEARING
  691. 2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 500
  692. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:60 sofia/default/+233242431111@196.44.101.68:5060 Standard HANGUP, cause: NORMAL_CLEARING
  693. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+233242431111@196.44.101.68:5060) State HANGUP going to sleep
  694. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:620 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_HANGUP -> CS_REPORTING
  695. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_REPORTING (Cur 21 Tot 4674738)
  696. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING
  697. 2020-03-16 07:59:13.674599 [INFO] mod_json_cdr.c:271 Process [ebad9d6d-c5ef-4344-874b-76b6a6d3c3a2.cdr.json]
  698. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233278910821 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
  699. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233278910821) State REPORTING going to sleep
  700. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233278910821) State Change CS_REPORTING -> CS_DESTROY
  701. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_session.c:1726 Session 4674738 (sofia/default/+233278910821) Locked, Waiting on external entities
  702. 2020-03-16 07:59:13.674599 [NOTICE] switch_core_session.c:1744 Session 4674738 (sofia/default/+233278910821) Ended
  703. 2020-03-16 07:59:13.674599 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233278910821 [CS_DESTROY]
  704. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233278910821) Running State Change CS_DESTROY (Cur 20 Tot 4674738)
  705. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY
  706. 2020-03-16 07:59:13.674599 [DEBUG] mod_sofia.c:364 sofia/default/+233278910821 SOFIA DESTROY
  707. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233278910821 Standard DESTROY
  708. 2020-03-16 07:59:13.674599 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233278910821) State DESTROY going to sleep
  709. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:174 sofia/default/+233242431111@196.44.101.68:5060 Standard REPORTING, cause: NORMAL_CLEARING
  710. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:936 (sofia/default/+233242431111@196.44.101.68:5060) State REPORTING going to sleep
  711. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:611 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_REPORTING -> CS_DESTROY
  712. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_session.c:1726 Session 4674737 (sofia/default/+233242431111@196.44.101.68:5060) Locked, Waiting on external entities
  713. 2020-03-16 07:59:13.714581 [NOTICE] switch_core_session.c:1744 Session 4674737 (sofia/default/+233242431111@196.44.101.68:5060) Ended
  714. 2020-03-16 07:59:13.714581 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/+233242431111@196.44.101.68:5060 [CS_DESTROY]
  715. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_DESTROY (Cur 19 Tot 4674738)
  716. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY
  717. 2020-03-16 07:59:13.714581 [DEBUG] mod_sofia.c:364 sofia/default/+233242431111@196.44.101.68:5060 SOFIA DESTROY
  718. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:181 sofia/default/+233242431111@196.44.101.68:5060 Standard DESTROY
  719. 2020-03-16 07:59:13.714581 [DEBUG] switch_core_state_machine.c:749 (sofia/default/+233242431111@196.44.101.68:5060) State DESTROY going to sleep
  720. 2020-03-16 07:59:13.854648 [DEBUG] switch_scheduler.c:147 Deleting task 1409255 switch_ivr_schedule_hangup (e11f0fab-6f98-4aec-8a48-d077ef91c8af)
  721. 2020-03-16 07:59:14.334640 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233242431111@196.44.101.68:5060 [d16e1c65-0a8f-4734-8997-4a50023e54ca]
  722. 2020-03-16 07:59:14.334640 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_NEW (Cur 20 Tot 4674739)
  723. 2020-03-16 07:59:14.334640 [DEBUG] sofia.c:10255 sofia/default/+233242431111@196.44.101.68:5060 receiving invite from 197.251.229.242:1025 version: 1.10.2 -release-13-f7bdd3845a 64bit
  724. 2020-03-16 07:59:14.334640 [DEBUG] sofia.c:10349 verifying acl "default" for ip/port 197.251.229.242:0.
  725. 2020-03-16 07:59:14.334640 [DEBUG] sofia.c:10378 IP 197.251.229.242 Approved by acl "default[]". Access Granted.
  726. 2020-03-16 07:59:14.354597 [DEBUG] sofia.c:11452 Setting NAT mode based on via port
  727. 2020-03-16 07:59:14.354597 [DEBUG] sofia.c:7301 Channel sofia/default/+233242431111@196.44.101.68:5060 entering state [received][100]
  728. 2020-03-16 07:59:14.354597 [DEBUG] sofia.c:7311 Remote SDP:
  729. v=0
  730. o=3cxPS 514641100800 373242724353 IN IP4 197.251.229.242
  731. s=3cxPS Audio call
  732. c=IN IP4 197.251.229.242
  733. t=0 0
  734. m=audio 9032 RTP/SAVP 0 8 18 101
  735. a=rtpmap:0 PCMU/8000
  736. a=rtpmap:8 PCMA/8000
  737. a=rtpmap:18 G729/8000
  738. a=fmtp:18 annexb=no
  739. a=rtpmap:101 telephone-event/8000
  740. a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08
  741. a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:px4YQ2itc3UT2Q6e8Qn6JDg5MLG/Z1Ke1agBXQdo
  742. m=audio 9032 RTP/AVP 0 8 18 101
  743. a=rtpmap:0 PCMU/8000
  744. a=rtpmap:8 PCMA/8000
  745. a=rtpmap:18 G729/8000
  746. a=fmtp:18 annexb=no
  747. a=rtpmap:101 telephone-event/8000
  748.  
  749. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AEAD_AES_256_GCM_8]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
  750. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AEAD_AES_128_GCM_8]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
  751. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_256_CM_HMAC_SHA1_80]alias=[AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
  752. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_192_CM_HMAC_SHA1_80]alias=[AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
  753. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1813 looking for crypto suite [AES_CM_128_HMAC_SHA1_80]alias=[] in [1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
  754. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1822 Found suite AES_CM_128_HMAC_SHA1_80
  755. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1892 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08]
  756. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:1215 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:RC0PS2qV/Se+E00kDVgysculRHVz0vEYkYMkPwhG]
  757. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  758. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  759. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  760. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  761. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  762. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  763. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  764. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  765. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  766. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  767. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  768. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  769. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
  770. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233242431111@196.44.101.68:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
  771. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_codec.c:111 sofia/default/+233242431111@196.44.101.68:5060 Original read codec set to G729:18
  772. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
  773. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_media.c:5909 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  774. 2020-03-16 07:59:14.354597 [DEBUG] sofia.c:7735 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_NEW -> CS_INIT
  775. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:604 (sofia/default/+233242431111@196.44.101.68:5060) State NEW
  776. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_INIT (Cur 20 Tot 4674739)
  777. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT
  778. 2020-03-16 07:59:14.354597 [DEBUG] mod_sofia.c:93 sofia/default/+233242431111@196.44.101.68:5060 SOFIA INIT
  779. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233242431111@196.44.101.68:5060 Standard INIT
  780. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_INIT -> CS_ROUTING
  781. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233242431111@196.44.101.68:5060) State INIT going to sleep
  782. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_ROUTING (Cur 20 Tot 4674739)
  783. 2020-03-16 07:59:14.354597 [DEBUG] switch_channel.c:2332 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change DOWN -> RINGING
  784. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING
  785. 2020-03-16 07:59:14.354597 [DEBUG] mod_sofia.c:154 sofia/default/+233242431111@196.44.101.68:5060 SOFIA ROUTING
  786. 2020-03-16 07:59:14.354597 [DEBUG] switch_core_state_machine.c:236 sofia/default/+233242431111@196.44.101.68:5060 Standard ROUTING
  787. 2020-03-16 07:59:14.354597 [INFO] mod_dialplan_xml.c:637 Processing +233242431111 <+233242431111>->233278910821 in context default
  788. 2020-03-16 07:59:14.354597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  789. 2020-03-16 07:59:14.354597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  790. 2020-03-16 07:59:14.374586 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
  791. Event-Name: REQUEST_PARAMS
  792. Core-UUID: 021f676c-1b86-4c5d-ab5d-83652fc892b0
  793. FreeSWITCH-Hostname: rack-astpp
  794. FreeSWITCH-Switchname: rack-astpp
  795. FreeSWITCH-IPv4: 196.44.101.68
  796. FreeSWITCH-IPv6: %3A%3A1
  797. Event-Date-Local: 2020-03-16%2007%3A59%3A14
  798. Event-Date-GMT: Mon,%2016%20Mar%202020%2007%3A59%3A14%20GMT
  799. Event-Date-Timestamp: 1584345554354597
  800. Event-Calling-File: mod_dialplan_xml.c
  801. Event-Calling-Function: dialplan_xml_locate
  802. Event-Calling-Line-Number: 608
  803. Event-Sequence: 281836777
  804. Channel-State: CS_ROUTING
  805. Channel-Call-State: RINGING
  806. Channel-State-Number: 2
  807. Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  808. Unique-ID: d16e1c65-0a8f-4734-8997-4a50023e54ca
  809. Call-Direction: inbound
  810. Presence-Call-Direction: inbound
  811. Channel-HIT-Dialplan: true
  812. Channel-Presence-ID: %2B233242431111%40196.44.101.68
  813. Channel-Call-UUID: d16e1c65-0a8f-4734-8997-4a50023e54ca
  814. Answer-State: ringing
  815. Channel-Read-Codec-Name: G729
  816. Channel-Read-Codec-Rate: 8000
  817. Channel-Read-Codec-Bit-Rate: 8000
  818. Channel-Write-Codec-Name: G729
  819. Channel-Write-Codec-Rate: 8000
  820. Channel-Write-Codec-Bit-Rate: 8000
  821. Caller-Direction: inbound
  822. Caller-Logical-Direction: inbound
  823. Caller-Username: %2B233242431111
  824. Caller-Dialplan: XML
  825. Caller-Caller-ID-Name: %2B233242431111
  826. Caller-Caller-ID-Number: %2B233242431111
  827. Caller-Orig-Caller-ID-Name: %2B233242431111
  828. Caller-Orig-Caller-ID-Number: %2B233242431111
  829. Caller-Network-Addr: 197.251.229.242
  830. Caller-ANI: %2B233242431111
  831. Caller-Destination-Number: 233278910821
  832. Caller-Unique-ID: d16e1c65-0a8f-4734-8997-4a50023e54ca
  833. Caller-Source: mod_sofia
  834. Caller-Context: default
  835. Caller-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  836. Caller-Profile-Index: 1
  837. Caller-Profile-Created-Time: 1584345554334640
  838. Caller-Channel-Created-Time: 1584345554334640
  839. Caller-Channel-Answered-Time: 0
  840. Caller-Channel-Progress-Time: 0
  841. Caller-Channel-Progress-Media-Time: 0
  842. Caller-Channel-Hangup-Time: 0
  843. Caller-Channel-Transfer-Time: 0
  844. Caller-Channel-Resurrect-Time: 0
  845. Caller-Channel-Bridged-Time: 0
  846. Caller-Channel-Last-Hold: 0
  847. Caller-Channel-Hold-Accum: 0
  848. Caller-Screen-Bit: true
  849. Caller-Privacy-Hide-Name: false
  850. Caller-Privacy-Hide-Number: false
  851. variable_direction: inbound
  852. variable_uuid: d16e1c65-0a8f-4734-8997-4a50023e54ca
  853. variable_session_id: 4674739
  854. variable_sip_from_user: %2B233242431111
  855. variable_sip_from_port: 5060
  856. variable_sip_from_uri: %2B233242431111%40196.44.101.68%3A5060
  857. variable_sip_from_host: 196.44.101.68
  858. variable_video_media_flow: disabled
  859. variable_text_media_flow: disabled
  860. variable_channel_name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  861. variable_sip_call_id: BDA7u_G6SBWCFmJboszX2w..
  862. variable_sip_local_network_addr: 196.44.101.68
  863. variable_sip_network_ip: 197.251.229.242
  864. variable_sip_network_port: 1025
  865. variable_sip_invite_stamp: 1584345554334640
  866. variable_sip_received_ip: 197.251.229.242
  867. variable_sip_received_port: 1025
  868. variable_sip_via_protocol: udp
  869. variable_sip_authorized: true
  870. variable_sip_acl_authed_by: default
  871. variable_sip_from_user_stripped: 233242431111
  872. variable_sip_from_tag: ed6d3d79
  873. variable_sofia_profile_name: default
  874. variable_sofia_profile_url: sip%3Amod_sofia%40196.44.101.68%3A5060
  875. variable_recovery_profile_name: default
  876. variable_sip_Remote-Party-ID: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Bparty%3Dcalling
  877. variable_sip_cid_type: rpid
  878. variable_sip_full_via: SIP/2.0/UDP%20197.251.229.242%3A5060%3Bbranch%3Dz9hG4bK-524287-1---285e821d9b37170b%3Brport%3D1025
  879. variable_sip_from_display: %2B233242431111
  880. variable_sip_full_from: %22%2B233242431111%22%20%3Csip%3A%2B233242431111%40196.44.101.68%3A5060%3E%3Btag%3Ded6d3d79
  881. variable_sip_full_to: %3Csip%3A233278910821%40196.44.101.68%3A5060%3E
  882. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REGISTER,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20INFO,%20MESSAGE,%20UPDATE
  883. variable_sip_req_user: 233278910821
  884. variable_sip_req_port: 5060
  885. variable_sip_req_uri: 233278910821%40196.44.101.68%3A5060
  886. variable_sip_req_host: 196.44.101.68
  887. variable_sip_to_user: 233278910821
  888. variable_sip_to_port: 5060
  889. variable_sip_to_uri: 233278910821%40196.44.101.68%3A5060
  890. variable_sip_to_host: 196.44.101.68
  891. variable_sip_contact_user: %2B233242431111
  892. variable_sip_contact_port: 5060
  893. variable_sip_contact_uri: %2B233242431111%40197.251.229.242%3A5060
  894. variable_sip_contact_host: 197.251.229.242
  895. variable_sip_user_agent: 3CXPhoneSystem%2015.5.15502.6%20(15502)
  896. variable_sip_via_host: 197.251.229.242
  897. variable_sip_via_port: 5060
  898. variable_sip_via_rport: 1025
  899. variable_max_forwards: 70
  900. variable_presence_id: %2B233242431111%40196.44.101.68
  901. variable_sip_nat_detected: true
  902. variable_switch_r_sdp: v%3D0%0D%0Ao%3D3cxPS%20514641100800%20373242724353%20IN%20IP4%20197.251.229.242%0D%0As%3D3cxPS%20Audio%20call%0D%0Ac%3DIN%20IP4%20197.251.229.242%0D%0At%3D0%200%0D%0Am%3Daudio%209032%20RTP/SAVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dcrypto%3A1%20AES_CM_128_HMAC_SHA1_80%20inline%3A6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08%0D%0Aa%3Dcrypto%3A2%20AES_CM_128_HMAC_SHA1_32%20inline%3Apx4YQ2itc3UT2Q6e8Qn6JDg5MLG/Z1Ke1agBXQdo%0D%0Am%3Daudio%209032%20RTP/AVP%200%208%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0A
  903. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_bcg729.G729%408000h%4020i%408000b
  904. variable_rtp_use_codec_string: G729,PCMA,PCMU
  905. variable_remote_video_media_flow: inactive
  906. variable_remote_text_media_flow: inactive
  907. variable_remote_audio_media_flow: sendrecv
  908. variable_audio_media_flow: sendrecv
  909. variable_rtp_secure_media_negotiated: AES_CM_128_HMAC_SHA1_80
  910. variable_srtp_remote_audio_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3A6jqn9OKLVB3c5/wp/u9eIBALeru84SsSczqE7B08
  911. variable_srtp_remote_audio_crypto_type: AES_CM_128_HMAC_SHA1_80
  912. variable_rtp_has_crypto: AES_CM_128_HMAC_SHA1_80
  913. variable_rtp_last_audio_local_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3ARC0PS2qV/Se%2BE00kDVgysculRHVz0vEYkYMkPwhG
  914. variable_remote_media_ip: 197.251.229.242
  915. variable_remote_media_port: 9032
  916. variable_rtp_audio_recv_pt: 18
  917. variable_rtp_use_codec_name: G729
  918. variable_rtp_use_codec_fmtp: annexb%3Dno
  919. variable_rtp_use_codec_rate: 8000
  920. variable_rtp_use_codec_ptime: 20
  921. variable_rtp_use_codec_channels: 1
  922. variable_rtp_last_audio_codec_string: G729%408000h%4020i%401c
  923. variable_read_codec: G729
  924. variable_original_read_codec: G729
  925. variable_read_rate: 8000
  926. variable_original_read_rate: 8000
  927. variable_write_codec: G729
  928. variable_write_rate: 8000
  929. variable_dtmf_type: rfc2833
  930. variable_endpoint_disposition: RECEIVED
  931. variable_call_uuid: d16e1c65-0a8f-4734-8997-4a50023e54ca
  932. Hunt-Direction: inbound
  933. Hunt-Logical-Direction: inbound
  934. Hunt-Username: %2B233242431111
  935. Hunt-Dialplan: XML
  936. Hunt-Caller-ID-Name: %2B233242431111
  937. Hunt-Caller-ID-Number: %2B233242431111
  938. Hunt-Orig-Caller-ID-Name: %2B233242431111
  939. Hunt-Orig-Caller-ID-Number: %2B233242431111
  940. Hunt-Network-Addr: 197.251.229.242
  941. Hunt-ANI: %2B233242431111
  942. Hunt-Destination-Number: 233278910821
  943. Hunt-Unique-ID: d16e1c65-0a8f-4734-8997-4a50023e54ca
  944. Hunt-Source: mod_sofia
  945. Hunt-Context: default
  946. Hunt-Channel-Name: sofia/default/%2B233242431111%40196.44.101.68%3A5060
  947. Hunt-Profile-Index: 1
  948. Hunt-Profile-Created-Time: 1584345554334640
  949. Hunt-Channel-Created-Time: 1584345554334640
  950. Hunt-Channel-Answered-Time: 0
  951. Hunt-Channel-Progress-Time: 0
  952. Hunt-Channel-Progress-Media-Time: 0
  953. Hunt-Channel-Hangup-Time: 0
  954. Hunt-Channel-Transfer-Time: 0
  955. Hunt-Channel-Resurrect-Time: 0
  956. Hunt-Channel-Bridged-Time: 0
  957. Hunt-Channel-Last-Hold: 0
  958. Hunt-Channel-Hold-Accum: 0
  959. Hunt-Screen-Bit: true
  960. Hunt-Privacy-Hide-Name: false
  961. Hunt-Privacy-Hide-Number: false
  962.  
  963.  
  964. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name:
  965. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
  966. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name:
  967. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value:
  968. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 233278910821
  969. 2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '233278910821' AND status=0 limit 1
  970. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : +233242431111 / +233242431111
  971. 2020-03-16 07:59:14.374586 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  972. 2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  973. 2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
  974. 2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
  975. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : outbound
  976. 2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("197.251.229.242") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "233278910821"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  977. 2020-03-16 07:59:14.374586 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 6425492662]
  978. 2020-03-16 07:59:14.374586 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "6425492662" AND deleted = 0 limit 1
  979. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^233278910821.*' OR blocked_patterns = '^23327891082.*' OR blocked_patterns = '^2332789108.*' OR blocked_patterns = '^233278910.*' OR blocked_patterns = '^23327891.*' OR blocked_patterns = '^2332789.*' OR blocked_patterns = '^233278.*' OR blocked_patterns = '^23327.*' OR blocked_patterns = '^2332.*' OR blocked_patterns = '^233.*' OR blocked_patterns = '^23.*' OR blocked_patterns = '^2.*' OR blocked_patterns ='--') AND accountid = 1367 limit 1
  980. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [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 ="233278910821" AND A.accountid = '1367' limit 1
  981. 2020-03-16 07:59:14.394597 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 233278910821
  982. 2020-03-16 07:59:14.394597 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  983. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  984. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="233278910821" LIMIT 1
  985. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="233278910821" limit 1
  986. 2020-03-16 07:59:14.394597 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : outbound
  987. 2020-03-16 07:59:14.394597 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  988. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  989. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="233278910821" AND (accountid = 0 OR status = 1) LIMIT 1
  990. 2020-03-16 07:59:14.394597 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^233278910821.*' OR patterns = '^23327891082.*' OR patterns = '^2332789108.*' OR patterns = '^233278910.*' OR patterns = '^23327891.*' OR patterns = '^2332789.*' OR patterns = '^233278.*' OR patterns = '^23327.*' OR patterns = '^2332.*' OR patterns = '^233.*' OR patterns = '^23.*' OR patterns = '^2.*' OR patterns ='--') AND accountid = 1367 ORDER BY LENGTH(PKGPTR.patterns) DESC
  991. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '233278910821' OR number = '23327891082' OR number = '2332789108' OR number = '233278910' OR number = '23327891' OR number = '2332789' OR number = '233278' OR number = '23327' OR number = '2332' OR number = '233' OR number = '23' OR number = '2' OR number ='--') OR (number = '233278910821*' OR number = '23327891082*' OR number = '2332789108*' OR number = '233278910*' OR number = '23327891*' OR number = '2332789*' OR number = '233278*' OR number = '23327*' OR number = '2332*' OR number = '233*' OR number = '23*' OR number = '2*' OR number ='--') OR (number = '+233242431111' OR number = '+23324243111' OR number = '+2332424311' OR number = '+233242431' OR number = '+23324243' OR number = '+2332424' OR number = '+233242' OR number = '+23324' OR number = '+2332' OR number = '+233' OR number = '+23' OR number = '+2' OR number = '+' OR number ='--') OR (number = '+233242431111*' OR number = '+23324243111*' OR number = '+2332424311*' OR number = '+233242431*' OR number = '+23324243*' OR number = '+2332424*' OR number = '+233242*' OR number = '+23324*' OR number = '+2332*' OR number = '+233*' OR number = '+23*' OR number = '+2*' OR number = '+*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  992. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
  993. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Caller Id : +233242431111
  994. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] CLI Pool Id : 1
  995. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CLI_POOL] Query :Select count(*) as count from dids where number='+233242431111' and status=0
  996. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_COUNT] COUNT: 0
  997. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CIDPOOL] Query :Select number from dids where status=0 and accountid='1367' order by RAND() limit 1
  998. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '233278910821' OR routing_prefix = '23327891082' OR routing_prefix = '2332789108' OR routing_prefix = '233278910' OR routing_prefix = '23327891' OR routing_prefix = '2332789' OR routing_prefix = '233278' OR routing_prefix = '23327' OR routing_prefix = '2332' OR routing_prefix = '233' OR routing_prefix = '23' OR routing_prefix = '2' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  999. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
  1000. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 1367
  1001. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 6425492662
  1002. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 1347.2088
  1003. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  1004. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 40
  1005. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 1
  1006. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
  1007. 2020-03-16 07:59:14.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH1: 2
  1008. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 2 AND status=0 limit 1
  1009. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
  1010. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :outbound
  1011. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 40 OR accountid=1367)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
  1012. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: outbound
  1013. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information ===================
  1014. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 1144850
  1015. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
  1016. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0
  1017. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.16000
  1018. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : GH-MOB-TIGO
  1019. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 0
  1020. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 1367
  1021. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk ID: 0
  1022. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Routing type:
  1023. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================
  1024. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your1347.2088 balance Accountid 1367 !!!
  1025. 2020-03-16 07:59:14.414599 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 24 mins!
  1026. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 24 minutes
  1027. 2020-03-16 07:59:14.414599 [INFO] switch_cpp.cpp:1443 [ASTPP] [DIALPLAN] User Rate ID : 1144850
  1028. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id  :0
  1029. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=40 ORDER by id asc
  1030. 2020-03-16 07:59:14.414599 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,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 = '^233278910821.*' OR pattern = '^23327891082.*' OR pattern = '^2332789108.*' OR pattern = '^233278910.*' OR pattern = '^23327891.*' OR pattern = '^2332789.*' OR pattern = '^233278.*' OR pattern = '^23327.*' OR pattern = '^2332.*' OR pattern = '^233.*' OR pattern = '^23.*' OR pattern = '^2.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (7) ORDER by TR.cost ASC,TR.precedence ASC, TK.precedence
  1031. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Termination Rates Information ===================
  1032. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 548686
  1033. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Code : ^233.*
  1034. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Destination : Ghana Mob
  1035. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
  1036. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Free Seconds : 0
  1037. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Prefix : ^233.*
  1038. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Strip :
  1039. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Prepend : +
  1040. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Carrier id : 7
  1041. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] carrier_name : MTN_LOCAL
  1042. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] dialplan_variable :
  1043. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Failover gateway :
  1044. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Vendor id : 17
  1045. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Max channels : 0
  1046. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] Trunk Name : MTN_LOCAL
  1047. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================END OF TERMINATION RATES=======================
  1048. 2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1367 AND status=0 LIMIT 1
  1049. 2020-03-16 07:59:14.434643 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 24
  1050. 2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 40 AND status = 0
  1051. 2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate FROM localization WHERE id=(SELECT localization_id from accounts where id = 17) AND status=0 limit 1
  1052. 2020-03-16 07:59:14.434643 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 233278910821
  1053. 2020-03-16 07:59:14.434643 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : +233278910821
  1054. 2020-03-16 07:59:14.434643 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:
  1055. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  1056. <document type="freeswitch/xml">
  1057. <section name="dialplan" description="ASTPP Dialplan">
  1058. <context name="default">
  1059. <extension name="233278910821">
  1060. <condition field="destination_number" expression="233278910821">
  1061. <action application="set" data="effective_destination_number=233278910821"/>
  1062. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  1063. <action application="set" data="bridge_pre_execute_bleg_data=+1440 normal_clearing"/>
  1064. <action application="set" data="callstart=2020-03-16 07:59:14"/>
  1065. <action application="set" data="hangup_after_bridge=true"/>
  1066. <action application="set" data="continue_on_fail=TRUE"/>
  1067. <action application="set" data="account_id=1367"/>
  1068. <action application="set" data="parent_id=0"/>
  1069. <action application="set" data="entity_id=0"/>
  1070. <action application="set" data="call_processed=internal"/>
  1071. <action application="set" data="call_direction=outbound"/>
  1072. <action application="set" data="accountname=PEG IP"/>
  1073. <action application="export" data="t38_passthru=true"/>
  1074. <action application="set" data="fax_enable_t38=true"/>
  1075. <action application="set" data="fax_enable_t38_request=true"/>
  1076. <action application="set" data="origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367"/>
  1077. <action application="set" data="original_caller_id_name=+233242431111"/>
  1078. <action application="set" data="original_caller_id_number=0242436863"/>
  1079. <action application="limit" data="db 6425492662 user_6425492662 90 !SWITCH_CONGESTION"/>
  1080.  
  1081. <action application="set" data="effective_caller_id_name=+233242431111"/>
  1082. <action application="set" data="effective_caller_id_number=+233242431111"/>
  1083. <action application="set" data="calltype=STANDARD"/>
  1084. <action application="set" data="termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17"/>
  1085. <action application="set" data="trunk_id=7"/>
  1086. <action application="set" data="provider_id=17"/>
  1087. <action application="set" data="rate_flag=1"/>
  1088. <action application="set" data="force_trunk_flag=1144850"/>
  1089. <action application="export" data="presence_data=trunk_id=7"/>
  1090. <action application="set" data="intcall=0"/>
  1091. <action application="export" data="presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242"/>
  1092. <action application="bridge" data="[leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821"/>
  1093. </condition>
  1094. </extension>
  1095. </context>
  1096. </section>
  1097. </document>
  1098. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 parsing [default->233278910821] continue=false
  1099. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Regex (PASS) [233278910821] destination_number(233278910821) =~ /233278910821/ break=on-false
  1100. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_destination_number=233278910821)
  1101. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  1102. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(bridge_pre_execute_bleg_data=+1440 normal_clearing)
  1103. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(callstart=2020-03-16 07:59:14)
  1104. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(hangup_after_bridge=true)
  1105. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(continue_on_fail=TRUE)
  1106. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(account_id=1367)
  1107. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(parent_id=0)
  1108. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(entity_id=0)
  1109. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_processed=internal)
  1110. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(call_direction=outbound)
  1111. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(accountname=PEG IP)
  1112. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(t38_passthru=true)
  1113. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38=true)
  1114. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(fax_enable_t38_request=true)
  1115. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367)
  1116. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_name=+233242431111)
  1117. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(original_caller_id_number=0242436863)
  1118. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION)
  1119. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_name=+233242431111)
  1120. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(effective_caller_id_number=+233242431111)
  1121. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(calltype=STANDARD)
  1122. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17)
  1123. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(trunk_id=7)
  1124. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(provider_id=17)
  1125. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(rate_flag=1)
  1126. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(force_trunk_flag=1144850)
  1127. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=trunk_id=7)
  1128. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action set(intcall=0)
  1129. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242)
  1130. Dialplan: sofia/default/+233242431111@196.44.101.68:5060 Action bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821)
  1131. 2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:287 (sofia/default/+233242431111@196.44.101.68:5060) State Change CS_ROUTING -> CS_EXECUTE
  1132. 2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233242431111@196.44.101.68:5060) State ROUTING going to sleep
  1133. 2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233242431111@196.44.101.68:5060) Running State Change CS_EXECUTE (Cur 20 Tot 4674739)
  1134. 2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:651 (sofia/default/+233242431111@196.44.101.68:5060) State EXECUTE
  1135. 2020-03-16 07:59:14.434643 [DEBUG] mod_sofia.c:209 sofia/default/+233242431111@196.44.101.68:5060 SOFIA EXECUTE
  1136. 2020-03-16 07:59:14.434643 [DEBUG] switch_core_state_machine.c:329 sofia/default/+233242431111@196.44.101.68:5060 Standard EXECUTE
  1137. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_destination_number=233278910821)
  1138. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_destination_number]=[233278910821]
  1139. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
  1140. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
  1141. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(bridge_pre_execute_bleg_data=+1440 normal_clearing)
  1142. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [bridge_pre_execute_bleg_data]=[+1440 normal_clearing]
  1143. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(callstart=2020-03-16 07:59:14)
  1144. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [callstart]=[2020-03-16 07:59:14]
  1145. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(hangup_after_bridge=true)
  1146. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [hangup_after_bridge]=[true]
  1147. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(continue_on_fail=TRUE)
  1148. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [continue_on_fail]=[TRUE]
  1149. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(account_id=1367)
  1150. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [account_id]=[1367]
  1151. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(parent_id=0)
  1152. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [parent_id]=[0]
  1153. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(entity_id=0)
  1154. 2020-03-16 07:59:14.434643 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [entity_id]=[0]
  1155. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_processed=internal)
  1156. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_processed]=[internal]
  1157. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(call_direction=outbound)
  1158. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [call_direction]=[outbound]
  1159. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(accountname=PEG IP)
  1160. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [accountname]=[PEG IP]
  1161. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(t38_passthru=true)
  1162. 2020-03-16 07:59:14.454644 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [t38_passthru]=[true]
  1163. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38=true)
  1164. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38]=[true]
  1165. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(fax_enable_t38_request=true)
  1166. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [fax_enable_t38_request]=[true]
  1167. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(origination_rates=ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367)
  1168. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [origination_rates]=[ID:1144850|CODE:^23327.*|DESTINATION:GH-MOB-TIGO|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.16000|INC:1|INITIALBLOCK:1|RATEGROUP:40|MARKUP:0|CI:0|ACCID:1367]
  1169. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_name=+233242431111)
  1170. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_name]=[+233242431111]
  1171. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(original_caller_id_number=0242436863)
  1172. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [original_caller_id_number]=[0242436863]
  1173. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 limit(db 6425492662 user_6425492662 90 !SWITCH_CONGESTION)
  1174. 2020-03-16 07:59:14.454644 [DEBUG] switch_limit.c:124 incr called: 6425492662_user_6425492662 max:90, interval:0
  1175. 2020-03-16 07:59:14.454644 [DEBUG] mod_db.c:194 Usage for 6425492662_user_6425492662 is now 1/90
  1176. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_name=+233242431111)
  1177. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_name]=[+233242431111]
  1178. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(effective_caller_id_number=+233242431111)
  1179. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [effective_caller_id_number]=[+233242431111]
  1180. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(calltype=STANDARD)
  1181. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [calltype]=[STANDARD]
  1182. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(termination_rates=ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17)
  1183. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [termination_rates]=[ID:548686|CODE:^233.*|DESTINATION:Ghana Mob|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09360|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:17]
  1184. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(trunk_id=7)
  1185. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [trunk_id]=[7]
  1186. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(provider_id=17)
  1187. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [provider_id]=[17]
  1188. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(rate_flag=1)
  1189. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [rate_flag]=[1]
  1190. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(force_trunk_flag=1144850)
  1191. 2020-03-16 07:59:14.454644 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [force_trunk_flag]=[1144850]
  1192. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=trunk_id=7)
  1193. 2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=7]
  1194. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 set(intcall=0)
  1195. 2020-03-16 07:59:14.474641 [DEBUG] mod_dptools.c:1672 SET sofia/default/+233242431111@196.44.101.68:5060 [intcall]=[0]
  1196. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 export(presence_data=x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242)
  1197. 2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242]
  1198. EXECUTE [depth=0] sofia/default/+233242431111@196.44.101.68:5060 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA:PCMU]sofia/gateway/MTN_LOCAL/+233278910821)
  1199. 2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [t38_passthru]=[true] to event
  1200. 2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
  1201. 2020-03-16 07:59:14.474641 [DEBUG] switch_channel.c:1264 sofia/default/+233242431111@196.44.101.68:5060 EXPORTING[export_vars] [presence_data]=[x|||PEG(6425492662)|||^23327.* // GH-MOB-TIGO // 0.16000 // trunk_id=0|||MTN_LOCAL // ^233.* // Ghana Mob // 0.09360 // trunk_id=7|||STD|||197.251.229.242] to event
  1202. 2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables
  1203. 2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:2760 Parsing session specific variables
  1204. 2020-03-16 07:59:14.474641 [NOTICE] switch_channel.c:1118 New Channel sofia/default/+233278910821 [f9621b72-aa88-489b-a428-c72f1da5d0f5]
  1205. 2020-03-16 07:59:14.474641 [DEBUG] mod_sofia.c:5089 (sofia/default/+233278910821) State Change CS_NEW -> CS_INIT
  1206. 2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:3067 sofia/default/+233278910821 Setting leg timeout to 30
  1207. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_INIT (Cur 21 Tot 4674740)
  1208. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT
  1209. 2020-03-16 07:59:14.474641 [DEBUG] mod_sofia.c:93 sofia/default/+233278910821 SOFIA INIT
  1210. 2020-03-16 07:59:14.474641 [DEBUG] sofia_glue.c:1618 sofia/default/+233278910821 sending invite version: 1.10.2 -release-13-f7bdd3845a 64bit
  1211. Local SDP:
  1212. v=0
  1213. o=FreeSWITCH 1584318802 1584318803 IN IP4 196.44.101.68
  1214. s=FreeSWITCH
  1215. c=IN IP4 196.44.101.68
  1216. t=0 0
  1217. m=audio 26752 RTP/AVP 18 8 0 101
  1218. a=rtpmap:18 G729/8000
  1219. a=fmtp:18 annexb=no
  1220. a=rtpmap:8 PCMA/8000
  1221. a=rtpmap:0 PCMU/8000
  1222. a=rtpmap:101 telephone-event/8000
  1223. a=fmtp:101 0-16
  1224. a=ptime:20
  1225. a=sendrecv
  1226.  
  1227. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:40 sofia/default/+233278910821 Standard INIT
  1228. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+233278910821) State Change CS_INIT -> CS_ROUTING
  1229. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:628 (sofia/default/+233278910821) State INIT going to sleep
  1230. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_ROUTING (Cur 21 Tot 4674740)
  1231. 2020-03-16 07:59:14.474641 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [calling][0]
  1232. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING
  1233. 2020-03-16 07:59:14.474641 [DEBUG] mod_sofia.c:154 sofia/default/+233278910821 SOFIA ROUTING
  1234. 2020-03-16 07:59:14.474641 [DEBUG] switch_ivr_originate.c:67 (sofia/default/+233278910821) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1235. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:644 (sofia/default/+233278910821) State ROUTING going to sleep
  1236. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_CONSUME_MEDIA (Cur 21 Tot 4674740)
  1237. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA
  1238. 2020-03-16 07:59:14.474641 [DEBUG] switch_core_state_machine.c:663 (sofia/default/+233278910821) State CONSUME_MEDIA going to sleep
  1239. 2020-03-16 07:59:14.794648 [DEBUG] sofia.c:7301 Channel sofia/default/+233278910821 entering state [proceeding][183]
  1240. 2020-03-16 07:59:14.794648 [DEBUG] sofia.c:7311 Remote SDP:
  1241. v=0
  1242. o=- 6982403 6982403 IN IP4 196.201.34.20
  1243. s=-
  1244. c=IN IP4 196.201.34.70
  1245. t=0 0
  1246. a=sendrecv
  1247. m=audio 27444 RTP/AVP 18 101
  1248. c=IN IP4 196.201.34.70
  1249. a=rtpmap:18 G729/8000
  1250. a=fmtp:18 annexb=no
  1251. a=rtpmap:101 telephone-event/8000
  1252. a=fmtp:101 0-15
  1253. a=maxptime:40
  1254.  
  1255. 2020-03-16 07:59:14.794648 [NOTICE] sofia.c:7314 Pre-Answer sofia/default/+233278910821!
  1256. 2020-03-16 07:59:14.794648 [DEBUG] switch_channel.c:3565 (sofia/default/+233278910821) Callstate Change DOWN -> EARLY
  1257. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[G729:18:8000:20:8000:1]
  1258. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  1259. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMA:8:8000:20:64000:1]
  1260. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:40:8000:1]/[PCMU:0:8000:20:64000:1]
  1261. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
  1262. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:3837 Set Codec sofia/default/+233278910821 G729/8000 20 ms 160 samples 8000 bits 1 channels
  1263. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_codec.c:111 sofia/default/+233278910821 Original read codec set to G729:18
  1264. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
  1265. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:5909 sofia/default/+233278910821 Set 2833 dtmf send payload to 101 recv payload to 101
  1266. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233278910821] 196.44.101.68 port 26752 -> 196.201.34.70 port 27444 codec: 18 ms: 20
  1267. 2020-03-16 07:59:14.794648 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  1268. 2020-03-16 07:59:14.794648 [INFO] switch_ivr_originate.c:3758 Sending early media
  1269. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8973 sofia/default/+233278910821 Set 2833 dtmf send payload to 101
  1270. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/+233242431111@196.44.101.68:5060] 196.44.101.68 port 16428 -> 197.251.229.242 port 9032 codec: 18 ms: 20
  1271. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8980 sofia/default/+233278910821 Set 2833 dtmf receive payload to 101
  1272. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:9003 sofia/default/+233278910821 Set rtp dtmf delay to 40
  1273. 2020-03-16 07:59:14.794648 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  1274. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8973 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf send payload to 101
  1275. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:8980 sofia/default/+233242431111@196.44.101.68:5060 Set 2833 dtmf receive payload to 101
  1276. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_media.c:9003 sofia/default/+233242431111@196.44.101.68:5060 Set rtp dtmf delay to 40
  1277. 2020-03-16 07:59:14.794648 [INFO] switch_rtp.c:4212 Activating audio Secure RTP SEND
  1278. 2020-03-16 07:59:14.794648 [INFO] switch_rtp.c:4190 Activating audio Secure RTP RECV
  1279. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  1280. 2020-03-16 07:59:14.794648 [DEBUG] switch_core_sqldb.c:2827 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  1281. 2020-03-16 07:59:14.794648 [DEBUG] mod_sofia.c:2593 Ring SDP:
  1282. v=0
  1283. o=FreeSWITCH 1584329126 1584329127 IN IP4 196.44.101.68
  1284. s=FreeSWITCH
  1285. c=IN IP4 196.44.101.68
  1286. t=0 0
  1287. m=audio 16428 RTP/SAVP 18 101
  1288. a=rtpmap:18 G729/8000
  1289. a=fmtp:18 annexb=no
  1290. a=rtpmap:101 telephone-event/8000
  1291. a=fmtp:101 0-16
  1292. a=ptime:20
  1293. a=sendrecv
  1294. a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:RC0PS2qV/Se+E00kDVgysculRHVz0vEYkYMkPwhG
  1295. m=audio 0 RTP/AVP 19
  1296.  
  1297. 2020-03-16 07:59:14.794648 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/+233242431111@196.44.101.68:5060!
  1298. 2020-03-16 07:59:14.794648 [DEBUG] switch_channel.c:3565 (sofia/default/+233242431111@196.44.101.68:5060) Callstate Change RINGING -> EARLY
  1299. 2020-03-16 07:59:14.794648 [DEBUG] switch_ivr_originate.c:3809 Originate Resulted in Success: [sofia/default/+233278910821]
  1300. 2020-03-16 07:59:14.814657 [DEBUG] switch_ivr_bridge.c:1796 (sofia/default/+233278910821) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1301. 2020-03-16 07:59:14.814657 [DEBUG] switch_core_state_machine.c:585 (sofia/default/+233278910821) Running State Change CS_EXCHANGE_MEDIA (Cur 21 Tot 4674740)
  1302. 2020-03-16 07:59:14.814657 [DEBUG] switch_core_state_machine.c:654 (sofia/default/+233278910821) State EXCHANGE_MEDIA
  1303. 2020-03-16 07:59:14.814657 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
  1304. 2020-03-16 07:59:14.854641 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
  1305. 2020-03-16 07:59:14.994641 [DEBUG] switch_ivr_bridge.c:658 sofia/default/+233278910821 Bridge execute app sched_hangup(+1440 normal_clearing)
  1306. 2020-03-16 07:59:15.014649 [DEBUG] switch_ivr.c:632 sofia/default/+233278910821 Command Execute [depth=0] sched_hangup(+1440 normal_clearing)
  1307. EXECUTE [depth=0] sofia/default/+233278910821 sched_hangup(+1440 normal_clearing)
  1308.  

Reply to "test"