FreeSWITCH Pastebin

clint

From clint, 8 Months ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/636ad370
Embed
Download Paste or View Raw
  1. span style="color: cornflowerblue; font-weight: bold;"> 2023-08-28 12:26:31.874308 99.10% [NOTICE] switch_channel.c:1123 New Channel sofia/default/0653910229@172.16.250.14:5060 [d8b09597-c3df-426e-a69e-ee598fe0ffab]
  2. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_NEW (Cur 1 Tot 368977)
  3. 2023-08-28 12:26:31.874308 99.10% [INFO] sofia.c:10453 sofia/default/0653910229@172.16.250.14:5060 receiving invite from 172.16.250.14:5060 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: isbcr203012qy2vq01w4bkva4uaqrykjaxx0@10.18.5.64
  4. 2023-08-28 12:26:31.874308 99.10% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 172.16.250.14:0.
  5. 2023-08-28 12:26:31.874308 99.10% [DEBUG] sofia.c:10576 IP 172.16.250.14 Approved by acl "default[]". Access Granted.
  6. 2023-08-28 12:26:31.874308 99.10% [DEBUG] sofia.c:7487 Channel sofia/default/0653910229@172.16.250.14:5060 entering state [received][100]
  7. 2023-08-28 12:26:31.874308 99.10% [DEBUG] sofia.c:7497 Remote SDP:
  8. v=0
  9. o=PortaSIP 2744735275425486618 1 IN IP4 172.16.250.14
  10. s=SBC call
  11. t=0 0
  12. m=audio 20176 RTP/AVP 18 8 0 116
  13. c=IN IP4 172.16.250.14
  14. a=rtpmap:18 G729/8000
  15. a=rtpmap:8 PCMA/8000
  16. a=rtpmap:0 PCMU/8000
  17. a=rtpmap:116 telephone-event/8000
  18. a=maxptime:20
  19. a=ptime:20
  20.  
  21. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  22. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  23. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  24. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  25. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  26. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  27. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  28. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  29. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  30. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  31. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  32. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  33. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 116@8000
  34. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/0653910229@172.16.250.14:5060 G729/8000 20 ms 160 samples 8000 bits 1 channels
  35. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_codec.c:111 sofia/default/0653910229@172.16.250.14:5060 Original read codec set to G729:18
  36. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 116@8000
  37. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_media.c:5850 sofia/default/0653910229@172.16.250.14:5060 Set 2833 dtmf send payload to 116 recv payload to 116
  38. 2023-08-28 12:26:31.874308 99.10% [DEBUG] sofia.c:7921 (sofia/default/0653910229@172.16.250.14:5060) State Change CS_NEW -> CS_INIT
  39. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:600 (sofia/default/0653910229@172.16.250.14:5060) State NEW
  40. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_INIT (Cur 1 Tot 368977)
  41. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0653910229@172.16.250.14:5060) State INIT
  42. 2023-08-28 12:26:31.874308 99.10% [DEBUG] mod_sofia.c:97 sofia/default/0653910229@172.16.250.14:5060 SOFIA INIT
  43. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/0653910229@172.16.250.14:5060 Standard INIT
  44. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/0653910229@172.16.250.14:5060) State Change CS_INIT -> CS_ROUTING
  45. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0653910229@172.16.250.14:5060) State INIT going to sleep
  46. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_ROUTING (Cur 1 Tot 368977)
  47. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_channel.c:2380 (sofia/default/0653910229@172.16.250.14:5060) Callstate Change DOWN -> RINGING
  48. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0653910229@172.16.250.14:5060) State ROUTING
  49. 2023-08-28 12:26:31.874308 99.10% [DEBUG] mod_sofia.c:158 sofia/default/0653910229@172.16.250.14:5060 SOFIA ROUTING
  50. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_core_state_machine.c:230 sofia/default/0653910229@172.16.250.14:5060 Standard ROUTING
  51. 2023-08-28 12:26:31.874308 99.10% [INFO] mod_dialplan_xml.c:639 Processing +27653910229 <27653910229>->0877032455 in context default
  52. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  53. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  54. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  55. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  56. 2023-08-28 12:26:31.874308 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [xml_handler] Params:
  57. Event-Name: REQUEST_PARAMS
  58. Core-UUID: 13943694-e7af-4b6a-a61f-ced98a365d54
  59. FreeSWITCH-Hostname: voice
  60. FreeSWITCH-Switchname: voice
  61. FreeSWITCH-IPv4: 102.23.150.243
  62. FreeSWITCH-IPv6: %3A%3A1
  63. Event-Date-Local: 2023-08-28%2012%3A26%3A31
  64. Event-Date-GMT: Mon,%2028%20Aug%202023%2012%3A26%3A31%20GMT
  65. Event-Date-Timestamp: 1693225591874308
  66. Event-Calling-File: mod_dialplan_xml.c
  67. Event-Calling-Function: dialplan_xml_locate
  68. Event-Calling-Line-Number: 610
  69. Event-Sequence: 26018077
  70. Channel-State: CS_ROUTING
  71. Channel-Call-State: RINGING
  72. Channel-State-Number: 2
  73. Channel-Name: sofia/default/0653910229%40172.16.250.14%3A5060
  74. Unique-ID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  75. Call-Direction: inbound
  76. Presence-Call-Direction: inbound
  77. Channel-HIT-Dialplan: true
  78. Channel-Presence-ID: 0653910229%40172.16.250.14
  79. Channel-Call-UUID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  80. Answer-State: ringing
  81. Channel-Read-Codec-Name: G729
  82. Channel-Read-Codec-Rate: 8000
  83. Channel-Read-Codec-Bit-Rate: 8000
  84. Channel-Write-Codec-Name: G729
  85. Channel-Write-Codec-Rate: 8000
  86. Channel-Write-Codec-Bit-Rate: 8000
  87. Caller-Direction: inbound
  88. Caller-Logical-Direction: inbound
  89. Caller-Username: 27653910229
  90. Caller-Dialplan: XML
  91. Caller-Caller-ID-Name: %2B27653910229
  92. Caller-Caller-ID-Number: 27653910229
  93. Caller-Orig-Caller-ID-Name: %2B27653910229
  94. Caller-Orig-Caller-ID-Number: 27653910229
  95. Caller-Network-Addr: 172.16.250.14
  96. Caller-ANI: 27653910229
  97. Caller-Destination-Number: 0877032455
  98. Caller-Unique-ID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  99. Caller-Source: mod_sofia
  100. Caller-Context: default
  101. Caller-Channel-Name: sofia/default/0653910229%40172.16.250.14%3A5060
  102. Caller-Profile-Index: 1
  103. Caller-Profile-Created-Time: 1693225591874308
  104. Caller-Channel-Created-Time: 1693225591874308
  105. Caller-Channel-Answered-Time: 0
  106. Caller-Channel-Progress-Time: 0
  107. Caller-Channel-Progress-Media-Time: 0
  108. Caller-Channel-Hangup-Time: 0
  109. Caller-Channel-Transfer-Time: 0
  110. Caller-Channel-Resurrect-Time: 0
  111. Caller-Channel-Bridged-Time: 0
  112. Caller-Channel-Last-Hold: 0
  113. Caller-Channel-Hold-Accum: 0
  114. Caller-Screen-Bit: false
  115. Caller-Privacy-Hide-Name: false
  116. Caller-Privacy-Hide-Number: false
  117. variable_direction: inbound
  118. variable_uuid: d8b09597-c3df-426e-a69e-ee598fe0ffab
  119. variable_session_id: 368977
  120. variable_sip_from_user: 0653910229
  121. variable_sip_from_port: 5060
  122. variable_sip_from_uri: 0653910229%40172.16.250.14%3A5060
  123. variable_sip_from_host: 172.16.250.14
  124. variable_video_media_flow: disabled
  125. variable_text_media_flow: disabled
  126. variable_channel_name: sofia/default/0653910229%40172.16.250.14%3A5060
  127. variable_sip_call_id: isbcr203012qy2vq01w4bkva4uaqrykjaxx0%4010.18.5.64
  128. variable_sip_local_network_addr: 102.23.150.242
  129. variable_sip_network_ip: 172.16.250.14
  130. variable_sip_network_port: 5060
  131. variable_sip_invite_stamp: 1693225591874308
  132. variable_sip_received_ip: 172.16.250.14
  133. variable_sip_received_port: 5060
  134. variable_sip_via_protocol: udp
  135. variable_sip_authorized: true
  136. variable_sip_acl_authed_by: default
  137. variable_sip_from_user_stripped: 0653910229
  138. variable_sip_from_tag: iwcy2b6xrdfv4i5x.o
  139. variable_sofia_profile_name: default
  140. variable_sofia_profile_url: sip%3Amod_sofia%40102.23.150.242%3A5060
  141. variable_recovery_profile_name: default
  142. variable_sip_Remote-Party-ID: %2B27653910229%20%3Csip%3A27653910229%4010.0.207.68%3E%3Bparty%3Dcalling%3Bscreen%3Dno
  143. variable_sip_P-Asserted-Identity: 27653910229
  144. variable_sip_cid_type: pid
  145. variable_sip_full_via: SIP/2.0/UDP%20172.16.250.14%3A5060%3Bbranch%3Dz9hG4bKgmpa6r20d8groheu77r0.1%3Brport%3D5060
  146. variable_sip_from_display: %2B27653910229
  147. variable_sip_full_from: %2B27653910229%20%3Csip%3A0653910229%40172.16.250.14%3A5060%3E%3Btag%3Diwcy2b6xrdfv4i5x.o
  148. variable_sip_full_to: %3Csip%3A0877032455%40102.23.150.242%3A5060%3E
  149. variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20INFO,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20UPDATE
  150. variable_sip_req_user: 0877032455
  151. variable_sip_req_port: 5060
  152. variable_sip_req_uri: 0877032455%40102.23.150.242%3A5060
  153. variable_sip_req_host: 102.23.150.242
  154. variable_sip_to_user: 0877032455
  155. variable_sip_to_port: 5060
  156. variable_sip_to_uri: 0877032455%40102.23.150.242%3A5060
  157. variable_sip_to_host: 102.23.150.242
  158. variable_sip_contact_params: transport%3Dudp
  159. variable_sip_contact_user: 27653910229
  160. variable_sip_contact_port: 5060
  161. variable_sip_contact_uri: 27653910229%40172.16.250.14%3A5060
  162. variable_sip_contact_host: 172.16.250.14
  163. variable_sip_user_agent: PortaSIP
  164. variable_sip_via_host: 172.16.250.14
  165. variable_sip_via_port: 5060
  166. variable_sip_via_rport: 5060
  167. variable_max_forwards: 68
  168. variable_presence_id: 0653910229%40172.16.250.14
  169. variable_sip_h_X-original-to: %2B27877032455
  170. variable_switch_r_sdp: v%3D0%0D%0Ao%3DPortaSIP%202744735275425486618%201%20IN%20IP4%20172.16.250.14%0D%0As%3DSBC%20call%0D%0At%3D0%200%0D%0Am%3Daudio%2020176%20RTP/AVP%2018%208%200%20116%0D%0Ac%3DIN%20IP4%20172.16.250.14%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A116%20telephone-event/8000%0D%0Aa%3Dmaxptime%3A20%0D%0Aa%3Dptime%3A20%0D%0A
  171. variable_ep_codec_string: mod_bcg729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  172. variable_rtp_use_codec_string: PCMA,PCMU,G729,H264
  173. variable_remote_video_media_flow: inactive
  174. variable_remote_text_media_flow: inactive
  175. variable_remote_audio_media_flow: sendrecv
  176. variable_audio_media_flow: sendrecv
  177. variable_remote_media_ip: 172.16.250.14
  178. variable_remote_media_port: 20176
  179. variable_rtp_audio_recv_pt: 18
  180. variable_rtp_use_codec_name: G729
  181. variable_rtp_use_codec_rate: 8000
  182. variable_rtp_use_codec_ptime: 20
  183. variable_rtp_use_codec_channels: 1
  184. variable_rtp_last_audio_codec_string: G729%408000h%4020i%401c
  185. variable_read_codec: G729
  186. variable_original_read_codec: G729
  187. variable_read_rate: 8000
  188. variable_original_read_rate: 8000
  189. variable_write_codec: G729
  190. variable_write_rate: 8000
  191. variable_dtmf_type: rfc2833
  192. variable_endpoint_disposition: RECEIVED
  193. variable_call_uuid: d8b09597-c3df-426e-a69e-ee598fe0ffab
  194. Hunt-Direction: inbound
  195. Hunt-Logical-Direction: inbound
  196. Hunt-Username: 27653910229
  197. Hunt-Dialplan: XML
  198. Hunt-Caller-ID-Name: %2B27653910229
  199. Hunt-Caller-ID-Number: 27653910229
  200. Hunt-Orig-Caller-ID-Name: %2B27653910229
  201. Hunt-Orig-Caller-ID-Number: 27653910229
  202. Hunt-Network-Addr: 172.16.250.14
  203. Hunt-ANI: 27653910229
  204. Hunt-Destination-Number: 0877032455
  205. Hunt-Unique-ID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  206. Hunt-Source: mod_sofia
  207. Hunt-Context: default
  208. Hunt-Channel-Name: sofia/default/0653910229%40172.16.250.14%3A5060
  209. Hunt-Profile-Index: 1
  210. Hunt-Profile-Created-Time: 1693225591874308
  211. Hunt-Channel-Created-Time: 1693225591874308
  212. Hunt-Channel-Answered-Time: 0
  213. Hunt-Channel-Progress-Time: 0
  214. Hunt-Channel-Progress-Media-Time: 0
  215. Hunt-Channel-Hangup-Time: 0
  216. Hunt-Channel-Transfer-Time: 0
  217. Hunt-Channel-Resurrect-Time: 0
  218. Hunt-Channel-Bridged-Time: 0
  219. Hunt-Channel-Last-Hold: 0
  220. Hunt-Channel-Hold-Accum: 0
  221. Hunt-Screen-Bit: false
  222. Hunt-Privacy-Hide-Name: false
  223. Hunt-Privacy-Hide-Number: false
  224.  
  225.  
  226. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_name:
  227. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] tag_name:
  228. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] section: dialplan
  229. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_value:
  230. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  231. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 0877032455
  232. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '0877032455' limit 1
  233. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '0877032455' AND status=0 limit 1
  234. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '0877032455' and status=0 LIMIT 1
  235. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER27653910229
  236. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  237. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '0877032455' limit 1
  238. 2023-08-28 12:26:31.874308 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : +27653910229 / 27653910229
  239. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 7032455
  240. 2023-08-28 12:26:31.874308 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 087 AND status=0 limit 1
  241. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  242. 2023-08-28 12:26:31.894271 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
  243. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  244. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0877032455
  245. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
  246. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
  247. 2023-08-28 12:26:31.894271 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
  248. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  249. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0877032455
  250. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0877032455" LIMIT 1
  251. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  252. 2023-08-28 12:26:31.894271 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
  253. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [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("172.16.250.14") 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 "0877032455"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  254. 2023-08-28 12:26:31.894271 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 6334793377]
  255. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "6334793377" AND deleted = 0 limit 1
  256. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =19
  257. 2023-08-28 12:26:31.894271 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
  258. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  259. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0877032455
  260. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
  261. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]1
  262. 2023-08-28 12:26:31.894271 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
  263. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  264. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Call Direction : inbound
  265. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID ACCOUNT ID : 19
  266. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^0877032455.*' OR patterns = '^087703245.*' OR patterns = '^08770324.*' OR patterns = '^0877032.*' OR patterns = '^087703.*' OR patterns = '^08770.*' OR patterns = '^0877.*' OR patterns = '^087.*' OR patterns = '^08.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 19 ORDER BY LENGTH(PKGPTR.patterns) DESC
  267. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="0877032455" AND A.accountid = '21' limit 1
  268. 2023-08-28 12:26:31.894271 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 0877032455
  269. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  270. 2023-08-28 12:26:31.894271 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
  271. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  272. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0877032455
  273. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
  274. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
  275. 2023-08-28 12:26:31.894271 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
  276. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  277. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0877032455
  278. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0877032455" LIMIT 1
  279. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  280. 2023-08-28 12:26:31.894271 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
  281. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '0877032455' OR number = '087703245' OR number = '08770324' OR number = '0877032' OR number = '087703' OR number = '08770' OR number = '0877' OR number = '087' OR number = '08' OR number = '0' OR number ='--') OR (number = '0877032455*' OR number = '087703245*' OR number = '08770324*' OR number = '0877032*' OR number = '087703*' OR number = '08770*' OR number = '0877*' OR number = '087*' OR number = '08*' OR number = '0*' OR number ='--') OR (number = '27653910229' OR number = '2765391022' OR number = '276539102' OR number = '27653910' OR number = '2765391' OR number = '276539' OR number = '27653' OR number = '2765' OR number = '276' OR number = '27' OR number = '2' OR number ='--') OR (number = '27653910229*' OR number = '2765391022*' OR number = '276539102*' OR number = '27653910*' OR number = '2765391*' OR number = '276539*' OR number = '27653*' OR number = '2765*' OR number = '276*' OR number = '27*' OR number = '2*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  282. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  283. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '0877032455' OR routing_prefix = '087703245' OR routing_prefix = '08770324' OR routing_prefix = '0877032' OR routing_prefix = '087703' OR routing_prefix = '08770' OR routing_prefix = '0877' OR routing_prefix = '087' OR routing_prefix = '08' OR routing_prefix = '0' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  284. 2023-08-28 12:26:31.894271 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  285. 2023-08-28 12:26:31.894271 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 21
  286. 2023-08-28 12:26:31.894271 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 6334793377
  287. 2023-08-28 12:26:31.894271 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =19
  288. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 987.5359
  289. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  290. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 4
  291. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  292. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  293. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
  294. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
  295. 2023-08-28 12:26:31.914274 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
  296. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  297. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0877032455
  298. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0877032455" LIMIT 1
  299. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  300. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
  301. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Markup : 100
  302. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  303. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 14
  304. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  305. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  306. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0
  307. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Kroonstad Freestate
  308. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 171
  309. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 21
  310. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  311. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =19
  312. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your987.5359 balance Accountid 21 !!!
  313. 2023-08-28 12:26:31.914274 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
  314. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
  315. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
  316. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
  317. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 21 AND status=0 LIMIT 1
  318. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "19" AND deleted = 0 limit 1
  319. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =19
  320. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:21
  321. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:21
  322. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 21
  323. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 19 limit 1
  324. 2023-08-28 12:26:31.914274 99.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : main.skybrevoip.co.za
  325. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
  326. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  327. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  328. 2023-08-28 12:26:31.914274 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
  329. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing  WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "3" AND accounts.status = 0
  330. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"27/0/*/*".....+27653910229
  331. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"27/0/*/*".....27653910229
  332. 2023-08-28 12:26:31.914274 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 27653910229
  333. 2023-08-28 12:26:31.914274 99.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 0653910229
  334. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"27/0/*/*".....+27653910229
  335. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"27/0/*/*".....0653910229
  336. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 19 limit 1
  337. 2023-08-28 12:26:31.914274 99.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : main.skybrevoip.co.za
  338. 2023-08-28 12:26:31.914274 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 19  and status = 0 limit 1
  339. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [XML] Custom Function Name :: custom_inbound_7
  340. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT daily_call_credit_limit,daily_call_used_limit,monthly_call_credit_limit,monthly_call_used_limit FROM accounts WHERE (number = 19 or id = 19 ) and status = 0 limit 1
  341. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : 7
  342. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : Ring Group
  343. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : Query - SELECT call_screening from accounts WHERE id = '19' LIMIT 1
  344. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : Screening Option - 1
  345. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : is_intro_recorded - false
  346. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : introduction_recording_name -
  347. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET RING GROUP INFO] Query :SELECT id as ringgroup_id,name as ringgroup_name,strategy as ringgroup_strategy,destinations as ringgroup_destinations,description as ringgroup_description,announcementid as ringgroup_announcementid,call_record as ringgroup_call_record,skip_busy as ringgroup_skip_busy,ringbackid as ringgroup_ringbackid,calleridname as ringgroup_calleridname,greetingid as ringgroup_greetingid,calleridnumber as ringgroup_calleridnumber,no_answer as ringgroup_no_answer,no_answer_call_type as no_answer_call_type,voicemail_set,no_answer_call_type_value, greetingid as ringgroup_greetingid, music_on_hold_type,music_on_hold from pbx_ringgroup WHERE id = 3 and status = 0 LIMIT 1
  348. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX]  Conference greetingid::0
  349. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '0' LIMIT 1
  350. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET MOH] Query :SELECT name FROM pbx_music_on_hold WHERE id = 0 ORDER BY RAND() LIMIT 1
  351. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 19 limit 1
  352. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== announcement===================0
  353. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Final Count : 0===
  354. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC1]
  355. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [sip_codec_for_outbound] Query :SELECT sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="100"  AND domains.domain="main.skybrevoip.co.za" limit 1
  356. 2023-08-28 12:26:31.934273 99.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] sip_codec : PCMA,G729,PCMU,H264
  357. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_extensions_forwarding] Query :select sip_devices.id,sip_device_routing.call_forwarding_flag,sip_device_routing.call_forwarding_destination from sip_devices JOIN sip_device_routing ON sip_devices.id = sip_device_routing.sip_device_id where sip_devices.username = "100"
  358. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC3]
  359. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [sip_codec_for_outbound] Query :SELECT sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="100"  AND domains.domain="main.skybrevoip.co.za" limit 1
  360. 2023-08-28 12:26:31.934273 99.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] sip_codec : PCMA,G729,PCMU,H264
  361. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] RingGroup Recording Flag : 1
  362. 2023-08-28 12:26:31.934273 99.10% [INFO] switch_cpp.cpp:1465 [ASTPP] RingGroup Dlr Str : [sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=10,confirm=false,announcement=no_file,dialed_user=100,is_intro_recorded=false,voicemail_sip=,voicemail_sip_domain=,leg_timeout=10,leg_delay_start=0,absolute_codec_string=^^:PCMA:G729:PCMU:H264]user/100@main.skybrevoip.co.za|
  363. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '' LIMIT 1
  364. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer_attended' limit 1
  365. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer' limit 1
  366. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  367. <document type="freeswitch/xml">
  368. <section name="dialplan" description="ASTPP Dialplan">
  369. <context name="default">
  370. <extension name="0877032455">
  371. <condition field="destination_number" expression="0877032455">
  372. <action application="set" data="effective_destination_number=0877032455"/>
  373. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  374. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  375. <action application="set" data="notify_flag=0"/>
  376. <action application="set" data="notify_credit_limit=5.00000"/>
  377. <action application="set" data="type=3"/>
  378. <action application="set" data="callstart=2023-08-28 12:26:31"/>
  379. <action application="set" data="hangup_after_bridge=true"/>
  380. <action application="set" data="continue_on_fail=TRUE"/>
  381. <action application="set" data="account_id=21"/>
  382. <action application="set" data="parent_id=0"/>
  383. <action application="set" data="entity_id=3"/>
  384. <action application="set" data="call_processed=internal"/>
  385. <action application="set" data="call_direction=inbound"/>
  386. <action application="set" data="accountname=SKYBRE"/>
  387. <action application="set" data="origination_rates_did=ID:14|CODE:^0877032455.*|DESTINATION:Kroonstad Freestate|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:100|CI:171|ACCID:19"/>
  388. <action application="set" data="origination_rates=0"/>
  389. <action application="set" data="original_caller_id_name=+27653910229"/>
  390. <action application="set" data="original_caller_id_number=27653910229"/>
  391. <action application="set" data="effective_caller_id_name=+27653910229"/>
  392. <action application="set" data="effective_caller_id_number=0653910229"/>
  393. <action application="set" data="receiver_accid=19"/>
  394. <action application="export" data="domain_name=main.skybrevoip.co.za"/>
  395. <action application="export" data="sip_to_host=main.skybrevoip.co.za"/>
  396. <action application="export" data="presence_data=x|||Skybre VoIP Pty Ltd(5295400691)|||||||||DID|||172.16.250.14"/>
  397. <action application="export" data="call_type=7"/>
  398. <action application="set" data="did_calltype=7"/>
  399. <action application="set" data="did_extensions=3"/>
  400. <action application="set" data="daily_call_credit_limit=0.00000"/>
  401. <action application="set" data="monthly_call_credit_limit=0.00000"/>
  402. <action application="set" data="daily_call_used_limit=0.00000"/>
  403. <action application="set" data="monthly_call_used_limit=0.00000"/>
  404. <action application="set" data="group_confirm_key=exec"/>
  405. <action application="set" data="group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua"/>
  406. <action application="set" data="ringback="/>
  407. <action application="set" data="module_name=pbx"/>
  408. <action application="db" data="insert/pickup_any_group_${callgroup}/3/${uuid}"/>
  409. <action application="set" data="dialed_extension=transfer_call"/>
  410. <action application="export" data="dialed_extension=transfer_call"/>
  411. <action application="bind_meta_app" data="2 b s execute_extension::transfer_call_2 XML default"/>
  412. <action application="bind_meta_app" data="3 b s execute_extension::transfer_call_3 XML default"/>
  413. <action application="export" data="ring_group_transfer=true"/>
  414. <action application="set" data="fail_on_single_reject=CALL_REJECTED"/>
  415. <action application="bridge" data="{sip_h_P-call_type='custom_forward'}[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=10,confirm=false,announcement=no_file,dialed_user=100,is_intro_recorded=false,voicemail_sip=,voicemail_sip_domain=,leg_timeout=10,leg_delay_start=0,absolute_codec_string=^^:PCMA:G729:PCMU:H264]user/100@main.skybrevoip.co.za|"/>
  416. <action application="set" data="original_did_number=0877032455"/>
  417. <action application="set" data="no_answer_number="/>
  418. <action application="set" data="no_answer_call_type=pstn_1"/>
  419. <action application="set" data="no_answer_call_type_value=0624114149"/>
  420. <action application="set" data="variable_sip_to_host=main.skybrevoip.co.za"/>
  421. <action application="set" data="userinfo_id=21"/>
  422. <action application="set" data="variable_sip_to_host=main.skybrevoip.co.za"/>
  423. <action application="set" data="opensips_flag=1"/>
  424. <action application="lua" data="astpp/lib/pbx/astpp.ring_group_destination.lua"/>
  425. </condition>
  426. </extension>
  427. </context>
  428. </section>
  429. </document>
  430. Dialplan: sofia/default/0653910229@172.16.250.14:5060 parsing [default->0877032455] continue=false
  431. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Regex (PASS) [0877032455] destination_number(0877032455) =~ /0877032455/ break=on-false
  432. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(effective_destination_number=0877032455)
  433. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  434. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  435. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(notify_flag=0)
  436. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(notify_credit_limit=5.00000)
  437. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(type=3)
  438. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(callstart=2023-08-28 12:26:31)
  439. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(hangup_after_bridge=true)
  440. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(continue_on_fail=TRUE)
  441. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(account_id=21)
  442. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(parent_id=0)
  443. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(entity_id=3)
  444. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(call_processed=internal)
  445. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(call_direction=inbound)
  446. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(accountname=SKYBRE)
  447. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(origination_rates_did=ID:14|CODE:^0877032455.*|DESTINATION:Kroonstad Freestate|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:100|CI:171|ACCID:19)
  448. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(origination_rates=0)
  449. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(original_caller_id_name=+27653910229)
  450. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(original_caller_id_number=27653910229)
  451. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(effective_caller_id_name=+27653910229)
  452. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(effective_caller_id_number=0653910229)
  453. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(receiver_accid=19)
  454. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(domain_name=main.skybrevoip.co.za)
  455. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(sip_to_host=main.skybrevoip.co.za)
  456. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(presence_data=x|||Skybre VoIP Pty Ltd(5295400691)|||||||||DID|||172.16.250.14)
  457. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(call_type=7)
  458. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(did_calltype=7)
  459. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(did_extensions=3)
  460. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(daily_call_credit_limit=0.00000)
  461. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(monthly_call_credit_limit=0.00000)
  462. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(daily_call_used_limit=0.00000)
  463. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(monthly_call_used_limit=0.00000)
  464. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(group_confirm_key=exec)
  465. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
  466. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(ringback=)
  467. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(module_name=pbx)
  468. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action db(insert/pickup_any_group_${callgroup}/3/${uuid})
  469. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(dialed_extension=transfer_call)
  470. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(dialed_extension=transfer_call)
  471. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
  472. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
  473. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(ring_group_transfer=true)
  474. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(fail_on_single_reject=CALL_REJECTED)
  475. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action bridge({sip_h_P-call_type='custom_forward'}[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=10,confirm=false,announcement=no_file,dialed_user=100,is_intro_recorded=false,voicemail_sip=,voicemail_sip_domain=,leg_timeout=10,leg_delay_start=0,absolute_codec_string=^^:PCMA:G729:PCMU:H264]user/100@main.skybrevoip.co.za|)
  476. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(original_did_number=0877032455)
  477. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(no_answer_number=)
  478. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(no_answer_call_type=pstn_1)
  479. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(no_answer_call_type_value=0624114149)
  480. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(variable_sip_to_host=main.skybrevoip.co.za)
  481. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(userinfo_id=21)
  482. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(variable_sip_to_host=main.skybrevoip.co.za)
  483. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(opensips_flag=1)
  484. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action lua(astpp/lib/pbx/astpp.ring_group_destination.lua)
  485. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0653910229@172.16.250.14:5060) State Change CS_ROUTING -> CS_EXECUTE
  486. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0653910229@172.16.250.14:5060) State ROUTING going to sleep
  487. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_EXECUTE (Cur 1 Tot 368977)
  488. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0653910229@172.16.250.14:5060) State EXECUTE
  489. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_sofia.c:213 sofia/default/0653910229@172.16.250.14:5060 SOFIA EXECUTE
  490. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_core_state_machine.c:323 sofia/default/0653910229@172.16.250.14:5060 Standard EXECUTE
  491. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(effective_destination_number=0877032455)
  492. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [effective_destination_number]=[0877032455]
  493. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
  494. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
  495. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  496. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  497. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(notify_flag=0)
  498. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [notify_flag]=[0]
  499. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(notify_credit_limit=5.00000)
  500. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [notify_credit_limit]=[5.00000]
  501. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(type=3)
  502. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [type]=[3]
  503. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(callstart=2023-08-28 12:26:31)
  504. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [callstart]=[2023-08-28 12:26:31]
  505. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(hangup_after_bridge=true)
  506. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [hangup_after_bridge]=[true]
  507. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(continue_on_fail=TRUE)
  508. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [continue_on_fail]=[TRUE]
  509. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(account_id=21)
  510. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [account_id]=[21]
  511. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(parent_id=0)
  512. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [parent_id]=[0]
  513. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(entity_id=3)
  514. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [entity_id]=[3]
  515. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(call_processed=internal)
  516. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [call_processed]=[internal]
  517. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(call_direction=inbound)
  518. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [call_direction]=[inbound]
  519. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(accountname=SKYBRE)
  520. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [accountname]=[SKYBRE]
  521. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(origination_rates_did=ID:14|CODE:^0877032455.*|DESTINATION:Kroonstad Freestate|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:100|CI:171|ACCID:19)
  522. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [origination_rates_did]=[ID:14|CODE:^0877032455.*|DESTINATION:Kroonstad Freestate|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:100|CI:171|ACCID:19]
  523. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(origination_rates=0)
  524. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [origination_rates]=[0]
  525. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(original_caller_id_name=+27653910229)
  526. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [original_caller_id_name]=[+27653910229]
  527. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(original_caller_id_number=27653910229)
  528. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [original_caller_id_number]=[27653910229]
  529. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(effective_caller_id_name=+27653910229)
  530. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [effective_caller_id_name]=[+27653910229]
  531. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(effective_caller_id_number=0653910229)
  532. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [effective_caller_id_number]=[0653910229]
  533. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(receiver_accid=19)
  534. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [receiver_accid]=[19]
  535. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(domain_name=main.skybrevoip.co.za)
  536. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [domain_name]=[main.skybrevoip.co.za]
  537. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(sip_to_host=main.skybrevoip.co.za)
  538. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_to_host]=[main.skybrevoip.co.za]
  539. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(presence_data=x|||Skybre VoIP Pty Ltd(5295400691)|||||||||DID|||172.16.250.14)
  540. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Skybre VoIP Pty Ltd(5295400691)|||||||||DID|||172.16.250.14]
  541. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(call_type=7)
  542. 2023-08-28 12:26:31.934273 99.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_type]=[7]
  543. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(did_calltype=7)
  544. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [did_calltype]=[7]
  545. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(did_extensions=3)
  546. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [did_extensions]=[3]
  547. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(daily_call_credit_limit=0.00000)
  548. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [daily_call_credit_limit]=[0.00000]
  549. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(monthly_call_credit_limit=0.00000)
  550. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [monthly_call_credit_limit]=[0.00000]
  551. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(daily_call_used_limit=0.00000)
  552. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [daily_call_used_limit]=[0.00000]
  553. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(monthly_call_used_limit=0.00000)
  554. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [monthly_call_used_limit]=[0.00000]
  555. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(group_confirm_key=exec)
  556. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [group_confirm_key]=[exec]
  557. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
  558. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [group_confirm_file]=[lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua]
  559. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(ringback=)
  560. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [ringback]=[UNDEF]
  561. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(module_name=pbx)
  562. 2023-08-28 12:26:31.934273 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [module_name]=[pbx]
  563. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 db(insert/pickup_any_group_/3/d8b09597-c3df-426e-a69e-ee598fe0ffab)
  564. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(dialed_extension=transfer_call)
  565. 2023-08-28 12:26:31.954269 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [dialed_extension]=[transfer_call]
  566. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(dialed_extension=transfer_call)
  567. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [dialed_extension]=[transfer_call]
  568. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
  569. 2023-08-28 12:26:31.954269 99.10% [INFO] switch_ivr_async.c:4780 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
  570. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
  571. 2023-08-28 12:26:31.954269 99.10% [INFO] switch_ivr_async.c:4780 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
  572. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(ring_group_transfer=true)
  573. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [ring_group_transfer]=[true]
  574. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(fail_on_single_reject=CALL_REJECTED)
  575. 2023-08-28 12:26:31.954269 99.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [fail_on_single_reject]=[CALL_REJECTED]
  576. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 bridge({sip_h_P-call_type='custom_forward'}[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=10,confirm=false,announcement=no_file,dialed_user=100,is_intro_recorded=false,voicemail_sip=,voicemail_sip_domain=,leg_timeout=10,leg_delay_start=0,absolute_codec_string=^^:PCMA:G729:PCMU:H264]user/100@main.skybrevoip.co.za|)
  577. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [domain_name]=[main.skybrevoip.co.za] to event
  578. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [sip_to_host]=[main.skybrevoip.co.za] to event
  579. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [presence_data]=[x|||Skybre VoIP Pty Ltd(5295400691)|||||||||DID|||172.16.250.14] to event
  580. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [call_type]=[7] to event
  581. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
  582. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
  583. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
  584. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_ivr_originate.c:2853 Parsing session specific variables
  585. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [domain_name]=[main.skybrevoip.co.za] to event
  586. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [sip_to_host]=[main.skybrevoip.co.za] to event
  587. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [presence_data]=[x|||Skybre VoIP Pty Ltd(5295400691)|||||||||DID|||172.16.250.14] to event
  588. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [call_type]=[7] to event
  589. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
  590. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
  591. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
  592. 2023-08-28 12:26:31.954269 99.10% [NOTICE] switch_channel.c:1123 New Channel sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 [4e237396-f047-48c0-a899-71d16744abd2]
  593. 2023-08-28 12:26:31.954269 99.10% [DEBUG] mod_sofia.c:5117 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State Change CS_NEW -> CS_INIT
  594. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_ivr_originate.c:3161 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 Setting leg timeout to 10
  595. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Running State Change CS_INIT (Cur 2 Tot 368978)
  596. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State INIT
  597. 2023-08-28 12:26:31.954269 99.10% [DEBUG] mod_sofia.c:97 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 SOFIA INIT
  598. 2023-08-28 12:26:31.954269 99.10% [DEBUG] sofia_glue.c:1623 sip:100-9fc21466638a24b06513c48ee5d2136f@35.195.163.239:5060 Setting proxy route to sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060
  599. 2023-08-28 12:26:31.954269 99.10% [INFO] sofia_glue.c:1654 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 sending invite call-id: (null)
  600. 2023-08-28 12:26:31.954269 99.10% [DEBUG] sofia_glue.c:1657 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 sending invite version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit
  601. Local SDP:
  602. v=0
  603. o=FreeSWITCH 1693203861 1693203862 IN IP4 102.23.150.242
  604. s=FreeSWITCH
  605. c=IN IP4 102.23.150.242
  606. t=0 0
  607. m=audio 21730 RTP/AVP 8 18 0 101
  608. a=rtpmap:8 PCMA/8000
  609. a=rtpmap:18 G729/8000
  610. a=fmtp:18 annexb=no
  611. a=rtpmap:0 PCMU/8000
  612. a=rtpmap:101 telephone-event/8000
  613. a=fmtp:101 0-15
  614. a=ptime:20
  615. a=sendrecv
  616. m=video 22312 RTP/AVP 102
  617. b=AS:3072
  618. a=rtpmap:102 H264/90000
  619. a=inactive
  620. a=rtcp-fb:102 ccm fir
  621. a=rtcp-fb:102 ccm tmmbr
  622. a=rtcp-fb:102 nack
  623. a=rtcp-fb:102 nack pli
  624.  
  625. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 Standard INIT
  626. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State Change CS_INIT -> CS_ROUTING
  627. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State INIT going to sleep
  628. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Running State Change CS_ROUTING (Cur 2 Tot 368978)
  629. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State ROUTING
  630. 2023-08-28 12:26:31.954269 99.10% [DEBUG] mod_sofia.c:158 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 SOFIA ROUTING
  631. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_ivr_originate.c:67 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  632. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State ROUTING going to sleep
  633. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 368978)
  634. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:659 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State CONSUME_MEDIA
  635. 2023-08-28 12:26:31.954269 99.10% [DEBUG] switch_core_state_machine.c:659 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State CONSUME_MEDIA going to sleep
  636. 2023-08-28 12:26:31.954269 99.10% [DEBUG] sofia.c:7487 Channel sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 entering state [calling][0]
  637. 2023-08-28 12:26:35.894290 99.23% [DEBUG] sofia.c:7487 Channel sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 entering state [terminated][486]
  638. 2023-08-28 12:26:35.894290 99.23% [NOTICE] sofia.c:8729 Hangup sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 [CS_CONSUME_MEDIA] [USER_BUSY]
  639. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Running State Change CS_HANGUP (Cur 2 Tot 368978)
  640. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:844 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Callstate Change DOWN -> HANGUP
  641. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:846 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State HANGUP
  642. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_sofia.c:468 Channel sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 hanging up, cause: USER_BUSY
  643. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:59 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 Standard HANGUP, cause: USER_BUSY
  644. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:846 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State HANGUP going to sleep
  645. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:616 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State Change CS_HANGUP -> CS_REPORTING
  646. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Running State Change CS_REPORTING (Cur 2 Tot 368978)
  647. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:932 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State REPORTING
  648. 2023-08-28 12:26:35.894290 99.23% [INFO] mod_json_cdr.c:272 Process [4e237396-f047-48c0-a899-71d16744abd2.cdr.json]
  649. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_ivr_originate.c:4045 Originate Resulted in Error Cause: 17 [USER_BUSY]
  650. 2023-08-28 12:26:35.894290 99.23% [NOTICE] switch_ivr_originate.c:3049 Cannot create outgoing channel of type [user] cause: [USER_BUSY]
  651. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_ivr_originate.c:4045 Originate Resulted in Error Cause: 17 [USER_BUSY]
  652. 2023-08-28 12:26:35.894290 99.23% [INFO] mod_dptools.c:3637 Originate Failed.  Cause: USER_BUSY
  653. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(original_did_number=0877032455)
  654. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [original_did_number]=[0877032455]
  655. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(no_answer_number=)
  656. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [no_answer_number]=[UNDEF]
  657. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(no_answer_call_type=pstn_1)
  658. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [no_answer_call_type]=[pstn_1]
  659. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(no_answer_call_type_value=0624114149)
  660. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [no_answer_call_type_value]=[0624114149]
  661. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(variable_sip_to_host=main.skybrevoip.co.za)
  662. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [variable_sip_to_host]=[main.skybrevoip.co.za]
  663. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(userinfo_id=21)
  664. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [userinfo_id]=[21]
  665. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(variable_sip_to_host=main.skybrevoip.co.za)
  666. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [variable_sip_to_host]=[main.skybrevoip.co.za]
  667. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(opensips_flag=1)
  668. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [opensips_flag]=[1]
  669. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 lua(astpp/lib/pbx/astpp.ring_group_destination.lua)
  670. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  671. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')
  672. 2023-08-28 12:26:35.894290 99.23% [WARNING] switch_cpp.cpp:1465 [ASTPP] This Is Last Dispositions USER_BUSY This Is No Answer Number : 0624114149
  673. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 transfer(0624114149 XML default)
  674. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_ivr.c:2288 (sofia/default/0653910229@172.16.250.14:5060) State Change CS_EXECUTE -> CS_ROUTING
  675. 2023-08-28 12:26:35.894290 99.23% [NOTICE] switch_ivr.c:2295 Transfer sofia/default/0653910229@172.16.250.14:5060 to XML[0624114149@default]
  676. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_cpp.cpp:1209 sofia/default/0653910229@172.16.250.14:5060 destroy/unlink session from object
  677. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0653910229@172.16.250.14:5060) State EXECUTE going to sleep
  678. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_ROUTING (Cur 2 Tot 368978)
  679. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0653910229@172.16.250.14:5060) State ROUTING
  680. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_sofia.c:149 Call appears to be already acknowledged
  681. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_sofia.c:158 sofia/default/0653910229@172.16.250.14:5060 SOFIA ROUTING
  682. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:230 sofia/default/0653910229@172.16.250.14:5060 Standard ROUTING
  683. 2023-08-28 12:26:35.894290 99.23% [INFO] mod_dialplan_xml.c:639 Processing +27653910229 <27653910229>->0624114149 in context default
  684. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:168 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 Standard REPORTING, cause: USER_BUSY
  685. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:932 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State REPORTING going to sleep
  686. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:607 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State Change CS_REPORTING -> CS_DESTROY
  687. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_session.c:1743 Session 368978 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Locked, Waiting on external entities
  688. 2023-08-28 12:26:35.894290 99.23% [NOTICE] switch_core_session.c:1761 Session 368978 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Ended
  689. 2023-08-28 12:26:35.894290 99.23% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 [CS_DESTROY]
  690. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:735 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) Running State Change CS_DESTROY (Cur 1 Tot 368978)
  691. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:745 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State DESTROY
  692. 2023-08-28 12:26:35.894290 99.23% [DEBUG] mod_sofia.c:379 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 SOFIA DESTROY
  693. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:175 sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060 Standard DESTROY
  694. 2023-08-28 12:26:35.894290 99.23% [DEBUG] switch_core_state_machine.c:745 (sofia/default/100-9fc21466638a24b06513c48ee5d2136f@10.14.135.129:5060) State DESTROY going to sleep
  695. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  696. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  697. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  698. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  699. 2023-08-28 12:26:35.914268 99.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [xml_handler] Params:
  700. Event-Name: REQUEST_PARAMS
  701. Core-UUID: 13943694-e7af-4b6a-a61f-ced98a365d54
  702. FreeSWITCH-Hostname: voice
  703. FreeSWITCH-Switchname: voice
  704. FreeSWITCH-IPv4: 102.23.150.243
  705. FreeSWITCH-IPv6: %3A%3A1
  706. Event-Date-Local: 2023-08-28%2012%3A26%3A35
  707. Event-Date-GMT: Mon,%2028%20Aug%202023%2012%3A26%3A35%20GMT
  708. Event-Date-Timestamp: 1693225595894290
  709. Event-Calling-File: mod_dialplan_xml.c
  710. Event-Calling-Function: dialplan_xml_locate
  711. Event-Calling-Line-Number: 610
  712. Event-Sequence: 26018206
  713. Channel-State: CS_ROUTING
  714. Channel-Call-State: RINGING
  715. Channel-State-Number: 2
  716. Channel-Name: sofia/default/0653910229%40172.16.250.14%3A5060
  717. Unique-ID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  718. Call-Direction: inbound
  719. Presence-Call-Direction: inbound
  720. Channel-HIT-Dialplan: true
  721. Channel-Presence-ID: 0653910229%40172.16.250.14
  722. Channel-Presence-Data: x%7C%7C%7CSkybre%20VoIP%20Pty%20Ltd(5295400691)%7C%7C%7C%7C%7C%7C%7C%7C%7CDID%7C%7C%7C172.16.250.14
  723. Channel-Call-UUID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  724. Answer-State: ringing
  725. Channel-Read-Codec-Name: G729
  726. Channel-Read-Codec-Rate: 8000
  727. Channel-Read-Codec-Bit-Rate: 8000
  728. Channel-Write-Codec-Name: G729
  729. Channel-Write-Codec-Rate: 8000
  730. Channel-Write-Codec-Bit-Rate: 8000
  731. Caller-Direction: inbound
  732. Caller-Logical-Direction: inbound
  733. Caller-Username: 27653910229
  734. Caller-Dialplan: XML
  735. Caller-Caller-ID-Name: %2B27653910229
  736. Caller-Caller-ID-Number: 27653910229
  737. Caller-Orig-Caller-ID-Name: %2B27653910229
  738. Caller-Orig-Caller-ID-Number: 27653910229
  739. Caller-Network-Addr: 172.16.250.14
  740. Caller-ANI: 27653910229
  741. Caller-Destination-Number: 0624114149
  742. Caller-Unique-ID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  743. Caller-Source: mod_sofia
  744. Caller-Transfer-Source: 1693225595%3Adf2978fd-1b97-4a5b-9724-b787e01dd9f1%3Abl_xfer%3A0624114149/default/XML
  745. Caller-Context: default
  746. Caller-RDNIS: 0877032455
  747. Caller-Channel-Name: sofia/default/0653910229%40172.16.250.14%3A5060
  748. Caller-Profile-Index: 2
  749. Caller-Profile-Created-Time: 1693225595894290
  750. Caller-Channel-Created-Time: 1693225591874308
  751. Caller-Channel-Answered-Time: 0
  752. Caller-Channel-Progress-Time: 0
  753. Caller-Channel-Progress-Media-Time: 0
  754. Caller-Channel-Hangup-Time: 0
  755. Caller-Channel-Transfer-Time: 0
  756. Caller-Channel-Resurrect-Time: 0
  757. Caller-Channel-Bridged-Time: 0
  758. Caller-Channel-Last-Hold: 0
  759. Caller-Channel-Hold-Accum: 0
  760. Caller-Screen-Bit: false
  761. Caller-Privacy-Hide-Name: false
  762. Caller-Privacy-Hide-Number: false
  763. variable_direction: inbound
  764. variable_uuid: d8b09597-c3df-426e-a69e-ee598fe0ffab
  765. variable_session_id: 368977
  766. variable_sip_from_user: 0653910229
  767. variable_sip_from_port: 5060
  768. variable_sip_from_uri: 0653910229%40172.16.250.14%3A5060
  769. variable_sip_from_host: 172.16.250.14
  770. variable_video_media_flow: disabled
  771. variable_text_media_flow: disabled
  772. variable_channel_name: sofia/default/0653910229%40172.16.250.14%3A5060
  773. variable_sip_call_id: isbcr203012qy2vq01w4bkva4uaqrykjaxx0%4010.18.5.64
  774. variable_sip_local_network_addr: 102.23.150.242
  775. variable_sip_network_ip: 172.16.250.14
  776. variable_sip_network_port: 5060
  777. variable_sip_invite_stamp: 1693225591874308
  778. variable_sip_received_ip: 172.16.250.14
  779. variable_sip_received_port: 5060
  780. variable_sip_via_protocol: udp
  781. variable_sip_authorized: true
  782. variable_sip_acl_authed_by: default
  783. variable_sip_from_user_stripped: 0653910229
  784. variable_sip_from_tag: iwcy2b6xrdfv4i5x.o
  785. variable_sofia_profile_name: default
  786. variable_sofia_profile_url: sip%3Amod_sofia%40102.23.150.242%3A5060
  787. variable_recovery_profile_name: default
  788. variable_sip_Remote-Party-ID: %2B27653910229%20%3Csip%3A27653910229%4010.0.207.68%3E%3Bparty%3Dcalling%3Bscreen%3Dno
  789. variable_sip_P-Asserted-Identity: 27653910229
  790. variable_sip_cid_type: pid
  791. variable_sip_full_via: SIP/2.0/UDP%20172.16.250.14%3A5060%3Bbranch%3Dz9hG4bKgmpa6r20d8groheu77r0.1%3Brport%3D5060
  792. variable_sip_from_display: %2B27653910229
  793. variable_sip_full_from: %2B27653910229%20%3Csip%3A0653910229%40172.16.250.14%3A5060%3E%3Btag%3Diwcy2b6xrdfv4i5x.o
  794. variable_sip_full_to: %3Csip%3A0877032455%40102.23.150.242%3A5060%3E
  795. variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20INFO,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20UPDATE
  796. variable_sip_req_user: 0877032455
  797. variable_sip_req_port: 5060
  798. variable_sip_req_uri: 0877032455%40102.23.150.242%3A5060
  799. variable_sip_req_host: 102.23.150.242
  800. variable_sip_to_user: 0877032455
  801. variable_sip_to_port: 5060
  802. variable_sip_to_uri: 0877032455%40102.23.150.242%3A5060
  803. variable_sip_contact_params: transport%3Dudp
  804. variable_sip_contact_user: 27653910229
  805. variable_sip_contact_port: 5060
  806. variable_sip_contact_uri: 27653910229%40172.16.250.14%3A5060
  807. variable_sip_contact_host: 172.16.250.14
  808. variable_sip_user_agent: PortaSIP
  809. variable_sip_via_host: 172.16.250.14
  810. variable_sip_via_port: 5060
  811. variable_sip_via_rport: 5060
  812. variable_presence_id: 0653910229%40172.16.250.14
  813. variable_sip_h_X-original-to: %2B27877032455
  814. variable_switch_r_sdp: v%3D0%0D%0Ao%3DPortaSIP%202744735275425486618%201%20IN%20IP4%20172.16.250.14%0D%0As%3DSBC%20call%0D%0At%3D0%200%0D%0Am%3Daudio%2020176%20RTP/AVP%2018%208%200%20116%0D%0Ac%3DIN%20IP4%20172.16.250.14%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A116%20telephone-event/8000%0D%0Aa%3Dmaxptime%3A20%0D%0Aa%3Dptime%3A20%0D%0A
  815. variable_ep_codec_string: mod_bcg729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  816. variable_rtp_use_codec_string: PCMA,PCMU,G729,H264
  817. variable_remote_video_media_flow: inactive
  818. variable_remote_text_media_flow: inactive
  819. variable_remote_audio_media_flow: sendrecv
  820. variable_audio_media_flow: sendrecv
  821. variable_remote_media_ip: 172.16.250.14
  822. variable_remote_media_port: 20176
  823. variable_rtp_audio_recv_pt: 18
  824. variable_rtp_use_codec_name: G729
  825. variable_rtp_use_codec_rate: 8000
  826. variable_rtp_use_codec_ptime: 20
  827. variable_rtp_use_codec_channels: 1
  828. variable_rtp_last_audio_codec_string: G729%408000h%4020i%401c
  829. variable_read_codec: G729
  830. variable_original_read_codec: G729
  831. variable_read_rate: 8000
  832. variable_original_read_rate: 8000
  833. variable_write_codec: G729
  834. variable_write_rate: 8000
  835. variable_dtmf_type: rfc2833
  836. variable_endpoint_disposition: RECEIVED
  837. variable_effective_destination_number: 0877032455
  838. variable_bridge_pre_execute_bleg_app: sched_hangup
  839. variable_bridge_pre_execute_bleg_data: %2B6000%20normal_clearing
  840. variable_notify_flag: 0
  841. variable_notify_credit_limit: 5.00000
  842. variable_type: 3
  843. variable_callstart: 2023-08-28%2012%3A26%3A31
  844. variable_hangup_after_bridge: true
  845. variable_continue_on_fail: TRUE
  846. variable_account_id: 21
  847. variable_parent_id: 0
  848. variable_entity_id: 3
  849. variable_call_processed: internal
  850. variable_call_direction: inbound
  851. variable_accountname: SKYBRE
  852. variable_origination_rates_did: ID%3A14%7CCODE%3A%5E0877032455.*%7CDESTINATION%3AKroonstad%20Freestate%7CCONNECTIONCOST%3A0.00000%7CINCLUDEDSECONDS%3A0%7CCT%3A7%7CCOST%3A0%7CINC%3A0%7CINITIALBLOCK%3A0%7CRATEGROUP%3A0%7CMARKUP%3A100%7CCI%3A171%7CACCID%3A19
  853. variable_origination_rates: 0
  854. variable_original_caller_id_name: %2B27653910229
  855. variable_original_caller_id_number: 27653910229
  856. variable_effective_caller_id_name: %2B27653910229
  857. variable_effective_caller_id_number: 0653910229
  858. variable_receiver_accid: 19
  859. variable_domain_name: main.skybrevoip.co.za
  860. variable_sip_to_host: main.skybrevoip.co.za
  861. variable_presence_data: x%7C%7C%7CSkybre%20VoIP%20Pty%20Ltd(5295400691)%7C%7C%7C%7C%7C%7C%7C%7C%7CDID%7C%7C%7C172.16.250.14
  862. variable_call_type: 7
  863. variable_did_calltype: 7
  864. variable_did_extensions: 3
  865. variable_daily_call_credit_limit: 0.00000
  866. variable_monthly_call_credit_limit: 0.00000
  867. variable_daily_call_used_limit: 0.00000
  868. variable_monthly_call_used_limit: 0.00000
  869. variable_group_confirm_key: exec
  870. variable_group_confirm_file: lua%20/usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua
  871. variable_module_name: pbx
  872. variable_dialed_extension: transfer_call
  873. variable_ring_group_transfer: true
  874. variable_export_vars: domain_name,sip_to_host,presence_data,call_type,dialed_extension,ring_group_transfer
  875. variable_fail_on_single_reject: CALL_REJECTED
  876. variable_dialed_user: 100
  877. variable_dialed_domain: main.skybrevoip.co.za
  878. variable_originate_signal_bond: 4e237396-f047-48c0-a899-71d16744abd2
  879. variable_originated_legs: 4e237396-f047-48c0-a899-71d16744abd2%3BOutbound%20Call%3B100-9fc21466638a24b06513c48ee5d2136f
  880. variable_sip_invite_failure_status: 486
  881. variable_sip_invite_failure_phrase: Busy%20Here
  882. variable_sip_hangup_phrase: Busy%20Here
  883. variable_last_bridge_hangup_cause: USER_BUSY
  884. variable_last_bridge_proto_specific_hangup_cause: sip%3A486
  885. variable_originate_causes: 4e237396-f047-48c0-a899-71d16744abd2%3BUSER_BUSY
  886. variable_originate_disposition: USER_BUSY
  887. variable_DIALSTATUS: BUSY
  888. variable_originate_failed_cause: USER_BUSY
  889. variable_original_did_number: 0877032455
  890. variable_no_answer_call_type: pstn_1
  891. variable_no_answer_call_type_value: 0624114149
  892. variable_userinfo_id: 21
  893. variable_variable_sip_to_host: main.skybrevoip.co.za
  894. variable_opensips_flag: 1
  895. variable_current_application_data: 0624114149%20XML%20default
  896. variable_current_application: transfer
  897. variable_max_forwards: 67
  898. variable_transfer_history: 1693225595%3Adf2978fd-1b97-4a5b-9724-b787e01dd9f1%3Abl_xfer%3A0624114149/default/XML
  899. variable_transfer_source: 1693225595%3Adf2978fd-1b97-4a5b-9724-b787e01dd9f1%3Abl_xfer%3A0624114149/default/XML
  900. variable_call_uuid: d8b09597-c3df-426e-a69e-ee598fe0ffab
  901. Hunt-Direction: inbound
  902. Hunt-Logical-Direction: inbound
  903. Hunt-Username: 27653910229
  904. Hunt-Dialplan: XML
  905. Hunt-Caller-ID-Name: %2B27653910229
  906. Hunt-Caller-ID-Number: 27653910229
  907. Hunt-Orig-Caller-ID-Name: %2B27653910229
  908. Hunt-Orig-Caller-ID-Number: 27653910229
  909. Hunt-Network-Addr: 172.16.250.14
  910. Hunt-ANI: 27653910229
  911. Hunt-Destination-Number: 0624114149
  912. Hunt-Unique-ID: d8b09597-c3df-426e-a69e-ee598fe0ffab
  913. Hunt-Source: mod_sofia
  914. Hunt-Transfer-Source: 1693225595%3Adf2978fd-1b97-4a5b-9724-b787e01dd9f1%3Abl_xfer%3A0624114149/default/XML
  915. Hunt-Context: default
  916. Hunt-RDNIS: 0877032455
  917. Hunt-Channel-Name: sofia/default/0653910229%40172.16.250.14%3A5060
  918. Hunt-Profile-Index: 2
  919. Hunt-Profile-Created-Time: 1693225595894290
  920. Hunt-Channel-Created-Time: 1693225591874308
  921. Hunt-Channel-Answered-Time: 0
  922. Hunt-Channel-Progress-Time: 0
  923. Hunt-Channel-Progress-Media-Time: 0
  924. Hunt-Channel-Hangup-Time: 0
  925. Hunt-Channel-Transfer-Time: 0
  926. Hunt-Channel-Resurrect-Time: 0
  927. Hunt-Channel-Bridged-Time: 0
  928. Hunt-Channel-Last-Hold: 0
  929. Hunt-Channel-Hold-Accum: 0
  930. Hunt-Screen-Bit: false
  931. Hunt-Privacy-Hide-Name: false
  932. Hunt-Privacy-Hide-Number: false
  933.  
  934.  
  935. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_value:
  936. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] section: dialplan
  937. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] tag_name:
  938. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [xml_REQUEST] key_name:
  939. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
  940. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Hunt Destination Number: 0624114149
  941. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer_attended' limit 1
  942. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer' limit 1
  943. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Feature Code Transfer Blind Value :*2
  944. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 0624114149
  945. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '0624114149' limit 1
  946. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '0624114149' AND status=0 limit 1
  947. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '0624114149' and status=0 LIMIT 1
  948. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER27653910229
  949. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  950. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '0624114149' limit 1
  951. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : +27653910229 / 0653910229
  952. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number 4114149
  953. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 062 AND status=0 limit 1
  954. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  955. 2023-08-28 12:26:35.914268 99.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
  956. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  957. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0624114149
  958. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="0624114149"  AND domains.domain="main.skybrevoip.co.za" limit 1
  959. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
  960. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [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("172.16.250.14") 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 "0624114149"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  961. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 6334793377]
  962. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "6334793377" AND deleted = 0 limit 1
  963. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^0624114149.*' OR blocked_patterns = '^062411414.*' OR blocked_patterns = '^06241141.*' OR blocked_patterns = '^0624114.*' OR blocked_patterns = '^062411.*' OR blocked_patterns = '^06241.*' OR blocked_patterns = '^0624.*' OR blocked_patterns = '^062.*' OR blocked_patterns = '^06.*' OR blocked_patterns = '^0.*' OR blocked_patterns ='--') AND accountid = 21 limit 1
  964. 2023-08-28 12:26:35.914268 99.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
  965. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  966. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0624114149
  967. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
  968. 2023-08-28 12:26:35.914268 99.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
  969. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  970. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0624114149
  971. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^0624114149.*' OR patterns = '^062411414.*' OR patterns = '^06241141.*' OR patterns = '^0624114.*' OR patterns = '^062411.*' OR patterns = '^06241.*' OR patterns = '^0624.*' OR patterns = '^062.*' OR patterns = '^06.*' OR patterns = '^0.*' OR patterns ='--') AND accountid = 21 ORDER BY LENGTH(PKGPTR.patterns) DESC
  972. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="0624114149" AND A.accountid = '21' limit 1
  973. 2023-08-28 12:26:35.914268 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 0624114149
  974. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
  975. 2023-08-28 12:26:35.914268 99.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
  976. 2023-08-28 12:26:35.914268 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  977. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0624114149
  978. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="0624114149"  AND domains.domain="main.skybrevoip.co.za" limit 1
  979. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
  980. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '0624114149' OR number = '062411414' OR number = '06241141' OR number = '0624114' OR number = '062411' OR number = '06241' OR number = '0624' OR number = '062' OR number = '06' OR number = '0' OR number ='--') OR (number = '0624114149*' OR number = '062411414*' OR number = '06241141*' OR number = '0624114*' OR number = '062411*' OR number = '06241*' OR number = '0624*' OR number = '062*' OR number = '06*' OR number = '0*' OR number ='--') OR (number = '0653910229' OR number = '065391022' OR number = '06539102' OR number = '0653910' OR number = '065391' OR number = '06539' OR number = '0653' OR number = '065' OR number = '06' OR number = '0' OR number ='--') OR (number = '0653910229*' OR number = '065391022*' OR number = '06539102*' OR number = '0653910*' OR number = '065391*' OR number = '06539*' OR number = '0653*' OR number = '065*' OR number = '06*' OR number = '0*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  981. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
  982. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '0624114149' OR routing_prefix = '062411414' OR routing_prefix = '06241141' OR routing_prefix = '0624114' OR routing_prefix = '062411' OR routing_prefix = '06241' OR routing_prefix = '0624' OR routing_prefix = '062' OR routing_prefix = '06' OR routing_prefix = '0' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  983. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
  984. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 21
  985. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 6334793377
  986. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 1000
  987. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  988. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 4
  989. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
  990. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
  991. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
  992. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
  993. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
  994. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^0624114149.*' OR pattern = '^062411414.*' OR pattern = '^06241141.*' OR pattern = '^0624114.*' OR pattern = '^062411.*' OR pattern = '^06241.*' OR pattern = '^0624.*' OR pattern = '^062.*' OR pattern = '^06.*' OR pattern = '^0.*' OR pattern ='--') AND (caller_id_pattern = '^0653910229.*' OR caller_id_pattern = '^065391022.*' OR caller_id_pattern = '^06539102.*' OR caller_id_pattern = '^0653910.*' OR caller_id_pattern = '^065391.*' OR caller_id_pattern = '^06539.*' OR caller_id_pattern = '^0653.*' OR caller_id_pattern = '^065.*' OR caller_id_pattern = '^06.*' OR caller_id_pattern = '^0.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 4 OR accountid=21)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1
  995. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
  996. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Markup : 100
  997. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
  998. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 697088
  999. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  1000. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
  1001. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.32
  1002. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : South Africa
  1003. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
  1004. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 21
  1005. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
  1006. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
  1007. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
  1008. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your1000 balance Accountid 21 !!!
  1009. 2023-08-28 12:26:35.934261 99.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
  1010. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
  1011. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
  1012. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=4 ORDER by id asc
  1013. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^0624114149.*' OR pattern = '^062411414.*' OR pattern = '^06241141.*' OR pattern = '^0624114.*' OR pattern = '^062411.*' OR pattern = '^06241.*' OR pattern = '^0624.*' OR pattern = '^062.*' OR pattern = '^06.*' OR pattern = '^0.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (1) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC
  1014. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
  1015. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 284951
  1016. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^0.*
  1017. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : South Africa
  1018. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
  1019. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
  1020. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^0.*
  1021. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
  1022. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
  1023. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
  1024. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : DimensionData
  1025. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
  1026. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
  1027. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 7
  1028. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
  1029. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
  1030. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 21 AND status=0 LIMIT 1
  1031. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
  1032. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1033. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
  1034. 2023-08-28 12:26:35.934261 99.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : outbound
  1035. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
  1036. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 7) AND status=0 limit 1
  1037. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 1) AND status=0 limit 1
  1038. 2023-08-28 12:26:35.934261 99.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
  1039. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
  1040. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"+/*/*/*".....0877032455
  1041. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0877032455" LIMIT 1
  1042. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  1043. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
  1044. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  1045. <document type="freeswitch/xml">
  1046. <section name="dialplan" description="ASTPP Dialplan">
  1047. <context name="default">
  1048. <extension name="0624114149">
  1049. <condition field="destination_number" expression="0624114149">
  1050. <action application="set" data="effective_destination_number=0624114149"/>
  1051. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  1052. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  1053. <action application="set" data="notify_flag=0"/>
  1054. <action application="set" data="notify_credit_limit=5.00000"/>
  1055. <action application="set" data="type=3"/>
  1056. <action application="set" data="callstart=2023-08-28 12:26:35"/>
  1057. <action application="set" data="hangup_after_bridge=true"/>
  1058. <action application="set" data="continue_on_fail=TRUE"/>
  1059. <action application="set" data="account_id=21"/>
  1060. <action application="set" data="parent_id=0"/>
  1061. <action application="set" data="entity_id=3"/>
  1062. <action application="set" data="call_processed=internal"/>
  1063. <action application="set" data="call_direction=outbound"/>
  1064. <action application="set" data="accountname=SKYBRE"/>
  1065. <action application="set" data="origination_rates=ID:697088|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.32|INC:1|INITIALBLOCK:1|RATEGROUP:4|MARKUP:100|CI:0|ACCID:21"/>
  1066. <action application="set" data="original_caller_id_name=+27653910229"/>
  1067. <action application="set" data="original_caller_id_number=0653910229"/>
  1068.  
  1069. <action application="set" data="effective_caller_id_name=+27653910229"/>
  1070. <action application="set" data="effective_caller_id_number=0653910229"/>
  1071. <action application="set" data="calltype=STANDARD"/>
  1072. <action application="set" data="termination_rates=ID:284951|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.16000|INC:1|INITIALBLOCK:1|TRUNK:1|PROVIDER:7"/>
  1073. <action application="set" data="trunk_id=1"/>
  1074. <action application="set" data="provider_id=7"/>
  1075. <action application="set" data="rate_flag=0"/>
  1076. <action application="set" data="daily_call_credit_limit=0.00000"/>
  1077. <action application="set" data="monthly_call_credit_limit=0.00000"/>
  1078. <action application="set" data="daily_call_used_limit=0.00000"/>
  1079. <action application="set" data="monthly_call_used_limit=0.00000"/>
  1080. <action application="set" data="quality_trunk_seq=1"/>
  1081. <action application="set" data="force_trunk_flag=0"/>
  1082. <action application="export" data="presence_data=trunk_id=1"/>
  1083. <action application="set" data="intcall=0"/>
  1084. <action application="export" data="presence_id=0624114149"/>
  1085. <action application="export" data="presence_data=x|||SKYBRE(6334793377)|||^0.* // South Africa // 0.32 |||Dimension Data // ^0.* // South Africa // 0.16000 // trunk_id=1|||STD|||172.16.250.14"/>
  1086. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA:H264]sofia/gateway/DimensionData/0624114149"/>
  1087. </condition>
  1088. </extension>
  1089. </context>
  1090. </section>
  1091. </document>
  1092. Dialplan: sofia/default/0653910229@172.16.250.14:5060 parsing [default->0624114149] continue=false
  1093. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Regex (PASS) [0624114149] destination_number(0624114149) =~ /0624114149/ break=on-false
  1094. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(effective_destination_number=0624114149)
  1095. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  1096. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  1097. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(notify_flag=0)
  1098. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(notify_credit_limit=5.00000)
  1099. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(type=3)
  1100. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(callstart=2023-08-28 12:26:35)
  1101. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(hangup_after_bridge=true)
  1102. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(continue_on_fail=TRUE)
  1103. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(account_id=21)
  1104. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(parent_id=0)
  1105. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(entity_id=3)
  1106. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(call_processed=internal)
  1107. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(call_direction=outbound)
  1108. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(accountname=SKYBRE)
  1109. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(origination_rates=ID:697088|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.32|INC:1|INITIALBLOCK:1|RATEGROUP:4|MARKUP:100|CI:0|ACCID:21)
  1110. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(original_caller_id_name=+27653910229)
  1111. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(original_caller_id_number=0653910229)
  1112. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(effective_caller_id_name=+27653910229)
  1113. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(effective_caller_id_number=0653910229)
  1114. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(calltype=STANDARD)
  1115. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(termination_rates=ID:284951|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.16000|INC:1|INITIALBLOCK:1|TRUNK:1|PROVIDER:7)
  1116. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(trunk_id=1)
  1117. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(provider_id=7)
  1118. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(rate_flag=0)
  1119. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(daily_call_credit_limit=0.00000)
  1120. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(monthly_call_credit_limit=0.00000)
  1121. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(daily_call_used_limit=0.00000)
  1122. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(monthly_call_used_limit=0.00000)
  1123. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(quality_trunk_seq=1)
  1124. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(force_trunk_flag=0)
  1125. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(presence_data=trunk_id=1)
  1126. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action set(intcall=0)
  1127. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(presence_id=0624114149)
  1128. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action export(presence_data=x|||SKYBRE(6334793377)|||^0.* // South Africa // 0.32 |||Dimension Data // ^0.* // South Africa // 0.16000 // trunk_id=1|||STD|||172.16.250.14)
  1129. Dialplan: sofia/default/0653910229@172.16.250.14:5060 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA:H264]sofia/gateway/DimensionData/0624114149)
  1130. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_core_state_machine.c:281 (sofia/default/0653910229@172.16.250.14:5060) State Change CS_ROUTING -> CS_EXECUTE
  1131. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0653910229@172.16.250.14:5060) State ROUTING going to sleep
  1132. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_EXECUTE (Cur 1 Tot 368978)
  1133. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0653910229@172.16.250.14:5060) State EXECUTE
  1134. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_sofia.c:213 sofia/default/0653910229@172.16.250.14:5060 SOFIA EXECUTE
  1135. 2023-08-28 12:26:35.934261 99.23% [DEBUG] switch_core_state_machine.c:323 sofia/default/0653910229@172.16.250.14:5060 Standard EXECUTE
  1136. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(effective_destination_number=0624114149)
  1137. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [effective_destination_number]=[0624114149]
  1138. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
  1139. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
  1140. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  1141. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  1142. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(notify_flag=0)
  1143. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [notify_flag]=[0]
  1144. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(notify_credit_limit=5.00000)
  1145. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [notify_credit_limit]=[5.00000]
  1146. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(type=3)
  1147. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [type]=[3]
  1148. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(callstart=2023-08-28 12:26:35)
  1149. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [callstart]=[2023-08-28 12:26:35]
  1150. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(hangup_after_bridge=true)
  1151. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [hangup_after_bridge]=[true]
  1152. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(continue_on_fail=TRUE)
  1153. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [continue_on_fail]=[TRUE]
  1154. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(account_id=21)
  1155. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [account_id]=[21]
  1156. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(parent_id=0)
  1157. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [parent_id]=[0]
  1158. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(entity_id=3)
  1159. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [entity_id]=[3]
  1160. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(call_processed=internal)
  1161. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [call_processed]=[internal]
  1162. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(call_direction=outbound)
  1163. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [call_direction]=[outbound]
  1164. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(accountname=SKYBRE)
  1165. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [accountname]=[SKYBRE]
  1166. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(origination_rates=ID:697088|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.32|INC:1|INITIALBLOCK:1|RATEGROUP:4|MARKUP:100|CI:0|ACCID:21)
  1167. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [origination_rates]=[ID:697088|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.32|INC:1|INITIALBLOCK:1|RATEGROUP:4|MARKUP:100|CI:0|ACCID:21]
  1168. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(original_caller_id_name=+27653910229)
  1169. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [original_caller_id_name]=[+27653910229]
  1170. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(original_caller_id_number=0653910229)
  1171. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [original_caller_id_number]=[0653910229]
  1172. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(effective_caller_id_name=+27653910229)
  1173. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [effective_caller_id_name]=[+27653910229]
  1174. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(effective_caller_id_number=0653910229)
  1175. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [effective_caller_id_number]=[0653910229]
  1176. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(calltype=STANDARD)
  1177. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [calltype]=[STANDARD]
  1178. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(termination_rates=ID:284951|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.16000|INC:1|INITIALBLOCK:1|TRUNK:1|PROVIDER:7)
  1179. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [termination_rates]=[ID:284951|CODE:^0.*|DESTINATION:South Africa|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.16000|INC:1|INITIALBLOCK:1|TRUNK:1|PROVIDER:7]
  1180. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(trunk_id=1)
  1181. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [trunk_id]=[1]
  1182. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(provider_id=7)
  1183. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [provider_id]=[7]
  1184. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(rate_flag=0)
  1185. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [rate_flag]=[0]
  1186. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(daily_call_credit_limit=0.00000)
  1187. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [daily_call_credit_limit]=[0.00000]
  1188. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(monthly_call_credit_limit=0.00000)
  1189. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [monthly_call_credit_limit]=[0.00000]
  1190. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(daily_call_used_limit=0.00000)
  1191. 2023-08-28 12:26:35.934261 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [daily_call_used_limit]=[0.00000]
  1192. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(monthly_call_used_limit=0.00000)
  1193. 2023-08-28 12:26:35.954271 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [monthly_call_used_limit]=[0.00000]
  1194. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(quality_trunk_seq=1)
  1195. 2023-08-28 12:26:35.954271 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [quality_trunk_seq]=[1]
  1196. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(force_trunk_flag=0)
  1197. 2023-08-28 12:26:35.954271 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [force_trunk_flag]=[0]
  1198. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(presence_data=trunk_id=1)
  1199. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
  1200. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 set(intcall=0)
  1201. 2023-08-28 12:26:35.954271 99.23% [DEBUG] mod_dptools.c:1673 SET sofia/default/0653910229@172.16.250.14:5060 [intcall]=[0]
  1202. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(presence_id=0624114149)
  1203. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[0624114149]
  1204. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 export(presence_data=x|||SKYBRE(6334793377)|||^0.* // South Africa // 0.32 |||Dimension Data // ^0.* // South Africa // 0.16000 // trunk_id=1|||STD|||172.16.250.14)
  1205. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||SKYBRE(6334793377)|||^0.* // South Africa // 0.32 |||Dimension Data // ^0.* // South Africa // 0.16000 // trunk_id=1|||STD|||172.16.250.14]
  1206. EXECUTE [depth=0] sofia/default/0653910229@172.16.250.14:5060 bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA:H264]sofia/gateway/DimensionData/0624114149)
  1207. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [domain_name]=[main.skybrevoip.co.za] to event
  1208. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [sip_to_host]=[main.skybrevoip.co.za] to event
  1209. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [presence_data]=[x|||SKYBRE(6334793377)|||^0.* // South Africa // 0.32 |||Dimension Data // ^0.* // South Africa // 0.16000 // trunk_id=1|||STD|||172.16.250.14] to event
  1210. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [call_type]=[7] to event
  1211. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
  1212. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
  1213. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [presence_data]=[x|||SKYBRE(6334793377)|||^0.* // South Africa // 0.32 |||Dimension Data // ^0.* // South Africa // 0.16000 // trunk_id=1|||STD|||172.16.250.14] to event
  1214. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [presence_id]=[0624114149] to event
  1215. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_channel.c:1269 sofia/default/0653910229@172.16.250.14:5060 EXPORTING[export_vars] [presence_data]=[x|||SKYBRE(6334793377)|||^0.* // South Africa // 0.32 |||Dimension Data // ^0.* // South Africa // 0.16000 // trunk_id=1|||STD|||172.16.250.14] to event
  1216. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
  1217. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_ivr_originate.c:2853 Parsing session specific variables
  1218. 2023-08-28 12:26:35.954271 99.23% [NOTICE] switch_channel.c:1123 New Channel sofia/default/0624114149 [be4315c4-817f-41ef-accc-ef30a208705b]
  1219. 2023-08-28 12:26:35.954271 99.23% [DEBUG] mod_sofia.c:5117 (sofia/default/0624114149) State Change CS_NEW -> CS_INIT
  1220. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_ivr_originate.c:3161 sofia/default/0624114149 Setting leg timeout to 30
  1221. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0624114149) Running State Change CS_INIT (Cur 2 Tot 368979)
  1222. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0624114149) State INIT
  1223. 2023-08-28 12:26:35.954271 99.23% [DEBUG] mod_sofia.c:97 sofia/default/0624114149 SOFIA INIT
  1224. 2023-08-28 12:26:35.954271 99.23% [INFO] sofia_glue.c:1654 sofia/default/0624114149 sending invite call-id: (null)
  1225. 2023-08-28 12:26:35.954271 99.23% [DEBUG] sofia_glue.c:1657 sofia/default/0624114149 sending invite version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit
  1226. Local SDP:
  1227. v=0
  1228. o=FreeSWITCH 1693199099 1693199100 IN IP4 102.23.150.242
  1229. s=FreeSWITCH
  1230. c=IN IP4 102.23.150.242
  1231. t=0 0
  1232. m=audio 26496 RTP/AVP 8 18 101
  1233. a=rtpmap:8 PCMA/8000
  1234. a=rtpmap:18 G729/8000
  1235. a=fmtp:18 annexb=no
  1236. a=rtpmap:101 telephone-event/8000
  1237. a=fmtp:101 0-15
  1238. a=ptime:20
  1239. a=sendrecv
  1240. m=video 29942 RTP/AVP 102
  1241. b=AS:3072
  1242. a=rtpmap:102 H264/90000
  1243. a=inactive
  1244. a=rtcp-fb:102 ccm fir
  1245. a=rtcp-fb:102 ccm tmmbr
  1246. a=rtcp-fb:102 nack
  1247. a=rtcp-fb:102 nack pli
  1248.  
  1249. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:40 sofia/default/0624114149 Standard INIT
  1250. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:48 (sofia/default/0624114149) State Change CS_INIT -> CS_ROUTING
  1251. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:624 (sofia/default/0624114149) State INIT going to sleep
  1252. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0624114149) Running State Change CS_ROUTING (Cur 2 Tot 368979)
  1253. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0624114149) State ROUTING
  1254. 2023-08-28 12:26:35.954271 99.23% [DEBUG] mod_sofia.c:158 sofia/default/0624114149 SOFIA ROUTING
  1255. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_ivr_originate.c:67 (sofia/default/0624114149) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1256. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:640 (sofia/default/0624114149) State ROUTING going to sleep
  1257. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0624114149) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 368979)
  1258. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:659 (sofia/default/0624114149) State CONSUME_MEDIA
  1259. 2023-08-28 12:26:35.954271 99.23% [DEBUG] switch_core_state_machine.c:659 (sofia/default/0624114149) State CONSUME_MEDIA going to sleep
  1260. 2023-08-28 12:26:35.954271 99.23% [DEBUG] sofia.c:7487 Channel sofia/default/0624114149 entering state [calling][0]
  1261. 2023-08-28 12:26:36.394290 99.23% [WARNING] sofia_reg.c:1846 SIP auth challenge (REGISTER) on sofia profile 'default' for [1015@voip.skybrevoip.co.za] from ip 102.23.149.200
  1262. 2023-08-28 12:26:36.414271 99.23% [WARNING] sofia_reg.c:1791 SIP auth failure (REGISTER) on sofia profile 'default' for [1015@voip.skybrevoip.co.za] from ip 102.23.149.200
  1263. 2023-08-28 12:26:40.854288 99.20% [DEBUG] sofia.c:7487 Channel sofia/default/0624114149 entering state [proceeding][180]
  1264. 2023-08-28 12:26:40.854288 99.20% [DEBUG] sofia.c:7497 Remote SDP:
  1265. v=0
  1266. o=PortaSIP 566259592093365592 1 IN IP4 172.16.250.14
  1267. s=-
  1268. t=0 0
  1269. m=audio 20074 RTP/AVP 8 101
  1270. c=IN IP4 172.16.250.14
  1271. a=rtpmap:8 PCMA/8000
  1272. a=rtpmap:101 telephone-event/8000
  1273. a=fmtp:101 0-15
  1274. a=ptime:20
  1275. m=video 0 RTP/AVP 102
  1276. c=IN IP4 172.16.250.14
  1277. b=AS:3072
  1278.  
  1279. 2023-08-28 12:26:40.854288 99.20% [NOTICE] sofia.c:7500 Pre-Answer sofia/default/0624114149!
  1280. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_channel.c:3566 (sofia/default/0624114149) Callstate Change DOWN -> EARLY
  1281. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1282. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1283. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  1284. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
  1285. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/0624114149 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1286. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_codec.c:111 sofia/default/0624114149 Original read codec set to PCMA:8
  1287. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
  1288. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:5850 sofia/default/0624114149 Set 2833 dtmf send payload to 101 recv payload to 101
  1289. 2023-08-28 12:26:40.854288 99.20% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/0624114149] 102.23.150.242 port 26496 -> 172.16.250.14 port 20074 codec: 8 ms: 20
  1290. 2023-08-28 12:26:40.874272 99.20% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
  1291. 2023-08-28 12:26:40.874272 99.20% [DEBUG] switch_core_media.c:8963 sofia/default/0624114149 Set 2833 dtmf send payload to 101
  1292. 2023-08-28 12:26:40.874272 99.20% [DEBUG] switch_core_media.c:8970 sofia/default/0624114149 Set 2833 dtmf receive payload to 101
  1293. 2023-08-28 12:26:40.874272 99.20% [DEBUG] switch_core_media.c:8993 sofia/default/0624114149 Set rtp dtmf delay to 40
  1294. EXECUTE [depth=0] sofia/default/0624114149 lua(/usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
  1295. 2023-08-28 12:26:40.874272 99.20% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group]  Ring group confirm flag :false
  1296. 2023-08-28 12:26:40.874272 99.20% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group] Ring group confirm :: false
  1297. 2023-08-28 12:26:40.874272 99.20% [DEBUG] switch_cpp.cpp:1209 sofia/default/0624114149 destroy/unlink session from object
  1298. 2023-08-28 12:26:40.894259 99.20% [INFO] switch_ivr_originate.c:3851 Sending early media
  1299. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/0653910229@172.16.250.14:5060] 102.23.150.242 port 21906 -> 172.16.250.14 port 20176 codec: 18 ms: 20
  1300. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
  1301. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_core_media.c:8963 sofia/default/0653910229@172.16.250.14:5060 Set 2833 dtmf send payload to 116
  1302. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_core_media.c:8970 sofia/default/0653910229@172.16.250.14:5060 Set 2833 dtmf receive payload to 116
  1303. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_core_media.c:8993 sofia/default/0653910229@172.16.250.14:5060 Set rtp dtmf delay to 40
  1304. 2023-08-28 12:26:40.894259 99.20% [DEBUG] mod_sofia.c:2609 Ring SDP:
  1305. v=0
  1306. o=FreeSWITCH 1693203694 1693203695 IN IP4 102.23.150.242
  1307. s=FreeSWITCH
  1308. c=IN IP4 102.23.150.242
  1309. t=0 0
  1310. m=audio 21906 RTP/AVP 18 116
  1311. a=rtpmap:18 G729/8000
  1312. a=fmtp:18 annexb=no
  1313. a=rtpmap:116 telephone-event/8000
  1314. a=fmtp:116 0-15
  1315. a=ptime:20
  1316. a=sendrecv
  1317.  
  1318. 2023-08-28 12:26:40.894259 99.20% [NOTICE] mod_sofia.c:2612 Pre-Answer sofia/default/0653910229@172.16.250.14:5060!
  1319. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_channel.c:3566 (sofia/default/0653910229@172.16.250.14:5060) Callstate Change RINGING -> EARLY
  1320. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_ivr_originate.c:3902 Originate Resulted in Success: [sofia/default/0624114149] Peer UUID: be4315c4-817f-41ef-accc-ef30a208705b
  1321. 2023-08-28 12:26:40.894259 99.20% [DEBUG] sofia.c:7487 Channel sofia/default/0653910229@172.16.250.14:5060 entering state [early][183]
  1322. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/0624114149) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1323. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0624114149) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 368979)
  1324. 2023-08-28 12:26:40.894259 99.20% [DEBUG] switch_core_state_machine.c:650 (sofia/default/0624114149) State EXCHANGE_MEDIA
  1325. 2023-08-28 12:26:40.894259 99.20% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
  1326. 2023-08-28 12:26:40.934296 99.20% [DEBUG] switch_rtp.c:7511 Correct audio ip/port confirmed.
  1327. 2023-08-28 12:26:41.074273 99.20% [DEBUG] switch_ivr_bridge.c:654 sofia/default/0624114149 Bridge execute app sched_hangup(+6000 normal_clearing)
  1328. 2023-08-28 12:26:41.094290 99.20% [DEBUG] switch_ivr.c:632 sofia/default/0624114149 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
  1329. EXECUTE [depth=0] sofia/default/0624114149 sched_hangup(+6000 normal_clearing)
  1330. 2023-08-28 12:26:41.094290 99.20% [DEBUG] switch_scheduler.c:263 Added task 3648 switch_ivr_schedule_hangup (be4315c4-817f-41ef-accc-ef30a208705b) to run at 1693231601
  1331. 2023-08-28 12:26:41.194270 99.20% [DEBUG] switch_rtp.c:7511 Correct audio ip/port confirmed.
  1332. 2023-08-28 12:26:50.174272 99.27% [WARNING] sofia_reg.c:1846 SIP auth challenge (REGISTER) on sofia profile 'default' for [16000@102.23.150.242] from ip 38.242.216.104
  1333. 2023-08-28 12:26:50.374272 99.27% [WARNING] sofia_reg.c:3211 Can't find user [16000@102.23.150.242] from 38.242.216.104
  1334. You must define a domain called '102.23.150.242' in your directory and add a user with the id="16000" attribute
  1335. and you must configure your device to use the proper domain in its authentication credentials.
  1336. 2023-08-28 12:26:50.374272 99.27% [WARNING] sofia_reg.c:1791 SIP auth failure (REGISTER) on sofia profile 'default' for [16000@102.23.150.242] from ip 38.242.216.104
  1337. 2023-08-28 12:26:54.514258 99.30% [WARNING] mod_event_socket.c:2676 IP ::ffff:148.113.16.121 Rejected by acl "loopback.auto"
  1338. 2023-08-28 12:26:57.054290 99.30% [DEBUG] sofia.c:7487 Channel sofia/default/0653910229@172.16.250.14:5060 entering state [terminated][487]
  1339. 2023-08-28 12:26:57.054290 99.30% [NOTICE] sofia.c:8729 Hangup sofia/default/0653910229@172.16.250.14:5060 [CS_EXECUTE] [ORIGINATOR_CANCEL]
  1340. 2023-08-28 12:26:57.054290 99.30% [DEBUG] switch_ivr_bridge.c:825 sofia/default/0653910229@172.16.250.14:5060 ending bridge by request from read function
  1341. 2023-08-28 12:26:57.054290 99.30% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/0653910229@172.16.250.14:5060]
  1342. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_ivr_bridge.c:819 sofia/default/0653910229@172.16.250.14:5060 ending bridge by request from write function
  1343. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/0624114149]
  1344. 2023-08-28 12:26:57.074288 99.30% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/default/0624114149 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
  1345. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:650 (sofia/default/0624114149) State EXCHANGE_MEDIA going to sleep
  1346. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0624114149) Running State Change CS_HANGUP (Cur 2 Tot 368979)
  1347. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:844 (sofia/default/0624114149) Callstate Change EARLY -> HANGUP
  1348. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/0624114149) State HANGUP
  1349. 2023-08-28 12:26:57.074288 99.30% [DEBUG] mod_sofia.c:462 sofia/default/0624114149 Overriding SIP cause 487 with 487 from the other leg
  1350. 2023-08-28 12:26:57.074288 99.30% [DEBUG] mod_sofia.c:468 Channel sofia/default/0624114149 hanging up, cause: ORIGINATOR_CANCEL
  1351. 2023-08-28 12:26:57.074288 99.30% [DEBUG] mod_sofia.c:536 Sending CANCEL to sofia/default/0624114149
  1352. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:59 sofia/default/0624114149 Standard HANGUP, cause: ORIGINATOR_CANCEL
  1353. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/0624114149) State HANGUP going to sleep
  1354. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:616 (sofia/default/0624114149) State Change CS_HANGUP -> CS_REPORTING
  1355. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0624114149) Running State Change CS_REPORTING (Cur 2 Tot 368979)
  1356. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/0624114149 skip receive message [UNBRIDGE] (channel is hungup already)
  1357. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/0653910229@172.16.250.14:5060 skip receive message [UNBRIDGE] (channel is hungup already)
  1358. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/0624114149) State REPORTING
  1359. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_session.c:2973 sofia/default/0653910229@172.16.250.14:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
  1360. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:647 (sofia/default/0653910229@172.16.250.14:5060) State EXECUTE going to sleep
  1361. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_HANGUP (Cur 2 Tot 368979)
  1362. 2023-08-28 12:26:57.074288 99.30% [INFO] mod_json_cdr.c:272 Process [be4315c4-817f-41ef-accc-ef30a208705b.cdr.json]
  1363. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:844 (sofia/default/0653910229@172.16.250.14:5060) Callstate Change EARLY -> HANGUP
  1364. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/0653910229@172.16.250.14:5060) State HANGUP
  1365. 2023-08-28 12:26:57.074288 99.30% [DEBUG] mod_sofia.c:462 sofia/default/0653910229@172.16.250.14:5060 Overriding SIP cause 487 with 486 from the other leg
  1366. 2023-08-28 12:26:57.074288 99.30% [DEBUG] mod_sofia.c:468 Channel sofia/default/0653910229@172.16.250.14:5060 hanging up, cause: ORIGINATOR_CANCEL
  1367. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:59 sofia/default/0653910229@172.16.250.14:5060 Standard HANGUP, cause: ORIGINATOR_CANCEL
  1368. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:846 (sofia/default/0653910229@172.16.250.14:5060) State HANGUP going to sleep
  1369. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:616 (sofia/default/0653910229@172.16.250.14:5060) State Change CS_HANGUP -> CS_REPORTING
  1370. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:581 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_REPORTING (Cur 2 Tot 368979)
  1371. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/0653910229@172.16.250.14:5060) State REPORTING
  1372. 2023-08-28 12:26:57.074288 99.30% [INFO] mod_json_cdr.c:272 Process [d8b09597-c3df-426e-a69e-ee598fe0ffab.cdr.json]
  1373. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:168 sofia/default/0624114149 Standard REPORTING, cause: ORIGINATOR_CANCEL
  1374. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/0624114149) State REPORTING going to sleep
  1375. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:607 (sofia/default/0624114149) State Change CS_REPORTING -> CS_DESTROY
  1376. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_session.c:1743 Session 368979 (sofia/default/0624114149) Locked, Waiting on external entities
  1377. 2023-08-28 12:26:57.074288 99.30% [NOTICE] switch_core_session.c:1761 Session 368979 (sofia/default/0624114149) Ended
  1378. 2023-08-28 12:26:57.074288 99.30% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/0624114149 [CS_DESTROY]
  1379. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:735 (sofia/default/0624114149) Running State Change CS_DESTROY (Cur 1 Tot 368979)
  1380. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/0624114149) State DESTROY
  1381. 2023-08-28 12:26:57.074288 99.30% [DEBUG] mod_sofia.c:379 sofia/default/0624114149 SOFIA DESTROY
  1382. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:175 sofia/default/0624114149 Standard DESTROY
  1383. 2023-08-28 12:26:57.074288 99.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/0624114149) State DESTROY going to sleep
  1384. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_scheduler.c:147 Deleting task 3648 switch_ivr_schedule_hangup (be4315c4-817f-41ef-accc-ef30a208705b)
  1385. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_state_machine.c:168 sofia/default/0653910229@172.16.250.14:5060 Standard REPORTING, cause: ORIGINATOR_CANCEL
  1386. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_state_machine.c:932 (sofia/default/0653910229@172.16.250.14:5060) State REPORTING going to sleep
  1387. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_state_machine.c:607 (sofia/default/0653910229@172.16.250.14:5060) State Change CS_REPORTING -> CS_DESTROY
  1388. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_session.c:1743 Session 368977 (sofia/default/0653910229@172.16.250.14:5060) Locked, Waiting on external entities
  1389. 2023-08-28 12:26:57.094278 99.30% [NOTICE] switch_core_session.c:1761 Session 368977 (sofia/default/0653910229@172.16.250.14:5060) Ended
  1390. 2023-08-28 12:26:57.094278 99.30% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/0653910229@172.16.250.14:5060 [CS_DESTROY]
  1391. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_state_machine.c:735 (sofia/default/0653910229@172.16.250.14:5060) Running State Change CS_DESTROY (Cur 0 Tot 368979)
  1392. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/0653910229@172.16.250.14:5060) State DESTROY
  1393. 2023-08-28 12:26:57.094278 99.30% [DEBUG] mod_sofia.c:379 sofia/default/0653910229@172.16.250.14:5060 SOFIA DESTROY
  1394. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_state_machine.c:175 sofia/default/0653910229@172.16.250.14:5060 Standard DESTROY
  1395. 2023-08-28 12:26:57.094278 99.30% [DEBUG] switch_core_state_machine.c:745 (sofia/default/0653910229@172.16.250.14:5060) State DESTROY going to sleep
  1396.  

Reply to "clint"