1.  
2. 2020-10-27 12:10:38.205813 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/pbx/ivr(2)_211020121726.wav
3. 2020-10-27 12:10:38.205813 [DEBUG] switch_ivr_menu.c:378 waiting for 20/20 digits t/o 2000
4. 2020-10-27 12:10:40.725806 [WARNING] switch_core_file.c:410 File has 2 channels, muxing to 1 channel will occur.
5. 2020-10-27 12:10:41.805800 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 2:968
6. 2020-10-27 12:10:41.805800 [INFO] switch_channel.c:522 RECV DTMF 2:968
7. 2020-10-27 12:10:43.805809 [DEBUG] switch_ivr_menu.c:425 digits '2'
8. 2020-10-27 12:10:43.805809 [DEBUG] switch_ivr_menu.c:580 IVR action on menu 'iVRtest0' matched '2' param 'transfer 9566364151 XML ${domain_name}'
9. 2020-10-27 12:10:43.805809 [DEBUG] switch_ivr_menu.c:584 switch_ivr_menu_execute todo=[2]
10. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 transfer(9566364151 XML 192.168.8.3)
11. 2020-10-27 12:10:43.805809 [DEBUG] switch_ivr.c:2231 (sofia/default/8138902220@192.168.8.6) State Change CS_EXECUTE -> CS_ROUTING
12. 2020-10-27 12:10:43.805809 [NOTICE] switch_ivr.c:2238 Transfer sofia/default/8138902220@192.168.8.6 to XML[9566364151@192.168.8.3]
13. 2020-10-27 12:10:43.805809 [DEBUG] switch_ivr_menu.c:562 action regex [2] [/^(\d{2,20})$/] [0]
14. 2020-10-27 12:10:43.805809 [DEBUG] switch_ivr_menu.c:676 exit-sound 'voicemail/vm-goodbye.wav'
15. 2020-10-27 12:10:43.805809 [DEBUG] switch_core_state_machine.c:651 (sofia/default/8138902220@192.168.8.6) State EXECUTE going to sleep
16. 2020-10-27 12:10:43.805809 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_ROUTING (Cur 1 Tot 8877)
17. 2020-10-27 12:10:43.805809 [DEBUG] switch_channel.c:2332 (sofia/default/8138902220@192.168.8.6) Callstate Change ACTIVE -> RINGING
18. 2020-10-27 12:10:43.805809 [DEBUG] switch_core_state_machine.c:644 (sofia/default/8138902220@192.168.8.6) State ROUTING
19. 2020-10-27 12:10:43.805809 [DEBUG] mod_sofia.c:154 sofia/default/8138902220@192.168.8.6 SOFIA ROUTING
20. 2020-10-27 12:10:43.805809 [DEBUG] switch_core_state_machine.c:236 sofia/default/8138902220@192.168.8.6 Standard ROUTING
21. 2020-10-27 12:10:43.805809 [INFO] mod_dialplan_xml.c:637 Processing 8138902220 <8138902220>->9566364151 in context 192.168.8.3
22. 2020-10-27 12:10:43.825807 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
23. 2020-10-27 12:10:43.825807 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
24. 2020-10-27 12:10:43.850728 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
25. Event-Name: REQUEST_PARAMS
26. Core-UUID: f0e00936-3fce-4a2b-af76-c25556ae0e1a
27. FreeSWITCH-Hostname: soultech
28. FreeSWITCH-Switchname: soultech
29. FreeSWITCH-IPv4: 192.168.8.3
30. FreeSWITCH-IPv6: %3A%3A1
31. Event-Date-Local: 2020-10-27%2012%3A10%3A43
32. Event-Date-GMT: Tue,%2027%20Oct%202020%2011%3A10%3A43%20GMT
33. Event-Date-Timestamp: 1603797043805809
34. Event-Calling-File: mod_dialplan_xml.c
35. Event-Calling-Function: dialplan_xml_locate
36. Event-Calling-Line-Number: 608
37. Event-Sequence: 454458
38. Channel-State: CS_ROUTING
39. Channel-Call-State: RINGING
40. Channel-State-Number: 2
41. Channel-Name: sofia/default/8138902220%40192.168.8.6
42. Unique-ID: ae154372-5007-45fa-978b-09c67b2e0b10
43. Call-Direction: inbound
44. Presence-Call-Direction: inbound
45. Channel-HIT-Dialplan: true
46. Channel-Presence-ID: 8138902220%40192.168.8.6
47. Channel-Presence-Data: x%7C%7C%7Cmartins(1135674299)%7C%7C%7C%5E9088999989.*%20//%209088999989%20//%200.00000%7C%7C%7C%7C%7C%7CDID
48. Channel-Call-UUID: ae154372-5007-45fa-978b-09c67b2e0b10
49. Answer-State: answered
50. Channel-Read-Codec-Name: PCMU
51. Channel-Read-Codec-Rate: 8000
52. Channel-Read-Codec-Bit-Rate: 64000
53. Channel-Write-Codec-Name: PCMU
54. Channel-Write-Codec-Rate: 8000
55. Channel-Write-Codec-Bit-Rate: 64000
56. Caller-Direction: inbound
57. Caller-Logical-Direction: inbound
58. Caller-Username: 8138902220
59. Caller-Dialplan: XML
60. Caller-Caller-ID-Name: 8138902220
61. Caller-Caller-ID-Number: 8138902220
62. Caller-Orig-Caller-ID-Name: 8138902220
63. Caller-Orig-Caller-ID-Number: 8138902220
64. Caller-Network-Addr: 192.168.8.6
65. Caller-ANI: 8138902220
66. Caller-Destination-Number: 9566364151
67. Caller-Unique-ID: ae154372-5007-45fa-978b-09c67b2e0b10
68. Caller-Source: mod_sofia
69. Caller-Transfer-Source: 1603797043%3A125a69b4-dd4b-4dd7-ac81-465fd23b5081%3Abl_xfer%3A9566364151/192.168.8.3/XML
70. Caller-Context: 192.168.8.3
71. Caller-RDNIS: 9088999989
72. Caller-Channel-Name: sofia/default/8138902220%40192.168.8.6
73. Caller-Profile-Index: 2
74. Caller-Profile-Created-Time: 1603797043805809
75. Caller-Channel-Created-Time: 1603797020385805
76. Caller-Channel-Answered-Time: 1603797020525804
77. Caller-Channel-Progress-Time: 0
78. Caller-Channel-Progress-Media-Time: 0
79. Caller-Channel-Hangup-Time: 0
80. Caller-Channel-Transfer-Time: 0
81. Caller-Channel-Resurrect-Time: 0
82. Caller-Channel-Bridged-Time: 0
83. Caller-Channel-Last-Hold: 0
84. Caller-Channel-Hold-Accum: 0
85. Caller-Screen-Bit: true
86. Caller-Privacy-Hide-Name: false
87. Caller-Privacy-Hide-Number: false
88. variable_direction: inbound
89. variable_uuid: ae154372-5007-45fa-978b-09c67b2e0b10
90. variable_session_id: 8876
91. variable_sip_from_user: 8138902220
92. variable_sip_from_uri: 8138902220%40192.168.8.6
93. variable_sip_from_host: 192.168.8.6
94. variable_video_media_flow: disabled
95. variable_text_media_flow: disabled
96. variable_channel_name: sofia/default/8138902220%40192.168.8.6
97. variable_sip_local_network_addr: 192.168.8.3
98. variable_sip_network_ip: 192.168.8.6
99. variable_sip_network_port: 5060
100. variable_sip_invite_stamp: 1603797020385805
101. variable_sip_received_ip: 192.168.8.6
102. variable_sip_received_port: 5060
103. variable_sip_via_protocol: udp
104. variable_sip_authorized: true
105. variable_sip_acl_authed_by: default
106. variable_sip_from_user_stripped: 8138902220
107. variable_sofia_profile_name: default
108. variable_sofia_profile_url: sip%3Amod_sofia%40192.168.8.3%3A5060
109. variable_recovery_profile_name: default
110. variable_sip_allow: OPTIONS,%20SUBSCRIBE,%20NOTIFY,%20PUBLISH,%20INVITE,%20ACK,%20BYE,%20CANCEL,%20UPDATE,%20PRACK,%20REGISTER,%20MESSAGE,%20REFER
111. variable_sip_req_user: 9088999989
112. variable_sip_req_uri: 9088999989%40192.168.8.3
113. variable_sip_req_host: 192.168.8.3
114. variable_sip_to_user: 9088999989
115. variable_sip_to_uri: 9088999989%40192.168.8.3
116. variable_sip_to_host: 192.168.8.3
117. variable_sip_contact_user: asterisk
118. variable_sip_contact_port: 5060
119. variable_sip_contact_uri: asterisk%40192.168.8.6%3A5060
120. variable_sip_contact_host: 192.168.8.6
121. variable_sip_user_agent: FPBX-14.0.13.40(13.29.2)
122. variable_sip_via_host: 192.168.8.6
123. variable_sip_via_port: 5060
124. variable_sip_via_rport: 5060
125. variable_presence_id: 8138902220%40192.168.8.6
126. variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%20702233995%20702233995%20IN%20IP4%20192.168.8.6%0D%0As%3DAsterisk%0D%0Ac%3DIN%20IP4%20192.168.8.6%0D%0At%3D0%200%0D%0Am%3Daudio%2013572%20RTP/AVP%200%208%203%20111%209%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A3%20GSM/8000%0D%0Aa%3Drtpmap%3A111%20G726-32/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dmaxptime%3A150%0D%0A
127. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
128. variable_rtp_use_codec_string: PCMA,PCMU
129. variable_remote_video_media_flow: inactive
130. variable_remote_text_media_flow: inactive
131. variable_remote_audio_media_flow: sendrecv
132. variable_audio_media_flow: sendrecv
133. variable_rtp_audio_recv_pt: 0
134. variable_rtp_use_codec_name: PCMU
135. variable_rtp_use_codec_rate: 8000
136. variable_rtp_use_codec_ptime: 20
137. variable_rtp_use_codec_channels: 1
138. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
139. variable_read_codec: PCMU
140. variable_original_read_codec: PCMU
141. variable_read_rate: 8000
142. variable_original_read_rate: 8000
143. variable_write_codec: PCMU
144. variable_write_rate: 8000
145. variable_dtmf_type: rfc2833
146. variable_effective_destination_number: 9088999989
147. variable_bridge_pre_execute_bleg_app: sched_hangup
148. variable_bridge_pre_execute_bleg_data: %2B6000%20normal_clearing
149. variable_callstart: 2020-10-27%2011%3A10%3A20
150. variable_hangup_after_bridge: true
151. variable_continue_on_fail: TRUE
152. variable_account_id: 123
153. variable_parent_id: 0
154. variable_entity_id: 0
155. variable_call_processed: internal
156. variable_call_direction: inbound
157. variable_accountname: HOD1
158. variable_origination_rates_did: ID%3A40%7CCODE%3A%5E9088999989.*%7CDESTINATION%3A9088999989%7CCONNECTIONCOST%3A0.00000%7CINCLUDEDSECONDS%3A0%7CCT%3A10%7CCOST%3A0.00000%7CINC%3A0%7CINITIALBLOCK%3A0%7CRATEGROUP%3A0%7CMARKUP%3A0%7CCI%3A141%7CACCID%3A138
159. variable_origination_rates: 0
160. variable_original_caller_id_name: 9088999989
161. variable_original_caller_id_number: 9088999989
162. variable_effective_caller_id_name: 9088999989
163. variable_effective_caller_id_number: 9088999989
164. variable_receiver_accid: 138
165. variable_presence_data: x%7C%7C%7Cmartins(1135674299)%7C%7C%7C%5E9088999989.*%20//%209088999989%20//%200.00000%7C%7C%7C%7C%7C%7CDID
166. variable_call_type: 10
167. variable_export_vars: presence_data,call_type
168. variable_did_calltype: 10
169. variable_did_extensions: 12
170. variable_module_name: pbx
171. variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201603776934%201603776935%20IN%20IP4%20192.168.8.3%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%20192.168.8.3%0D%0At%3D0%200%0D%0Am%3Daudio%2020086%20RTP/AVP%200%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
172. variable_local_media_ip: 192.168.8.3
173. variable_local_media_port: 20086
174. variable_advertised_media_ip: 192.168.8.3
175. variable_rtp_use_timer_name: soft
176. variable_rtp_use_pt: 0
177. variable_rtp_use_ssrc: 64067780
178. variable_rtp_2833_send_payload: 101
179. variable_rtp_2833_recv_payload: 101
180. variable_remote_media_ip: 192.168.8.6
181. variable_remote_media_port: 13572
182. variable_endpoint_disposition: ANSWER
183. variable_sip_to_tag: U6Bt432p62vmc
184. variable_sip_from_tag: 6c25189e-9233-41e4-8de0-fb50700f273b
185. variable_sip_cseq: 28281
186. variable_sip_call_id: fd577983-79e4-4dc7-bf96-31cd59a2eb70
187. variable_sip_full_via: SIP/2.0/UDP%20192.168.8.6%3A5060%3Brport%3D5060%3Bbranch%3Dz9hG4bKPjb39079ba-0d9f-4212-8cf5-369dbb799560
188. variable_sip_from_display: 8138902220
189. variable_sip_full_from: %228138902220%22%20%3Csip%3A8138902220%40192.168.8.6%3E%3Btag%3D6c25189e-9233-41e4-8de0-fb50700f273b
190. variable_sip_full_to: %3Csip%3A9088999989%40192.168.8.3%3E%3Btag%3DU6Bt432p62vmc
191. variable_ivr_menu_status: success
192. variable_RECORD_SOFTWARE: Lavf58.46.101
193. variable_playback_last_offset_pos: 141271
194. variable_playback_seconds: 17
195. variable_playback_ms: 17658
196. variable_playback_samples: 141271
197. variable_current_application_data: 9566364151%20XML%20192.168.8.3
198. variable_current_application: transfer
199. variable_max_forwards: 69
200. variable_transfer_history: 1603797043%3A125a69b4-dd4b-4dd7-ac81-465fd23b5081%3Abl_xfer%3A9566364151/192.168.8.3/XML
201. variable_transfer_source: 1603797043%3A125a69b4-dd4b-4dd7-ac81-465fd23b5081%3Abl_xfer%3A9566364151/192.168.8.3/XML
202. variable_call_uuid: ae154372-5007-45fa-978b-09c67b2e0b10
203. Hunt-Direction: inbound
204. Hunt-Logical-Direction: inbound
205. Hunt-Username: 8138902220
206. Hunt-Dialplan: XML
207. Hunt-Caller-ID-Name: 8138902220
208. Hunt-Caller-ID-Number: 8138902220
209. Hunt-Orig-Caller-ID-Name: 8138902220
210. Hunt-Orig-Caller-ID-Number: 8138902220
211. Hunt-Network-Addr: 192.168.8.6
212. Hunt-ANI: 8138902220
213. Hunt-Destination-Number: 9566364151
214. Hunt-Unique-ID: ae154372-5007-45fa-978b-09c67b2e0b10
215. Hunt-Source: mod_sofia
216. Hunt-Transfer-Source: 1603797043%3A125a69b4-dd4b-4dd7-ac81-465fd23b5081%3Abl_xfer%3A9566364151/192.168.8.3/XML
217. Hunt-Context: 192.168.8.3
218. Hunt-RDNIS: 9088999989
219. Hunt-Channel-Name: sofia/default/8138902220%40192.168.8.6
220. Hunt-Profile-Index: 2
221. Hunt-Profile-Created-Time: 1603797043805809
222. Hunt-Channel-Created-Time: 1603797020385805
223. Hunt-Channel-Answered-Time: 1603797020525804
224. Hunt-Channel-Progress-Time: 0
225. Hunt-Channel-Progress-Media-Time: 0
226. Hunt-Channel-Hangup-Time: 0
227. Hunt-Channel-Transfer-Time: 0
228. Hunt-Channel-Resurrect-Time: 0
229. Hunt-Channel-Bridged-Time: 0
230. Hunt-Channel-Last-Hold: 0
231. Hunt-Channel-Hold-Accum: 0
232. Hunt-Screen-Bit: true
233. Hunt-Privacy-Hide-Name: false
234. Hunt-Privacy-Hide-Number: false
235.  
236.  
237. 2020-10-27 12:10:43.850728 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name:
238. 2020-10-27 12:10:43.850728 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name:
239. 2020-10-27 12:10:43.850728 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
240. 2020-10-27 12:10:43.850728 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value:
241. 2020-10-27 12:10:43.850728 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] blind_flag: 0
242. 2020-10-27 12:10:43.850728 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 9566364151
243. 2020-10-27 12:10:43.850728 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '9566364151' limit 1
244. 2020-10-27 12:10:43.850728 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '9566364151' AND status=0 limit 1
245. 2020-10-27 12:10:43.850728 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '9566364151' and status=0 LIMIT 1
246. 2020-10-27 12:10:43.850728 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : 9088999989 / 9088999989
247. 2020-10-27 12:10:43.885802 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
248. 2020-10-27 12:10:43.885802 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
249. 2020-10-27 12:10:43.885802 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="9566364151" LIMIT 1
250. 2020-10-27 12:10:43.885802 [DEBUG] switch_cpp.cpp:1443 [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 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="9566364151"  AND domains.domain="192.168.8.3" limit 1
251. 2020-10-27 12:10:43.885802 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : outbound
252. 2020-10-27 12:10:43.885802 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("192.168.8.6") 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 "9566364151"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
253. 2020-10-27 12:10:43.885802 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 1135674299]
254. 2020-10-27 12:10:43.885802 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "1135674299" AND deleted = 0 limit 1
255. 2020-10-27 12:10:43.885802 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE number = "1135674299" AND deleted = 0 limit 1
256. 2020-10-27 12:10:43.885802 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^9566364151.*' OR blocked_patterns = '^956636415.*' OR blocked_patterns = '^95663641.*' OR blocked_patterns = '^9566364.*' OR blocked_patterns = '^956636.*' OR blocked_patterns = '^95663.*' OR blocked_patterns = '^9566.*' OR blocked_patterns = '^956.*' OR blocked_patterns = '^95.*' OR blocked_patterns = '^9.*' OR blocked_patterns ='--') AND accountid = 123 limit 1
257. 2020-10-27 12:10:43.905808 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="9566364151" AND A.accountid = '123' limit 1
258. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 9566364151
259. 2020-10-27 12:10:43.905900 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
260. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
261. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="9566364151" LIMIT 1
262. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [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 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="9566364151"  AND domains.domain="192.168.8.3" limit 1
263. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : outbound
264. 2020-10-27 12:10:43.905900 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
265. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
266. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="9566364151" AND (accountid = 0 OR status = 1) LIMIT 1
267. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^9566364151.*' OR patterns = '^956636415.*' OR patterns = '^95663641.*' OR patterns = '^9566364.*' OR patterns = '^956636.*' OR patterns = '^95663.*' OR patterns = '^9566.*' OR patterns = '^956.*' OR patterns = '^95.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 123 ORDER BY LENGTH(PKGPTR.patterns) DESC
268. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '9566364151' OR number = '956636415' OR number = '95663641' OR number = '9566364' OR number = '956636' OR number = '95663' OR number = '9566' OR number = '956' OR number = '95' OR number = '9' OR number ='--') OR (number = '9566364151*' OR number = '956636415*' OR number = '95663641*' OR number = '9566364*' OR number = '956636*' OR number = '95663*' OR number = '9566*' OR number = '956*' OR number = '95*' OR number = '9*' OR number ='--') OR (number = '9088999989' OR number = '908899998' OR number = '90889999' OR number = '9088999' OR number = '908899' OR number = '90889' OR number = '9088' OR number = '908' OR number = '90' OR number = '9' OR number ='--') OR (number = '9088999989*' OR number = '908899998*' OR number = '90889999*' OR number = '9088999*' OR number = '908899*' OR number = '90889*' OR number = '9088*' OR number = '908*' OR number = '90*' OR number = '9*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
269. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
270. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '9566364151' OR routing_prefix = '956636415' OR routing_prefix = '95663641' OR routing_prefix = '9566364' OR routing_prefix = '956636' OR routing_prefix = '95663' OR routing_prefix = '9566' OR routing_prefix = '956' OR routing_prefix = '95' OR routing_prefix = '9' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
271. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
272. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 123
273. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 1135674299
274. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 6856.66
275. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
276. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 1
277. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 0
278. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
279. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
280. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :outbound
281. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^9566364151.*' OR pattern = '^956636415.*' OR pattern = '^95663641.*' OR pattern = '^9566364.*' OR pattern = '^956636.*' OR pattern = '^95663.*' OR pattern = '^9566.*' OR pattern = '^956.*' OR pattern = '^95.*' OR pattern = '^9.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 1 OR accountid=123)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
282. 2020-10-27 12:10:43.905900 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: outbound
283. 2020-10-27 12:10:43.905900 [WARNING] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Rates not found!!!
284. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] post_cdrs:::0
285. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 123
286. 2020-10-27 12:10:43.905900 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Generated XML:
287. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
288. <document type="freeswitch/xml">
289. <section name="dialplan" description="ASTPP Dialplan">
290. <context name="192.168.8.3">
291. <extension name="9566364151">
292. <condition field="destination_number" expression="9566364151">
293. <action application="log" data="WARNING Accountcode . Dialed number (9566364151)  origination rates not found!!"/>
294. <action application="set" data="original_caller_id_name=9088999989"/>
295. <action application="set" data="original_caller_id_number=9088999989"/>
296. <action application="set" data="error_cdr=1"/>
297. <action application="set" data="callstart=2020-10-27 11:10:43"/>
298. <action application="set" data="account_id=123"/>
299. <action application="set" data="parent_id=0"/>
300. <action application="set" data="call_direction=outbound"/>
301. <action application="set" data="sip_ignore_remote_cause=true"/>
302. <action application="set" data="call_processed=internal"/>
303. <action application="set" data="effective_destination_number=9566364151"/>
304. <action application="set" data="last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND"/>
305. <action application="hangup" data="ORIGNATION_RATE_NOT_FOUND"/>
306. </condition>
307. </extension>
308. </context>
309. </section>
310. </document>
311. Dialplan: sofia/default/8138902220@192.168.8.6 parsing [192.168.8.3->9566364151] continue=false
312. Dialplan: sofia/default/8138902220@192.168.8.6 Regex (PASS) [9566364151] destination_number(9566364151) =~ /9566364151/ break=on-false
313. Dialplan: sofia/default/8138902220@192.168.8.6 Action log(WARNING Accountcode . Dialed number (9566364151)  origination rates not found!!)
314. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(original_caller_id_name=9088999989)
315. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(original_caller_id_number=9088999989)
316. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(error_cdr=1)
317. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(callstart=2020-10-27 11:10:43)
318. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(account_id=123)
319. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(parent_id=0)
320. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(call_direction=outbound)
321. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(sip_ignore_remote_cause=true)
322. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(call_processed=internal)
323. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(effective_destination_number=9566364151)
324. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND)
325. Dialplan: sofia/default/8138902220@192.168.8.6 Action hangup(ORIGNATION_RATE_NOT_FOUND)
326. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:287 (sofia/default/8138902220@192.168.8.6) State Change CS_ROUTING -> CS_EXECUTE
327. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:644 (sofia/default/8138902220@192.168.8.6) State ROUTING going to sleep
328. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_EXECUTE (Cur 1 Tot 8877)
329. 2020-10-27 12:10:43.905900 [DEBUG] switch_channel.c:2334 (sofia/default/8138902220@192.168.8.6) Callstate Change RINGING -> ACTIVE
330. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:651 (sofia/default/8138902220@192.168.8.6) State EXECUTE
331. 2020-10-27 12:10:43.905900 [DEBUG] mod_sofia.c:209 sofia/default/8138902220@192.168.8.6 SOFIA EXECUTE
332. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:329 sofia/default/8138902220@192.168.8.6 Standard EXECUTE
333. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 log(WARNING Accountcode . Dialed number (9566364151)  origination rates not found!!)
334. 2020-10-27 12:10:43.905900 [WARNING] mod_dptools.c:1866 Accountcode . Dialed number (9566364151)  origination rates not found!!
335. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(original_caller_id_name=9088999989)
336. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [original_caller_id_name]=[9088999989]
337. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(original_caller_id_number=9088999989)
338. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [original_caller_id_number]=[9088999989]
339. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(error_cdr=1)
340. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [error_cdr]=[1]
341. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(callstart=2020-10-27 11:10:43)
342. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [callstart]=[2020-10-27 11:10:43]
343. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(account_id=123)
344. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [account_id]=[123]
345. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(parent_id=0)
346. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [parent_id]=[0]
347. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(call_direction=outbound)
348. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [call_direction]=[outbound]
349. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(sip_ignore_remote_cause=true)
350. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [sip_ignore_remote_cause]=[true]
351. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(call_processed=internal)
352. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [call_processed]=[internal]
353. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(effective_destination_number=9566364151)
354. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [effective_destination_number]=[9566364151]
355. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND)
356. 2020-10-27 12:10:43.905900 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [last_bridge_hangup_cause]=[ORIGNATION_RATE_NOT_FOUND]
357. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 hangup(ORIGNATION_RATE_NOT_FOUND)
358. 2020-10-27 12:10:43.905900 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/8138902220@192.168.8.6 [CS_EXECUTE] [NORMAL_CLEARING]
359. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_session.c:2905 sofia/default/8138902220@192.168.8.6 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
360. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:651 (sofia/default/8138902220@192.168.8.6) State EXECUTE going to sleep
361. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_HANGUP (Cur 1 Tot 8877)
362. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:848 (sofia/default/8138902220@192.168.8.6) Callstate Change ACTIVE -> HANGUP
363. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:850 (sofia/default/8138902220@192.168.8.6) State HANGUP
364. 2020-10-27 12:10:43.905900 [DEBUG] mod_sofia.c:460 Channel sofia/default/8138902220@192.168.8.6 hanging up, cause: NORMAL_CLEARING
365. 2020-10-27 12:10:43.905900 [DEBUG] mod_sofia.c:514 Sending BYE to sofia/default/8138902220@192.168.8.6
366. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:60 sofia/default/8138902220@192.168.8.6 Standard HANGUP, cause: NORMAL_CLEARING
367. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:850 (sofia/default/8138902220@192.168.8.6) State HANGUP going to sleep
368. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:620 (sofia/default/8138902220@192.168.8.6) State Change CS_HANGUP -> CS_REPORTING
369. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_REPORTING (Cur 1 Tot 8877)
370. 2020-10-27 12:10:43.905900 [DEBUG] switch_core_state_machine.c:936 (sofia/default/8138902220@192.168.8.6) State REPORTING
371. 2020-10-27 12:10:43.905900 [INFO] mod_json_cdr.c:271 Process [ae154372-5007-45fa-978b-09c67b2e0b10.cdr.json]
372. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_state_machine.c:174 sofia/default/8138902220@192.168.8.6 Standard REPORTING, cause: NORMAL_CLEARING
373. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_state_machine.c:936 (sofia/default/8138902220@192.168.8.6) State REPORTING going to sleep
374. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_state_machine.c:611 (sofia/default/8138902220@192.168.8.6) State Change CS_REPORTING -> CS_DESTROY
375. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_session.c:1726 Session 8876 (sofia/default/8138902220@192.168.8.6) Locked, Waiting on external entities
376. 2020-10-27 12:10:43.985802 [NOTICE] switch_core_session.c:1744 Session 8876 (sofia/default/8138902220@192.168.8.6) Ended
377. 2020-10-27 12:10:43.985802 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/8138902220@192.168.8.6 [CS_DESTROY]
378. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_state_machine.c:739 (sofia/default/8138902220@192.168.8.6) Running State Change CS_DESTROY (Cur 0 Tot 8877)
379. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_state_machine.c:749 (sofia/default/8138902220@192.168.8.6) State DESTROY
380. 2020-10-27 12:10:43.985802 [DEBUG] mod_sofia.c:365 sofia/default/8138902220@192.168.8.6 SOFIA DESTROY
381. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_state_machine.c:181 sofia/default/8138902220@192.168.8.6 Standard DESTROY
382. 2020-10-27 12:10:43.985802 [DEBUG] switch_core_state_machine.c:749 (sofia/default/8138902220@192.168.8.6) State DESTROY going to sleep
383. 2020-10-27 12:10:47.465802 [WARNING] switch_core_file.c:410 File has 2 channels, muxing to 1 channel will occur.
384. 2020-10-27 12:10:53.165802 [WARNING] switch_core_file.c:410 File has 2 channels, muxing to 1 channel will occur.
385. 2020-10-27 12:11:03.905802 [WARNING] switch_core_file.c:410 File has 2 channels, muxing to 1 channel will occur.
386. freeswitch@soultech>
387. freeswitch@soultech>
388. freeswitch@soultech>
389. freeswitch@soultech>
390. 2020-10-27 12:11:05.565800 [NOTICE] switch_channel.c:1118 New Channel sofia/default/8138902220@192.168.8.6 [d96a1153-e817-46ae-a3bd-61b2d6bbcd0d]
391. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_NEW (Cur 1 Tot 8878)
392. 2020-10-27 12:11:05.565800 [DEBUG] sofia.c:10243 sofia/default/8138902220@192.168.8.6 receiving invite from 192.168.8.6:5060 version: 1.10.1 -release-12-f9990221e6 64bit
393. 2020-10-27 12:11:05.565800 [DEBUG] sofia.c:10337 verifying acl "default" for ip/port 192.168.8.6:0.
394. 2020-10-27 12:11:05.565800 [DEBUG] sofia.c:10366 IP 192.168.8.6 Approved by acl "default[]". Access Granted.
395. 2020-10-27 12:11:05.565800 [DEBUG] sofia.c:7290 Channel sofia/default/8138902220@192.168.8.6 entering state [received][100]
396. 2020-10-27 12:11:05.565800 [DEBUG] sofia.c:7300 Remote SDP:
397. v=0
398. o=- 49732651 49732651 IN IP4 192.168.8.6
399. s=Asterisk
400. c=IN IP4 192.168.8.6
401. t=0 0
402. m=audio 13976 RTP/AVP 0 8 3 111 9 101
403. a=rtpmap:0 PCMU/8000
404. a=rtpmap:8 PCMA/8000
405. a=rtpmap:3 GSM/8000
406. a=rtpmap:111 G726-32/8000
407. a=rtpmap:9 G722/8000
408. a=rtpmap:101 telephone-event/8000
409. a=fmtp:101 0-16
410. a=ptime:20
411. a=maxptime:150
412.  
413. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
414. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
415. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
416. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
417. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
418. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
419. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
420. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
421. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
422. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G726-32:111:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
423. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
424. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
425. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5506 Set telephone-event payload to 101@8000
426. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:3834 Set Codec sofia/default/8138902220@192.168.8.6 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
427. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_codec.c:111 sofia/default/8138902220@192.168.8.6 Original read codec set to PCMU:0
428. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5849 Set telephone-event payload to 101@8000
429. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_media.c:5907 sofia/default/8138902220@192.168.8.6 Set 2833 dtmf send payload to 101 recv payload to 101
430. 2020-10-27 12:11:05.565800 [DEBUG] sofia.c:7724 (sofia/default/8138902220@192.168.8.6) State Change CS_NEW -> CS_INIT
431. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:604 (sofia/default/8138902220@192.168.8.6) State NEW
432. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_INIT (Cur 1 Tot 8878)
433. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:628 (sofia/default/8138902220@192.168.8.6) State INIT
434. 2020-10-27 12:11:05.565800 [DEBUG] mod_sofia.c:93 sofia/default/8138902220@192.168.8.6 SOFIA INIT
435. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:40 sofia/default/8138902220@192.168.8.6 Standard INIT
436. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:48 (sofia/default/8138902220@192.168.8.6) State Change CS_INIT -> CS_ROUTING
437. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:628 (sofia/default/8138902220@192.168.8.6) State INIT going to sleep
438. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_ROUTING (Cur 1 Tot 8878)
439. 2020-10-27 12:11:05.565800 [DEBUG] switch_channel.c:2332 (sofia/default/8138902220@192.168.8.6) Callstate Change DOWN -> RINGING
440. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:644 (sofia/default/8138902220@192.168.8.6) State ROUTING
441. 2020-10-27 12:11:05.565800 [DEBUG] mod_sofia.c:154 sofia/default/8138902220@192.168.8.6 SOFIA ROUTING
442. 2020-10-27 12:11:05.565800 [DEBUG] switch_core_state_machine.c:236 sofia/default/8138902220@192.168.8.6 Standard ROUTING
443. 2020-10-27 12:11:05.565800 [INFO] mod_dialplan_xml.c:637 Processing 8138902220 <8138902220>->9088999989 in context default
444. 2020-10-27 12:11:05.565800 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
445. 2020-10-27 12:11:05.585798 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
446. freeswitch@soultech>
447. 2020-10-27 12:11:05.625803 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
448. Event-Name: REQUEST_PARAMS
449. Core-UUID: f0e00936-3fce-4a2b-af76-c25556ae0e1a
450. FreeSWITCH-Hostname: soultech
451. FreeSWITCH-Switchname: soultech
452. FreeSWITCH-IPv4: 192.168.8.3
453. FreeSWITCH-IPv6: %3A%3A1
454. Event-Date-Local: 2020-10-27%2012%3A11%3A05
455. Event-Date-GMT: Tue,%2027%20Oct%202020%2011%3A11%3A05%20GMT
456. Event-Date-Timestamp: 1603797065565800
457. Event-Calling-File: mod_dialplan_xml.c
458. Event-Calling-Function: dialplan_xml_locate
459. Event-Calling-Line-Number: 608
460. Event-Sequence: 454506
461. Channel-State: CS_ROUTING
462. Channel-Call-State: RINGING
463. Channel-State-Number: 2
464. Channel-Name: sofia/default/8138902220%40192.168.8.6
465. Unique-ID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
466. Call-Direction: inbound
467. Presence-Call-Direction: inbound
468. Channel-HIT-Dialplan: true
469. Channel-Presence-ID: 8138902220%40192.168.8.6
470. Channel-Call-UUID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
471. Answer-State: ringing
472. Channel-Read-Codec-Name: PCMU
473. Channel-Read-Codec-Rate: 8000
474. Channel-Read-Codec-Bit-Rate: 64000
475. Channel-Write-Codec-Name: PCMU
476. Channel-Write-Codec-Rate: 8000
477. Channel-Write-Codec-Bit-Rate: 64000
478. Caller-Direction: inbound
479. Caller-Logical-Direction: inbound
480. Caller-Username: 8138902220
481. Caller-Dialplan: XML
482. Caller-Caller-ID-Name: 8138902220
483. Caller-Caller-ID-Number: 8138902220
484. Caller-Orig-Caller-ID-Name: 8138902220
485. Caller-Orig-Caller-ID-Number: 8138902220
486. Caller-Network-Addr: 192.168.8.6
487. Caller-ANI: 8138902220
488. Caller-Destination-Number: 9088999989
489. Caller-Unique-ID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
490. Caller-Source: mod_sofia
491. Caller-Context: default
492. Caller-Channel-Name: sofia/default/8138902220%40192.168.8.6
493. Caller-Profile-Index: 1
494. Caller-Profile-Created-Time: 1603797065565800
495. Caller-Channel-Created-Time: 1603797065565800
496. Caller-Channel-Answered-Time: 0
497. Caller-Channel-Progress-Time: 0
498. Caller-Channel-Progress-Media-Time: 0
499. Caller-Channel-Hangup-Time: 0
500. Caller-Channel-Transfer-Time: 0
501. Caller-Channel-Resurrect-Time: 0
502. Caller-Channel-Bridged-Time: 0
503. Caller-Channel-Last-Hold: 0
504. Caller-Channel-Hold-Accum: 0
505. Caller-Screen-Bit: true
506. Caller-Privacy-Hide-Name: false
507. Caller-Privacy-Hide-Number: false
508. variable_direction: inbound
509. variable_uuid: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
510. variable_session_id: 8878
511. variable_sip_from_user: 8138902220
512. variable_sip_from_uri: 8138902220%40192.168.8.6
513. variable_sip_from_host: 192.168.8.6
514. variable_video_media_flow: disabled
515. variable_text_media_flow: disabled
516. variable_channel_name: sofia/default/8138902220%40192.168.8.6
517. variable_sip_call_id: cb76c3b5-2f4e-46d5-b10d-777c42fb6183
518. variable_sip_local_network_addr: 192.168.8.3
519. variable_sip_network_ip: 192.168.8.6
520. variable_sip_network_port: 5060
521. variable_sip_invite_stamp: 1603797065565800
522. variable_sip_received_ip: 192.168.8.6
523. variable_sip_received_port: 5060
524. variable_sip_via_protocol: udp
525. variable_sip_authorized: true
526. variable_sip_acl_authed_by: default
527. variable_sip_from_user_stripped: 8138902220
528. variable_sip_from_tag: 0c4f9baa-7528-4f59-8898-957434b5e5ab
529. variable_sofia_profile_name: default
530. variable_sofia_profile_url: sip%3Amod_sofia%40192.168.8.3%3A5060
531. variable_recovery_profile_name: default
532. variable_sip_full_via: SIP/2.0/UDP%20192.168.8.6%3A5060%3Brport%3D5060%3Bbranch%3Dz9hG4bKPjbcec0b43-403a-4b9a-a6c8-45e106277d66
533. variable_sip_from_display: 8138902220
534. variable_sip_full_from: %228138902220%22%20%3Csip%3A8138902220%40192.168.8.6%3E%3Btag%3D0c4f9baa-7528-4f59-8898-957434b5e5ab
535. variable_sip_full_to: %3Csip%3A9088999989%40192.168.8.3%3E
536. variable_sip_allow: OPTIONS,%20SUBSCRIBE,%20NOTIFY,%20PUBLISH,%20INVITE,%20ACK,%20BYE,%20CANCEL,%20UPDATE,%20PRACK,%20REGISTER,%20MESSAGE,%20REFER
537. variable_sip_req_user: 9088999989
538. variable_sip_req_uri: 9088999989%40192.168.8.3
539. variable_sip_req_host: 192.168.8.3
540. variable_sip_to_user: 9088999989
541. variable_sip_to_uri: 9088999989%40192.168.8.3
542. variable_sip_to_host: 192.168.8.3
543. variable_sip_contact_user: asterisk
544. variable_sip_contact_port: 5060
545. variable_sip_contact_uri: asterisk%40192.168.8.6%3A5060
546. variable_sip_contact_host: 192.168.8.6
547. variable_sip_user_agent: FPBX-14.0.13.40(13.29.2)
548. variable_sip_via_host: 192.168.8.6
549. variable_sip_via_port: 5060
550. variable_sip_via_rport: 5060
551. variable_max_forwards: 70
552. variable_presence_id: 8138902220%40192.168.8.6
553. variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%2049732651%2049732651%20IN%20IP4%20192.168.8.6%0D%0As%3DAsterisk%0D%0Ac%3DIN%20IP4%20192.168.8.6%0D%0At%3D0%200%0D%0Am%3Daudio%2013976%20RTP/AVP%200%208%203%20111%209%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A3%20GSM/8000%0D%0Aa%3Drtpmap%3A111%20G726-32/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dmaxptime%3A150%0D%0A
554. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
555. variable_rtp_use_codec_string: PCMA,PCMU
556. variable_remote_video_media_flow: inactive
557. variable_remote_text_media_flow: inactive
558. variable_remote_audio_media_flow: sendrecv
559. variable_audio_media_flow: sendrecv
560. variable_remote_media_ip: 192.168.8.6
561. variable_remote_media_port: 13976
562. variable_rtp_audio_recv_pt: 0
563. variable_rtp_use_codec_name: PCMU
564. variable_rtp_use_codec_rate: 8000
565. variable_rtp_use_codec_ptime: 20
566. variable_rtp_use_codec_channels: 1
567. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
568. variable_read_codec: PCMU
569. variable_original_read_codec: PCMU
570. variable_read_rate: 8000
571. variable_original_read_rate: 8000
572. variable_write_codec: PCMU
573. variable_write_rate: 8000
574. variable_dtmf_type: rfc2833
575. variable_endpoint_disposition: RECEIVED
576. variable_call_uuid: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
577. Hunt-Direction: inbound
578. Hunt-Logical-Direction: inbound
579. Hunt-Username: 8138902220
580. Hunt-Dialplan: XML
581. Hunt-Caller-ID-Name: 8138902220
582. Hunt-Caller-ID-Number: 8138902220
583. Hunt-Orig-Caller-ID-Name: 8138902220
584. Hunt-Orig-Caller-ID-Number: 8138902220
585. Hunt-Network-Addr: 192.168.8.6
586. Hunt-ANI: 8138902220
587. Hunt-Destination-Number: 9088999989
588. Hunt-Unique-ID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
589. Hunt-Source: mod_sofia
590. Hunt-Context: default
591. Hunt-Channel-Name: sofia/default/8138902220%40192.168.8.6
592. Hunt-Profile-Index: 1
593. Hunt-Profile-Created-Time: 1603797065565800
594. Hunt-Channel-Created-Time: 1603797065565800
595. Hunt-Channel-Answered-Time: 0
596. Hunt-Channel-Progress-Time: 0
597. Hunt-Channel-Progress-Media-Time: 0
598. Hunt-Channel-Hangup-Time: 0
599. Hunt-Channel-Transfer-Time: 0
600. Hunt-Channel-Resurrect-Time: 0
601. Hunt-Channel-Bridged-Time: 0
602. Hunt-Channel-Last-Hold: 0
603. Hunt-Channel-Hold-Accum: 0
604. Hunt-Screen-Bit: true
605. Hunt-Privacy-Hide-Name: false
606. Hunt-Privacy-Hide-Number: false
607.  
608.  
609. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
610. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value:
611. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name:
612. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name:
613. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] blind_flag: 0
614. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 9088999989
615. 2020-10-27 12:11:05.625803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '9088999989' limit 1
616. 2020-10-27 12:11:05.625803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '9088999989' AND status=0 limit 1
617. 2020-10-27 12:11:05.625803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '9088999989' and status=0 LIMIT 1
618. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : 8138902220 / 8138902220
619. 2020-10-27 12:11:05.625803 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
620. 2020-10-27 12:11:05.625803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
621. 2020-10-27 12:11:05.625803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="9088999989" LIMIT 1
622. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : inbound
623. 2020-10-27 12:11:05.625803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("192.168.8.6") 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 "9088999989"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
624. 2020-10-27 12:11:05.625803 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 1135674299]
625. 2020-10-27 12:11:05.625803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "1135674299" AND deleted = 0 limit 1
626. freeswitch@soultech>
627. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE number = "1135674299" AND deleted = 0 limit 1
628. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="9088999989" AND A.accountid = '123' limit 1
629. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 9088999989
630. 2020-10-27 12:11:05.645799 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
631. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
632. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="9088999989" LIMIT 1
633. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : inbound
634. 2020-10-27 12:11:05.645799 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
635. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
636. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="9088999989" AND (accountid = 0 OR status = 1) LIMIT 1
637. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
638. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :138
639. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^9088999989.*' OR patterns = '^908899998.*' OR patterns = '^90889999.*' OR patterns = '^9088999.*' OR patterns = '^908899.*' OR patterns = '^90889.*' OR patterns = '^9088.*' OR patterns = '^908.*' OR patterns = '^90.*' OR patterns = '^9.*' OR patterns ='--') AND accountid = 138 ORDER BY LENGTH(PKGPTR.patterns) DESC
640. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '9088999989' OR number = '908899998' OR number = '90889999' OR number = '9088999' OR number = '908899' OR number = '90889' OR number = '9088' OR number = '908' OR number = '90' OR number = '9' OR number ='--') OR (number = '9088999989*' OR number = '908899998*' OR number = '90889999*' OR number = '9088999*' OR number = '908899*' OR number = '90889*' OR number = '9088*' OR number = '908*' OR number = '90*' OR number = '9*' OR number ='--') OR (number = '8138902220' OR number = '813890222' OR number = '81389022' OR number = '8138902' OR number = '813890' OR number = '81389' OR number = '8138' OR number = '813' OR number = '81' OR number = '8' OR number ='--') OR (number = '8138902220*' OR number = '813890222*' OR number = '81389022*' OR number = '8138902*' OR number = '813890*' OR number = '81389*' OR number = '8138*' OR number = '813*' OR number = '81*' OR number = '8*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
641. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
642. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '9088999989' OR routing_prefix = '908899998' OR routing_prefix = '90889999' OR routing_prefix = '9088999' OR routing_prefix = '908899' OR routing_prefix = '90889' OR routing_prefix = '9088' OR routing_prefix = '908' OR routing_prefix = '90' OR routing_prefix = '9' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
643. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
644. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 123
645. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 1135674299
646. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 6856.66
647. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
648. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 1
649. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 0
650. 2020-10-27 12:11:05.645799 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
651. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
652. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :inbound
653. 2020-10-27 12:11:05.645799 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
654. 2020-10-27 12:11:05.645799 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
655. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="9088999989" LIMIT 1
656. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: inbound
657. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information ===================
658. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 40
659. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
660. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0
661. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.00000
662. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : 9088999989
663. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 141
664. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 123
665. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================
666. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your6856.66 balance Accountid 123 !!!
667. 2020-10-27 12:11:05.665803 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
668. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 100 minutes
669. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] INB_FREE:TRUE
670. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] free_inbound:1
671. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 123 AND status=0 LIMIT 1
672. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "138" AND deleted = 0 limit 1
673. freeswitch@soultech>
674. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE id = "138" AND deleted = 0 limit 1
675. freeswitch@soultech>
676. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Actual CustomerInfo XML:123
677. freeswitch@soultech>
678. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Userinfo XML:123
679. freeswitch@soultech>
680. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Actual CustomerInfo XML : 123
681. freeswitch@soultech>
682. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 100
683. freeswitch@soultech>
684. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 138 limit 1
685. freeswitch@soultech>
686. 2020-10-27 12:11:05.665803 [WARNING] switch_cpp.cpp:1443 [ASTPP] [user_domain] user_domaintest446.9mobile.nativetalk.com.ng
687. freeswitch@soultech>
688. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_10
689. freeswitch@soultech>
690. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] custom_function_name::::::::::1343:::::::::::::::40
691. freeswitch@soultech>
692. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [PBX] call_type : 10
693. freeswitch@soultech>
694. 2020-10-27 12:11:05.665803 [INFO] switch_cpp.cpp:1443 [ASTPP] [PBX] call_type : IVR
695. freeswitch@soultech>
696. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_IVR_INFO] Query :SELECT * from pbx_ivr_specification WHERE id = 12 and status=0 LIMIT 1
697. freeswitch@soultech>
698. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '' LIMIT 1
699. freeswitch@soultech>
700. 2020-10-27 12:11:05.665803 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
701. <document type="freeswitch/xml">
702. <section name="dialplan" description="ASTPP Dialplan">
703. <context name="default">
704. <extension name="9088999989">
705. <condition field="destination_number" expression="9088999989">
706. <action application="set" data="effective_destination_number=9088999989"/>
707. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
708. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
709. <action application="set" data="callstart=2020-10-27 11:11:05"/>
710. <action application="set" data="hangup_after_bridge=true"/>
711. <action application="set" data="continue_on_fail=TRUE"/>
712. <action application="set" data="account_id=123"/>
713. <action application="set" data="parent_id=0"/>
714. <action application="set" data="entity_id=0"/>
715. <action application="set" data="call_processed=internal"/>
716. <action application="set" data="call_direction=inbound"/>
717. <action application="set" data="accountname=HOD1"/>
718. <action application="set" data="origination_rates_did=ID:40|CODE:^9088999989.*|DESTINATION:9088999989|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:138"/>
719. <action application="set" data="origination_rates=0"/>
720. <action application="set" data="original_caller_id_name=9088999989"/>
721. <action application="set" data="original_caller_id_number=9088999989"/>
722. <action application="set" data="effective_caller_id_name=9088999989"/>
723. <action application="set" data="effective_caller_id_number=9088999989"/>
724. <action application="set" data="receiver_accid=138"/>
725. <action application="export" data="presence_data=x|||martins(1135674299)|||^9088999989.* // 9088999989 // 0.00000||||||DID"/>
726. <action application="export" data="call_type=10"/>
727. <action application="set" data="did_calltype=10"/>
728. <action application="set" data="did_extensions=12"/>
729. <action application="set" data="ringback="/>
730. <action application="set" data="module_name=pbx"/>
731.     <action application="answer"/>
732. <action application="ivr" data="iVRtest0"/>
733. </condition>
734. </extension>
735. </context>
736. </section>
737. </document>
738. freeswitch@soultech>
739. Dialplan: sofia/default/8138902220@192.168.8.6 parsing [default->9088999989] continue=false
740. freeswitch@soultech>
741. Dialplan: sofia/default/8138902220@192.168.8.6 Regex (PASS) [9088999989] destination_number(9088999989) =~ /9088999989/ break=on-false
742. freeswitch@soultech>
743. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(effective_destination_number=9088999989)
744. freeswitch@soultech>
745. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(bridge_pre_execute_bleg_app=sched_hangup)
746. freeswitch@soultech>
747. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
748. freeswitch@soultech>
749. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(callstart=2020-10-27 11:11:05)
750. freeswitch@soultech>
751. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(hangup_after_bridge=true)
752. freeswitch@soultech>
753. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(continue_on_fail=TRUE)
754. freeswitch@soultech>
755. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(account_id=123)
756. freeswitch@soultech>
757. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(parent_id=0)
758. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(entity_id=0)
759. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(call_processed=internal)
760. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(call_direction=inbound)
761. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(accountname=HOD1)
762. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(origination_rates_did=ID:40|CODE:^9088999989.*|DESTINATION:9088999989|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:138)
763. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(origination_rates=0)
764. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(original_caller_id_name=9088999989)
765. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(original_caller_id_number=9088999989)
766. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(effective_caller_id_name=9088999989)
767. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(effective_caller_id_number=9088999989)
768. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(receiver_accid=138)
769. Dialplan: sofia/default/8138902220@192.168.8.6 Action export(presence_data=x|||martins(1135674299)|||^9088999989.* // 9088999989 // 0.00000||||||DID)
770. Dialplan: sofia/default/8138902220@192.168.8.6 Action export(call_type=10)
771. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(did_calltype=10)
772. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(did_extensions=12)
773. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(ringback=)
774. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(module_name=pbx)
775. Dialplan: sofia/default/8138902220@192.168.8.6 Action answer()
776. Dialplan: sofia/default/8138902220@192.168.8.6 Action ivr(iVRtest0)
777. 2020-10-27 12:11:05.665803 [DEBUG] switch_core_state_machine.c:287 (sofia/default/8138902220@192.168.8.6) State Change CS_ROUTING -> CS_EXECUTE
778. 2020-10-27 12:11:05.665803 [DEBUG] switch_core_state_machine.c:644 (sofia/default/8138902220@192.168.8.6) State ROUTING going to sleep
779. 2020-10-27 12:11:05.665803 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_EXECUTE (Cur 1 Tot 8878)
780. 2020-10-27 12:11:05.665803 [DEBUG] switch_core_state_machine.c:651 (sofia/default/8138902220@192.168.8.6) State EXECUTE
781. 2020-10-27 12:11:05.665803 [DEBUG] mod_sofia.c:209 sofia/default/8138902220@192.168.8.6 SOFIA EXECUTE
782. 2020-10-27 12:11:05.665803 [DEBUG] switch_core_state_machine.c:329 sofia/default/8138902220@192.168.8.6 Standard EXECUTE
783. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(effective_destination_number=9088999989)
784. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [effective_destination_number]=[9088999989]
785. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(bridge_pre_execute_bleg_app=sched_hangup)
786. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [bridge_pre_execute_bleg_app]=[sched_hangup]
787. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
788. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
789. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(callstart=2020-10-27 11:11:05)
790. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [callstart]=[2020-10-27 11:11:05]
791. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(hangup_after_bridge=true)
792. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [hangup_after_bridge]=[true]
793. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(continue_on_fail=TRUE)
794. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [continue_on_fail]=[TRUE]
795. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(account_id=123)
796. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [account_id]=[123]
797. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(parent_id=0)
798. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [parent_id]=[0]
799. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(entity_id=0)
800. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [entity_id]=[0]
801. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(call_processed=internal)
802. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [call_processed]=[internal]
803. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(call_direction=inbound)
804. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [call_direction]=[inbound]
805. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(accountname=HOD1)
806. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [accountname]=[HOD1]
807. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(origination_rates_did=ID:40|CODE:^9088999989.*|DESTINATION:9088999989|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:138)
808. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [origination_rates_did]=[ID:40|CODE:^9088999989.*|DESTINATION:9088999989|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:138]
809. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(origination_rates=0)
810. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [origination_rates]=[0]
811. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(original_caller_id_name=9088999989)
812. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [original_caller_id_name]=[9088999989]
813. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(original_caller_id_number=9088999989)
814. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [original_caller_id_number]=[9088999989]
815. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(effective_caller_id_name=9088999989)
816. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [effective_caller_id_name]=[9088999989]
817. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(effective_caller_id_number=9088999989)
818. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [effective_caller_id_number]=[9088999989]
819. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(receiver_accid=138)
820. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [receiver_accid]=[138]
821. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 export(presence_data=x|||martins(1135674299)|||^9088999989.* // 9088999989 // 0.00000||||||DID)
822. 2020-10-27 12:11:05.665803 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||martins(1135674299)|||^9088999989.* // 9088999989 // 0.00000||||||DID]
823. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 export(call_type=10)
824. 2020-10-27 12:11:05.665803 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_type]=[10]
825. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(did_calltype=10)
826. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [did_calltype]=[10]
827. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(did_extensions=12)
828. 2020-10-27 12:11:05.665803 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [did_extensions]=[12]
829. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(ringback=)
830. 2020-10-27 12:11:05.685813 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [ringback]=[UNDEF]
831. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(module_name=pbx)
832. 2020-10-27 12:11:05.685813 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [module_name]=[pbx]
833. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 answer()
834. 2020-10-27 12:11:05.685813 [DEBUG] switch_core_media.c:8658 AUDIO RTP [sofia/default/8138902220@192.168.8.6] 192.168.8.3 port 16826 -> 192.168.8.6 port 13976 codec: 0 ms: 20
835. 2020-10-27 12:11:05.685813 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
836. 2020-10-27 12:11:05.685813 [DEBUG] switch_core_media.c:8972 sofia/default/8138902220@192.168.8.6 Set 2833 dtmf send payload to 101
837. 2020-10-27 12:11:05.685813 [DEBUG] switch_core_media.c:8979 sofia/default/8138902220@192.168.8.6 Set 2833 dtmf receive payload to 101
838. 2020-10-27 12:11:05.685813 [DEBUG] switch_core_media.c:9002 sofia/default/8138902220@192.168.8.6 Set rtp dtmf delay to 40
839. 2020-10-27 12:11:05.685813 [DEBUG] mod_sofia.c:905 Local SDP sofia/default/8138902220@192.168.8.6:
840. v=0
841. o=FreeSWITCH 1603780239 1603780240 IN IP4 192.168.8.3
842. s=FreeSWITCH
843. c=IN IP4 192.168.8.3
844. t=0 0
845. m=audio 16826 RTP/AVP 0 101
846. a=rtpmap:0 PCMU/8000
847. a=rtpmap:101 telephone-event/8000
848. a=fmtp:101 0-16
849. a=ptime:20
850. a=sendrecv
851.  
852. 2020-10-27 12:11:05.685813 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/8138902220@192.168.8.6] has been answered
853. 2020-10-27 12:11:05.685813 [DEBUG] switch_channel.c:3865 (sofia/default/8138902220@192.168.8.6) Callstate Change RINGING -> ACTIVE
854. 2020-10-27 12:11:05.685813 [DEBUG] sofia.c:7290 Channel sofia/default/8138902220@192.168.8.6 entering state [completed][200]
855. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 ivr(iVRtest0)
856. freeswitch@soultech>
857. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-exit'
858. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-sub'
859. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
860. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
861. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-back'
862. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-top'
863. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:883 building menu 'iVRtest0'
864. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '1'
865. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '2'
866. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '3'
867. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '/^(\d{2,20})$/'
868. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_menu.c:486 Executing IVR menu iVRtest0
869. 2020-10-27 12:11:05.725796 [DEBUG] sofia.c:7290 Channel sofia/default/8138902220@192.168.8.6 entering state [ready][200]
870. 2020-10-27 12:11:05.725796 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms
871. freeswitch@soultech>
872. freeswitch@soultech>
873. freeswitch@soultech>
874. freeswitch@soultech>
875. freeswitch@soultech>
876. 2020-10-27 12:11:06.065802 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
877. 2020-10-27 12:11:11.625801 [WARNING] switch_core_file.c:410 File has 2 channels, muxing to 1 channel will occur.
878. 2020-10-27 12:11:17.325813 [WARNING] switch_core_file.c:410 File has 2 channels, muxing to 1 channel will occur.
879. 2020-10-27 12:11:23.365800 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/pbx/ivr(2)_211020121726.wav
880. 2020-10-27 12:11:23.365800 [DEBUG] switch_ivr_menu.c:378 waiting for 20/20 digits t/o 2000
881. 2020-10-27 12:11:24.905896 [DEBUG] switch_rtp.c:7963 RTP RECV DTMF 1:1448
882. 2020-10-27 12:11:24.905896 [INFO] switch_channel.c:522 RECV DTMF 1:1448
883. 2020-10-27 12:11:26.925800 [DEBUG] switch_ivr_menu.c:425 digits '1'
884. 2020-10-27 12:11:26.925800 [DEBUG] switch_ivr_menu.c:580 IVR action on menu 'iVRtest0' matched '1' param 'transfer 6350951709 XML ${domain_name}'
885. 2020-10-27 12:11:26.925800 [DEBUG] switch_ivr_menu.c:584 switch_ivr_menu_execute todo=[2]
886. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 transfer(6350951709 XML 192.168.8.3)
887. 2020-10-27 12:11:26.925800 [DEBUG] switch_ivr.c:2231 (sofia/default/8138902220@192.168.8.6) State Change CS_EXECUTE -> CS_ROUTING
888. 2020-10-27 12:11:26.925800 [NOTICE] switch_ivr.c:2238 Transfer sofia/default/8138902220@192.168.8.6 to XML[6350951709@192.168.8.3]
889. 2020-10-27 12:11:26.925800 [DEBUG] switch_ivr_menu.c:562 action regex [1] [/^(\d{2,20})$/] [0]
890. 2020-10-27 12:11:26.925800 [DEBUG] switch_ivr_menu.c:676 exit-sound 'voicemail/vm-goodbye.wav'
891. 2020-10-27 12:11:26.925800 [DEBUG] switch_core_state_machine.c:651 (sofia/default/8138902220@192.168.8.6) State EXECUTE going to sleep
892. 2020-10-27 12:11:26.925800 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_ROUTING (Cur 1 Tot 8878)
893. 2020-10-27 12:11:26.925800 [DEBUG] switch_channel.c:2332 (sofia/default/8138902220@192.168.8.6) Callstate Change ACTIVE -> RINGING
894. 2020-10-27 12:11:26.925800 [DEBUG] switch_core_state_machine.c:644 (sofia/default/8138902220@192.168.8.6) State ROUTING
895. 2020-10-27 12:11:26.925800 [DEBUG] mod_sofia.c:154 sofia/default/8138902220@192.168.8.6 SOFIA ROUTING
896. 2020-10-27 12:11:26.925800 [DEBUG] switch_core_state_machine.c:236 sofia/default/8138902220@192.168.8.6 Standard ROUTING
897. 2020-10-27 12:11:26.925800 [INFO] mod_dialplan_xml.c:637 Processing 8138902220 <8138902220>->6350951709 in context 192.168.8.3
898. 2020-10-27 12:11:26.925800 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
899. 2020-10-27 12:11:26.925800 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
900. 2020-10-27 12:11:26.965835 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
901. Event-Name: REQUEST_PARAMS
902. Core-UUID: f0e00936-3fce-4a2b-af76-c25556ae0e1a
903. FreeSWITCH-Hostname: soultech
904. FreeSWITCH-Switchname: soultech
905. FreeSWITCH-IPv4: 192.168.8.3
906. FreeSWITCH-IPv6: %3A%3A1
907. Event-Date-Local: 2020-10-27%2012%3A11%3A26
908. Event-Date-GMT: Tue,%2027%20Oct%202020%2011%3A11%3A26%20GMT
909. Event-Date-Timestamp: 1603797086925800
910. Event-Calling-File: mod_dialplan_xml.c
911. Event-Calling-Function: dialplan_xml_locate
912. Event-Calling-Line-Number: 608
913. Event-Sequence: 454581
914. Channel-State: CS_ROUTING
915. Channel-Call-State: RINGING
916. Channel-State-Number: 2
917. Channel-Name: sofia/default/8138902220%40192.168.8.6
918. Unique-ID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
919. Call-Direction: inbound
920. Presence-Call-Direction: inbound
921. Channel-HIT-Dialplan: true
922. Channel-Presence-ID: 8138902220%40192.168.8.6
923. Channel-Presence-Data: x%7C%7C%7Cmartins(1135674299)%7C%7C%7C%5E9088999989.*%20//%209088999989%20//%200.00000%7C%7C%7C%7C%7C%7CDID
924. Channel-Call-UUID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
925. Answer-State: answered
926. Channel-Read-Codec-Name: PCMU
927. Channel-Read-Codec-Rate: 8000
928. Channel-Read-Codec-Bit-Rate: 64000
929. Channel-Write-Codec-Name: PCMU
930. Channel-Write-Codec-Rate: 8000
931. Channel-Write-Codec-Bit-Rate: 64000
932. Caller-Direction: inbound
933. Caller-Logical-Direction: inbound
934. Caller-Username: 8138902220
935. Caller-Dialplan: XML
936. Caller-Caller-ID-Name: 8138902220
937. Caller-Caller-ID-Number: 8138902220
938. Caller-Orig-Caller-ID-Name: 8138902220
939. Caller-Orig-Caller-ID-Number: 8138902220
940. Caller-Network-Addr: 192.168.8.6
941. Caller-ANI: 8138902220
942. Caller-Destination-Number: 6350951709
943. Caller-Unique-ID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
944. Caller-Source: mod_sofia
945. Caller-Transfer-Source: 1603797086%3Ae66f24eb-b321-4e34-bbc5-e1c2a7201d2e%3Abl_xfer%3A6350951709/192.168.8.3/XML
946. Caller-Context: 192.168.8.3
947. Caller-RDNIS: 9088999989
948. Caller-Channel-Name: sofia/default/8138902220%40192.168.8.6
949. Caller-Profile-Index: 2
950. Caller-Profile-Created-Time: 1603797086925800
951. Caller-Channel-Created-Time: 1603797065565800
952. Caller-Channel-Answered-Time: 1603797065685813
953. Caller-Channel-Progress-Time: 0
954. Caller-Channel-Progress-Media-Time: 0
955. Caller-Channel-Hangup-Time: 0
956. Caller-Channel-Transfer-Time: 0
957. Caller-Channel-Resurrect-Time: 0
958. Caller-Channel-Bridged-Time: 0
959. Caller-Channel-Last-Hold: 0
960. Caller-Channel-Hold-Accum: 0
961. Caller-Screen-Bit: true
962. Caller-Privacy-Hide-Name: false
963. Caller-Privacy-Hide-Number: false
964. variable_direction: inbound
965. variable_uuid: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
966. variable_session_id: 8878
967. variable_sip_from_user: 8138902220
968. variable_sip_from_uri: 8138902220%40192.168.8.6
969. variable_sip_from_host: 192.168.8.6
970. variable_video_media_flow: disabled
971. variable_text_media_flow: disabled
972. variable_channel_name: sofia/default/8138902220%40192.168.8.6
973. variable_sip_local_network_addr: 192.168.8.3
974. variable_sip_network_ip: 192.168.8.6
975. variable_sip_network_port: 5060
976. variable_sip_invite_stamp: 1603797065565800
977. variable_sip_received_ip: 192.168.8.6
978. variable_sip_received_port: 5060
979. variable_sip_via_protocol: udp
980. variable_sip_authorized: true
981. variable_sip_acl_authed_by: default
982. variable_sip_from_user_stripped: 8138902220
983. variable_sofia_profile_name: default
984. variable_sofia_profile_url: sip%3Amod_sofia%40192.168.8.3%3A5060
985. variable_recovery_profile_name: default
986. variable_sip_allow: OPTIONS,%20SUBSCRIBE,%20NOTIFY,%20PUBLISH,%20INVITE,%20ACK,%20BYE,%20CANCEL,%20UPDATE,%20PRACK,%20REGISTER,%20MESSAGE,%20REFER
987. variable_sip_req_user: 9088999989
988. variable_sip_req_uri: 9088999989%40192.168.8.3
989. variable_sip_req_host: 192.168.8.3
990. variable_sip_to_user: 9088999989
991. variable_sip_to_uri: 9088999989%40192.168.8.3
992. variable_sip_to_host: 192.168.8.3
993. variable_sip_contact_user: asterisk
994. variable_sip_contact_port: 5060
995. variable_sip_contact_uri: asterisk%40192.168.8.6%3A5060
996. variable_sip_contact_host: 192.168.8.6
997. variable_sip_user_agent: FPBX-14.0.13.40(13.29.2)
998. variable_sip_via_host: 192.168.8.6
999. variable_sip_via_port: 5060
1000. variable_sip_via_rport: 5060
1001. variable_presence_id: 8138902220%40192.168.8.6
1002. variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%2049732651%2049732651%20IN%20IP4%20192.168.8.6%0D%0As%3DAsterisk%0D%0Ac%3DIN%20IP4%20192.168.8.6%0D%0At%3D0%200%0D%0Am%3Daudio%2013976%20RTP/AVP%200%208%203%20111%209%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A3%20GSM/8000%0D%0Aa%3Drtpmap%3A111%20G726-32/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dmaxptime%3A150%0D%0A
1003. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
1004. variable_rtp_use_codec_string: PCMA,PCMU
1005. variable_remote_video_media_flow: inactive
1006. variable_remote_text_media_flow: inactive
1007. variable_remote_audio_media_flow: sendrecv
1008. variable_audio_media_flow: sendrecv
1009. variable_rtp_audio_recv_pt: 0
1010. variable_rtp_use_codec_name: PCMU
1011. variable_rtp_use_codec_rate: 8000
1012. variable_rtp_use_codec_ptime: 20
1013. variable_rtp_use_codec_channels: 1
1014. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
1015. variable_read_codec: PCMU
1016. variable_original_read_codec: PCMU
1017. variable_read_rate: 8000
1018. variable_original_read_rate: 8000
1019. variable_write_codec: PCMU
1020. variable_write_rate: 8000
1021. variable_dtmf_type: rfc2833
1022. variable_effective_destination_number: 9088999989
1023. variable_bridge_pre_execute_bleg_app: sched_hangup
1024. variable_bridge_pre_execute_bleg_data: %2B6000%20normal_clearing
1025. variable_callstart: 2020-10-27%2011%3A11%3A05
1026. variable_hangup_after_bridge: true
1027. variable_continue_on_fail: TRUE
1028. variable_account_id: 123
1029. variable_parent_id: 0
1030. variable_entity_id: 0
1031. variable_call_processed: internal
1032. variable_call_direction: inbound
1033. variable_accountname: HOD1
1034. variable_origination_rates_did: ID%3A40%7CCODE%3A%5E9088999989.*%7CDESTINATION%3A9088999989%7CCONNECTIONCOST%3A0.00000%7CINCLUDEDSECONDS%3A0%7CCT%3A10%7CCOST%3A0.00000%7CINC%3A0%7CINITIALBLOCK%3A0%7CRATEGROUP%3A0%7CMARKUP%3A0%7CCI%3A141%7CACCID%3A138
1035. variable_origination_rates: 0
1036. variable_original_caller_id_name: 9088999989
1037. variable_original_caller_id_number: 9088999989
1038. variable_effective_caller_id_name: 9088999989
1039. variable_effective_caller_id_number: 9088999989
1040. variable_receiver_accid: 138
1041. variable_presence_data: x%7C%7C%7Cmartins(1135674299)%7C%7C%7C%5E9088999989.*%20//%209088999989%20//%200.00000%7C%7C%7C%7C%7C%7CDID
1042. variable_call_type: 10
1043. variable_export_vars: presence_data,call_type
1044. variable_did_calltype: 10
1045. variable_did_extensions: 12
1046. variable_module_name: pbx
1047. variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201603780239%201603780240%20IN%20IP4%20192.168.8.3%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%20192.168.8.3%0D%0At%3D0%200%0D%0Am%3Daudio%2016826%20RTP/AVP%200%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
1048. variable_local_media_ip: 192.168.8.3
1049. variable_local_media_port: 16826
1050. variable_advertised_media_ip: 192.168.8.3
1051. variable_rtp_use_timer_name: soft
1052. variable_rtp_use_pt: 0
1053. variable_rtp_use_ssrc: 64039153
1054. variable_rtp_2833_send_payload: 101
1055. variable_rtp_2833_recv_payload: 101
1056. variable_remote_media_ip: 192.168.8.6
1057. variable_remote_media_port: 13976
1058. variable_endpoint_disposition: ANSWER
1059. variable_ivr_menu_status: success
1060. variable_sip_to_tag: 3ep0jv9jFaFaS
1061. variable_sip_from_tag: 0c4f9baa-7528-4f59-8898-957434b5e5ab
1062. variable_sip_cseq: 24960
1063. variable_sip_call_id: cb76c3b5-2f4e-46d5-b10d-777c42fb6183
1064. variable_sip_full_via: SIP/2.0/UDP%20192.168.8.6%3A5060%3Brport%3D5060%3Bbranch%3Dz9hG4bKPjf39f7ed4-cb6b-4c0a-b7ec-a38ebad02edf
1065. variable_sip_from_display: 8138902220
1066. variable_sip_full_from: %228138902220%22%20%3Csip%3A8138902220%40192.168.8.6%3E%3Btag%3D0c4f9baa-7528-4f59-8898-957434b5e5ab
1067. variable_sip_full_to: %3Csip%3A9088999989%40192.168.8.3%3E%3Btag%3D3ep0jv9jFaFaS
1068. variable_RECORD_SOFTWARE: Lavf58.46.101
1069. variable_playback_last_offset_pos: 141271
1070. variable_playback_seconds: 17
1071. variable_playback_ms: 17658
1072. variable_playback_samples: 141271
1073. variable_current_application_data: 6350951709%20XML%20192.168.8.3
1074. variable_current_application: transfer
1075. variable_max_forwards: 69
1076. variable_transfer_history: 1603797086%3Ae66f24eb-b321-4e34-bbc5-e1c2a7201d2e%3Abl_xfer%3A6350951709/192.168.8.3/XML
1077. variable_transfer_source: 1603797086%3Ae66f24eb-b321-4e34-bbc5-e1c2a7201d2e%3Abl_xfer%3A6350951709/192.168.8.3/XML
1078. variable_call_uuid: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
1079. Hunt-Direction: inbound
1080. Hunt-Logical-Direction: inbound
1081. Hunt-Username: 8138902220
1082. Hunt-Dialplan: XML
1083. Hunt-Caller-ID-Name: 8138902220
1084. Hunt-Caller-ID-Number: 8138902220
1085. Hunt-Orig-Caller-ID-Name: 8138902220
1086. Hunt-Orig-Caller-ID-Number: 8138902220
1087. Hunt-Network-Addr: 192.168.8.6
1088. Hunt-ANI: 8138902220
1089. Hunt-Destination-Number: 6350951709
1090. Hunt-Unique-ID: d96a1153-e817-46ae-a3bd-61b2d6bbcd0d
1091. Hunt-Source: mod_sofia
1092. Hunt-Transfer-Source: 1603797086%3Ae66f24eb-b321-4e34-bbc5-e1c2a7201d2e%3Abl_xfer%3A6350951709/192.168.8.3/XML
1093. Hunt-Context: 192.168.8.3
1094. Hunt-RDNIS: 9088999989
1095. Hunt-Channel-Name: sofia/default/8138902220%40192.168.8.6
1096. Hunt-Profile-Index: 2
1097. Hunt-Profile-Created-Time: 1603797086925800
1098. Hunt-Channel-Created-Time: 1603797065565800
1099. Hunt-Channel-Answered-Time: 1603797065685813
1100. Hunt-Channel-Progress-Time: 0
1101. Hunt-Channel-Progress-Media-Time: 0
1102. Hunt-Channel-Hangup-Time: 0
1103. Hunt-Channel-Transfer-Time: 0
1104. Hunt-Channel-Resurrect-Time: 0
1105. Hunt-Channel-Bridged-Time: 0
1106. Hunt-Channel-Last-Hold: 0
1107. Hunt-Channel-Hold-Accum: 0
1108. Hunt-Screen-Bit: true
1109. Hunt-Privacy-Hide-Name: false
1110. Hunt-Privacy-Hide-Number: false
1111.  
1112.  
1113. 2020-10-27 12:11:26.965835 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value:
1114. 2020-10-27 12:11:26.965835 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name:
1115. 2020-10-27 12:11:26.965835 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name:
1116. 2020-10-27 12:11:26.965835 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
1117. 2020-10-27 12:11:26.985806 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] blind_flag: 0
1118. 2020-10-27 12:11:26.985806 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 6350951709
1119. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '6350951709' limit 1
1120. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '6350951709' AND status=0 limit 1
1121. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '6350951709' and status=0 LIMIT 1
1122. 2020-10-27 12:11:26.985806 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : 9088999989 / 9088999989
1123. 2020-10-27 12:11:26.985806 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
1124. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
1125. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="6350951709" LIMIT 1
1126. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [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 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="6350951709"  AND domains.domain="192.168.8.3" limit 1
1127. 2020-10-27 12:11:26.985806 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : outbound
1128. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("192.168.8.6") 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 "6350951709"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
1129. 2020-10-27 12:11:26.985806 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 1135674299]
1130. 2020-10-27 12:11:26.985806 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "1135674299" AND deleted = 0 limit 1
1131. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE number = "1135674299" AND deleted = 0 limit 1
1132. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^6350951709.*' OR blocked_patterns = '^635095170.*' OR blocked_patterns = '^63509517.*' OR blocked_patterns = '^6350951.*' OR blocked_patterns = '^635095.*' OR blocked_patterns = '^63509.*' OR blocked_patterns = '^6350.*' OR blocked_patterns = '^635.*' OR blocked_patterns = '^63.*' OR blocked_patterns = '^6.*' OR blocked_patterns ='--') AND accountid = 123 limit 1
1133. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="6350951709" AND A.accountid = '123' limit 1
1134. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 6350951709
1135. 2020-10-27 12:11:27.005813 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
1136. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
1137. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="6350951709" LIMIT 1
1138. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [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 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="6350951709"  AND domains.domain="192.168.8.3" limit 1
1139. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : outbound
1140. 2020-10-27 12:11:27.005813 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
1141. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
1142. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="6350951709" AND (accountid = 0 OR status = 1) LIMIT 1
1143. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^6350951709.*' OR patterns = '^635095170.*' OR patterns = '^63509517.*' OR patterns = '^6350951.*' OR patterns = '^635095.*' OR patterns = '^63509.*' OR patterns = '^6350.*' OR patterns = '^635.*' OR patterns = '^63.*' OR patterns = '^6.*' OR patterns ='--') AND accountid = 123 ORDER BY LENGTH(PKGPTR.patterns) DESC
1144. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '6350951709' OR number = '635095170' OR number = '63509517' OR number = '6350951' OR number = '635095' OR number = '63509' OR number = '6350' OR number = '635' OR number = '63' OR number = '6' OR number ='--') OR (number = '6350951709*' OR number = '635095170*' OR number = '63509517*' OR number = '6350951*' OR number = '635095*' OR number = '63509*' OR number = '6350*' OR number = '635*' OR number = '63*' OR number = '6*' OR number ='--') OR (number = '9088999989' OR number = '908899998' OR number = '90889999' OR number = '9088999' OR number = '908899' OR number = '90889' OR number = '9088' OR number = '908' OR number = '90' OR number = '9' OR number ='--') OR (number = '9088999989*' OR number = '908899998*' OR number = '90889999*' OR number = '9088999*' OR number = '908899*' OR number = '90889*' OR number = '9088*' OR number = '908*' OR number = '90*' OR number = '9*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
1145. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
1146. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '6350951709' OR routing_prefix = '635095170' OR routing_prefix = '63509517' OR routing_prefix = '6350951' OR routing_prefix = '635095' OR routing_prefix = '63509' OR routing_prefix = '6350' OR routing_prefix = '635' OR routing_prefix = '63' OR routing_prefix = '6' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
1147. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
1148. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 123
1149. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 1135674299
1150. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 6856.66
1151. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
1152. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 1
1153. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 0
1154. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
1155. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
1156. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :outbound
1157. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^6350951709.*' OR pattern = '^635095170.*' OR pattern = '^63509517.*' OR pattern = '^6350951.*' OR pattern = '^635095.*' OR pattern = '^63509.*' OR pattern = '^6350.*' OR pattern = '^635.*' OR pattern = '^63.*' OR pattern = '^6.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 1 OR accountid=123)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
1158. 2020-10-27 12:11:27.005813 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: outbound
1159. 2020-10-27 12:11:27.005813 [WARNING] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Rates not found!!!
1160. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] post_cdrs:::0
1161. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 123
1162. 2020-10-27 12:11:27.005813 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Generated XML:
1163. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
1164. <document type="freeswitch/xml">
1165. <section name="dialplan" description="ASTPP Dialplan">
1166. <context name="192.168.8.3">
1167. <extension name="6350951709">
1168. <condition field="destination_number" expression="6350951709">
1169. <action application="log" data="WARNING Accountcode . Dialed number (6350951709)  origination rates not found!!"/>
1170. <action application="set" data="original_caller_id_name=9088999989"/>
1171. <action application="set" data="original_caller_id_number=9088999989"/>
1172. <action application="set" data="error_cdr=1"/>
1173. <action application="set" data="callstart=2020-10-27 11:11:27"/>
1174. <action application="set" data="account_id=123"/>
1175. <action application="set" data="parent_id=0"/>
1176. <action application="set" data="call_direction=outbound"/>
1177. <action application="set" data="sip_ignore_remote_cause=true"/>
1178. <action application="set" data="call_processed=internal"/>
1179. <action application="set" data="effective_destination_number=6350951709"/>
1180. <action application="set" data="last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND"/>
1181. <action application="hangup" data="ORIGNATION_RATE_NOT_FOUND"/>
1182. </condition>
1183. </extension>
1184. </context>
1185. </section>
1186. </document>
1187. Dialplan: sofia/default/8138902220@192.168.8.6 parsing [192.168.8.3->6350951709] continue=false
1188. Dialplan: sofia/default/8138902220@192.168.8.6 Regex (PASS) [6350951709] destination_number(6350951709) =~ /6350951709/ break=on-false
1189. Dialplan: sofia/default/8138902220@192.168.8.6 Action log(WARNING Accountcode . Dialed number (6350951709)  origination rates not found!!)
1190. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(original_caller_id_name=9088999989)
1191. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(original_caller_id_number=9088999989)
1192. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(error_cdr=1)
1193. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(callstart=2020-10-27 11:11:27)
1194. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(account_id=123)
1195. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(parent_id=0)
1196. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(call_direction=outbound)
1197. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(sip_ignore_remote_cause=true)
1198. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(call_processed=internal)
1199. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(effective_destination_number=6350951709)
1200. Dialplan: sofia/default/8138902220@192.168.8.6 Action set(last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND)
1201. Dialplan: sofia/default/8138902220@192.168.8.6 Action hangup(ORIGNATION_RATE_NOT_FOUND)
1202. 2020-10-27 12:11:27.005813 [DEBUG] switch_core_state_machine.c:287 (sofia/default/8138902220@192.168.8.6) State Change CS_ROUTING -> CS_EXECUTE
1203. 2020-10-27 12:11:27.005813 [DEBUG] switch_core_state_machine.c:644 (sofia/default/8138902220@192.168.8.6) State ROUTING going to sleep
1204. 2020-10-27 12:11:27.005813 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_EXECUTE (Cur 1 Tot 8878)
1205. 2020-10-27 12:11:27.005813 [DEBUG] switch_channel.c:2334 (sofia/default/8138902220@192.168.8.6) Callstate Change RINGING -> ACTIVE
1206. 2020-10-27 12:11:27.005813 [DEBUG] switch_core_state_machine.c:651 (sofia/default/8138902220@192.168.8.6) State EXECUTE
1207. 2020-10-27 12:11:27.005813 [DEBUG] mod_sofia.c:209 sofia/default/8138902220@192.168.8.6 SOFIA EXECUTE
1208. 2020-10-27 12:11:27.005813 [DEBUG] switch_core_state_machine.c:329 sofia/default/8138902220@192.168.8.6 Standard EXECUTE
1209. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 log(WARNING Accountcode . Dialed number (6350951709)  origination rates not found!!)
1210. 2020-10-27 12:11:27.005813 [WARNING] mod_dptools.c:1866 Accountcode . Dialed number (6350951709)  origination rates not found!!
1211. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(original_caller_id_name=9088999989)
1212. 2020-10-27 12:11:27.005813 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [original_caller_id_name]=[9088999989]
1213. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(original_caller_id_number=9088999989)
1214. 2020-10-27 12:11:27.005813 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [original_caller_id_number]=[9088999989]
1215. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(error_cdr=1)
1216. 2020-10-27 12:11:27.005813 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [error_cdr]=[1]
1217. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(callstart=2020-10-27 11:11:27)
1218. 2020-10-27 12:11:27.005813 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [callstart]=[2020-10-27 11:11:27]
1219. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(account_id=123)
1220. 2020-10-27 12:11:27.025811 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [account_id]=[123]
1221. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(parent_id=0)
1222. 2020-10-27 12:11:27.025811 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [parent_id]=[0]
1223. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(call_direction=outbound)
1224. 2020-10-27 12:11:27.025811 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [call_direction]=[outbound]
1225. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(sip_ignore_remote_cause=true)
1226. 2020-10-27 12:11:27.025811 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [sip_ignore_remote_cause]=[true]
1227. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(call_processed=internal)
1228. 2020-10-27 12:11:27.025811 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [call_processed]=[internal]
1229. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(effective_destination_number=6350951709)
1230. 2020-10-27 12:11:27.025811 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [effective_destination_number]=[6350951709]
1231. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 set(last_bridge_hangup_cause=ORIGNATION_RATE_NOT_FOUND)
1232. 2020-10-27 12:11:27.025811 [DEBUG] mod_dptools.c:1672 SET sofia/default/8138902220@192.168.8.6 [last_bridge_hangup_cause]=[ORIGNATION_RATE_NOT_FOUND]
1233. EXECUTE [depth=0] sofia/default/8138902220@192.168.8.6 hangup(ORIGNATION_RATE_NOT_FOUND)
1234. 2020-10-27 12:11:27.025811 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/8138902220@192.168.8.6 [CS_EXECUTE] [NORMAL_CLEARING]
1235. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_session.c:2905 sofia/default/8138902220@192.168.8.6 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1236. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:651 (sofia/default/8138902220@192.168.8.6) State EXECUTE going to sleep
1237. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_HANGUP (Cur 1 Tot 8878)
1238. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:848 (sofia/default/8138902220@192.168.8.6) Callstate Change ACTIVE -> HANGUP
1239. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:850 (sofia/default/8138902220@192.168.8.6) State HANGUP
1240. 2020-10-27 12:11:27.025811 [DEBUG] mod_sofia.c:460 Channel sofia/default/8138902220@192.168.8.6 hanging up, cause: NORMAL_CLEARING
1241. 2020-10-27 12:11:27.025811 [DEBUG] mod_sofia.c:514 Sending BYE to sofia/default/8138902220@192.168.8.6
1242. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:60 sofia/default/8138902220@192.168.8.6 Standard HANGUP, cause: NORMAL_CLEARING
1243. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:850 (sofia/default/8138902220@192.168.8.6) State HANGUP going to sleep
1244. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:620 (sofia/default/8138902220@192.168.8.6) State Change CS_HANGUP -> CS_REPORTING
1245. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8138902220@192.168.8.6) Running State Change CS_REPORTING (Cur 1 Tot 8878)
1246. 2020-10-27 12:11:27.025811 [DEBUG] switch_core_state_machine.c:936 (sofia/default/8138902220@192.168.8.6) State REPORTING
1247. 2020-10-27 12:11:27.025811 [INFO] mod_json_cdr.c:271 Process [d96a1153-e817-46ae-a3bd-61b2d6bbcd0d.cdr.json]
1248. 2020-10-27 12:11:27.065827 [WARNING] switch_core_file.c:410 File has 2 channels, muxing to 1 channel will occur.
1249. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_state_machine.c:174 sofia/default/8138902220@192.168.8.6 Standard REPORTING, cause: NORMAL_CLEARING
1250. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_state_machine.c:936 (sofia/default/8138902220@192.168.8.6) State REPORTING going to sleep
1251. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_state_machine.c:611 (sofia/default/8138902220@192.168.8.6) State Change CS_REPORTING -> CS_DESTROY
1252. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_session.c:1726 Session 8878 (sofia/default/8138902220@192.168.8.6) Locked, Waiting on external entities
1253. 2020-10-27 12:11:27.085813 [NOTICE] switch_core_session.c:1744 Session 8878 (sofia/default/8138902220@192.168.8.6) Ended
1254. 2020-10-27 12:11:27.085813 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/8138902220@192.168.8.6 [CS_DESTROY]
1255. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_state_machine.c:739 (sofia/default/8138902220@192.168.8.6) Running State Change CS_DESTROY (Cur 0 Tot 8878)
1256. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_state_machine.c:749 (sofia/default/8138902220@192.168.8.6) State DESTROY
1257. 2020-10-27 12:11:27.085813 [DEBUG] mod_sofia.c:365 sofia/default/8138902220@192.168.8.6 SOFIA DESTROY
1258. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_state_machine.c:181 sofia/default/8138902220@192.168.8.6 Standard DESTROY
1259. 2020-10-27 12:11:27.085813 [DEBUG] switch_core_state_machine.c:749 (sofia/default/8138902220@192.168.8.6) State DESTROY going to sleep
1260.