1. span style="color: green; font-weight: bold;"> 2022-12-16 11:39:33.207380 [INFO] mod_dialplan_xml.c:637 Processing 1000 <1000>->319000980 in context default
2. 2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
3. 2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
4. 2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
5. 2022-12-16 11:39:33.207380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
6. 2022-12-16 11:39:33.227380 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
7. Event-Name: REQUEST_PARAMS
8. Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
9. FreeSWITCH-Hostname: multitenant-1
10. FreeSWITCH-Switchname: multitenant-1
11. FreeSWITCH-IPv4: 185.249.40.236
12. FreeSWITCH-IPv6: %3A%3A1
13. Event-Date-Local: 2022-12-16%2011%3A39%3A33
14. Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A33%20GMT
15. Event-Date-Timestamp: 1671187173207380
16. Event-Calling-File: mod_dialplan_xml.c
17. Event-Calling-Function: dialplan_xml_locate
18. Event-Calling-Line-Number: 608
19. Event-Sequence: 639842
20. Channel-State: CS_ROUTING
21. Channel-Call-State: RINGING
22. Channel-State-Number: 2
23. Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
24. Unique-ID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
25. Call-Direction: inbound
26. Presence-Call-Direction: inbound
27. Channel-HIT-Dialplan: true
28. Channel-Presence-ID: 183284%40213.144.239.110
29. Channel-Call-UUID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
30. Answer-State: ringing
31. Channel-Read-Codec-Name: PCMU
32. Channel-Read-Codec-Rate: 8000
33. Channel-Read-Codec-Bit-Rate: 64000
34. Channel-Write-Codec-Name: PCMU
35. Channel-Write-Codec-Rate: 8000
36. Channel-Write-Codec-Bit-Rate: 64000
37. Caller-Direction: inbound
38. Caller-Logical-Direction: inbound
39. Caller-Username: 31232051002
40. Caller-Dialplan: XML
41. Caller-Caller-ID-Name: Anonymous
42. Caller-Caller-ID-Number: 31232051002
43. Caller-Orig-Caller-ID-Name: Anonymous
44. Caller-Orig-Caller-ID-Number: 31232051002
45. Caller-Network-Addr: 213.144.239.110
46. Caller-ANI: 31232051002
47. Caller-Destination-Number: 352621453076
48. Caller-Unique-ID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
49. Caller-Source: mod_sofia
50. Caller-Context: default
51. Caller-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
52. Caller-Profile-Index: 1
53. Caller-Profile-Created-Time: 1671187173207380
54. Caller-Channel-Created-Time: 1671187173207380
55. Caller-Channel-Answered-Time: 0
56. Caller-Channel-Progress-Time: 0
57. Caller-Channel-Progress-Media-Time: 0
58. Caller-Channel-Hangup-Time: 0
59. Caller-Channel-Transfer-Time: 0
60. Caller-Channel-Resurrect-Time: 0
61. Caller-Channel-Bridged-Time: 0
62. Caller-Channel-Last-Hold: 0
63. Caller-Channel-Hold-Accum: 0
64. Caller-Screen-Bit: false
65. Caller-Privacy-Hide-Name: true
66. Caller-Privacy-Hide-Number: true
67. variable_direction: inbound
68. variable_uuid: 501160ca-0747-4211-b6aa-8caed8d6b7cc
69. variable_session_id: 7226
70. variable_sip_from_user: 183284
71. variable_sip_from_port: 9933
72. variable_sip_from_uri: 183284%40213.144.239.110%3A9933
73. variable_sip_from_host: 213.144.239.110
74. variable_video_media_flow: disabled
75. variable_text_media_flow: disabled
76. variable_channel_name: sofia/default/183284%40213.144.239.110%3A9933
77. variable_sip_call_id: 6428d50963019eb768f7ae97398eaf86%40213.144.239.110%3A9933
78. variable_sip_local_network_addr: 185.249.40.236
79. variable_sip_network_ip: 213.144.239.110
80. variable_sip_network_port: 9933
81. variable_sip_invite_stamp: 1671187173207380
82. variable_sip_received_ip: 213.144.239.110
83. variable_sip_received_port: 9933
84. variable_sip_via_protocol: udp
85. variable_sip_authorized: true
86. variable_sip_acl_authed_by: default
87. variable_sip_from_user_stripped: 183284
88. variable_sip_from_tag: as484c2d4d
89. variable_sofia_profile_name: default
90. variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
91. variable_recovery_profile_name: default
92. variable_sip_Remote-Party-ID: %22Anonymous%22%20%3Csip%3A31232051002%40213.144.239.110%3E%3Bparty%3Dcalling%3Bprivacy%3Doff%3Bscreen%3Dno
93. variable_sip_cid_type: rpid
94. variable_sip_full_via: SIP/2.0/UDP%20213.144.239.110%3A9933%3Bbranch%3Dz9hG4bK39b1428a%3Brport%3D9933
95. variable_sip_from_display: Anonymous
96. variable_sip_full_from: %22Anonymous%22%20%3Csip%3A183284%40213.144.239.110%3A9933%3E%3Btag%3Das484c2d4d
97. variable_sip_full_to: %3Csip%3A352621453076%40emolife138.yoursipcloud.com%3E
98. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
99. variable_sip_req_user: 352621453076
100. variable_sip_req_uri: 352621453076%40emolife138.yoursipcloud.com
101. variable_sip_req_host: emolife138.yoursipcloud.com
102. variable_sip_to_user: 352621453076
103. variable_sip_to_uri: 352621453076%40emolife138.yoursipcloud.com
104. variable_sip_to_host: emolife138.yoursipcloud.com
105. variable_sip_contact_user: 183284
106. variable_sip_contact_port: 9933
107. variable_sip_contact_uri: 183284%40213.144.239.110%3A9933
108. variable_sip_contact_host: 213.144.239.110
109. variable_sip_user_agent: FPBX-2.11.0(11.4.0)
110. variable_sip_via_host: 213.144.239.110
111. variable_sip_via_port: 9933
112. variable_sip_via_rport: 9933
113. variable_max_forwards: 70
114. variable_presence_id: 183284%40213.144.239.110
115. variable_sip_Privacy: id
116. variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201703048574%201703048574%20IN%20IP4%20213.144.239.110%0D%0As%3DAsterisk%20PBX%2011.4.0%0D%0Ac%3DIN%20IP4%20213.144.239.110%0D%0At%3D0%200%0D%0Am%3Daudio%2017212%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
117. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
118. variable_rtp_use_codec_string: PCMA,PCMU
119. variable_remote_video_media_flow: inactive
120. variable_remote_text_media_flow: inactive
121. variable_remote_audio_media_flow: sendrecv
122. variable_audio_media_flow: sendrecv
123. variable_remote_media_ip: 213.144.239.110
124. variable_remote_media_port: 17212
125. variable_rtp_audio_recv_pt: 0
126. variable_rtp_use_codec_name: PCMU
127. variable_rtp_use_codec_rate: 8000
128. variable_rtp_use_codec_ptime: 20
129. variable_rtp_use_codec_channels: 1
130. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
131. variable_read_codec: PCMU
132. variable_original_read_codec: PCMU
133. variable_read_rate: 8000
134. variable_original_read_rate: 8000
135. variable_write_codec: PCMU
136. variable_write_rate: 8000
137. variable_dtmf_type: rfc2833
138. variable_endpoint_disposition: RECEIVED
139. variable_call_uuid: 501160ca-0747-4211-b6aa-8caed8d6b7cc
140. Hunt-Direction: inbound
141. Hunt-Logical-Direction: inbound
142. Hunt-Username: 31232051002
143. Hunt-Dialplan: XML
144. Hunt-Caller-ID-Name: Anonymous
145. Hunt-Caller-ID-Number: 31232051002
146. Hunt-Orig-Caller-ID-Name: Anonymous
147. Hunt-Orig-Caller-ID-Number: 31232051002
148. Hunt-Network-Addr: 213.144.239.110
149. Hunt-ANI: 31232051002
150. Hunt-Destination-Number: 352621453076
151. Hunt-Unique-ID: 501160ca-0747-4211-b6aa-8caed8d6b7cc
152. Hunt-Source: mod_sofia
153. Hunt-Context: default
154. Hunt-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
155. Hunt-Profile-Index: 1
156. Hunt-Profile-Created-Time: 1671187173207380
157. Hunt-Channel-Created-Time: 1671187173207380
158. Hunt-Channel-Answered-Time: 0
159. Hunt-Channel-Progress-Time: 0
160. Hunt-Channel-Progress-Media-Time: 0
161. Hunt-Channel-Hangup-Time: 0
162. Hunt-Channel-Transfer-Time: 0
163. Hunt-Channel-Resurrect-Time: 0
164. Hunt-Channel-Bridged-Time: 0
165. Hunt-Channel-Last-Hold: 0
166. Hunt-Channel-Hold-Accum: 0
167. Hunt-Screen-Bit: false
168. Hunt-Privacy-Hide-Name: true
169. Hunt-Privacy-Hide-Number: true
170.  
171.  
172. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value:
173. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
174. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name:
175. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name:
176. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
177. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 352621453076
178. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '352621453076' limit 1
179. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '352621453076' AND status=0 limit 1
180. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '352621453076' and status=0 LIMIT 1
181. 2022-12-16 11:39:33.227380 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
182. Event-Name: REQUEST_PARAMS
183. Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
184. FreeSWITCH-Hostname: multitenant-1
185. FreeSWITCH-Switchname: multitenant-1
186. FreeSWITCH-IPv4: 185.249.40.236
187. FreeSWITCH-IPv6: %3A%3A1
188. Event-Date-Local: 2022-12-16%2011%3A39%3A33
189. Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A33%20GMT
190. Event-Date-Timestamp: 1671187173207380
191. Event-Calling-File: mod_dialplan_xml.c
192. Event-Calling-Function: dialplan_xml_locate
193. Event-Calling-Line-Number: 608
194. Event-Sequence: 639849
195. Channel-State: CS_ROUTING
196. Channel-Call-State: RINGING
197. Channel-State-Number: 2
198. Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
199. Unique-ID: 6220a791-8f80-4e4b-9751-c5905c23eede
200. Call-Direction: inbound
201. Presence-Call-Direction: inbound
202. Channel-HIT-Dialplan: true
203. Channel-Presence-ID: 1000%40141826.yoursipcloud.com
204. Channel-Call-UUID: 6220a791-8f80-4e4b-9751-c5905c23eede
205. Answer-State: ringing
206. Channel-Read-Codec-Name: PCMU
207. Channel-Read-Codec-Rate: 8000
208. Channel-Read-Codec-Bit-Rate: 64000
209. Channel-Write-Codec-Name: PCMU
210. Channel-Write-Codec-Rate: 8000
211. Channel-Write-Codec-Bit-Rate: 64000
212. Caller-Direction: inbound
213. Caller-Logical-Direction: inbound
214. Caller-Username: 1000
215. Caller-Dialplan: XML
216. Caller-Caller-ID-Name: 1000
217. Caller-Caller-ID-Number: 1000
218. Caller-Orig-Caller-ID-Name: 1000
219. Caller-Orig-Caller-ID-Number: 1000
220. Caller-Network-Addr: 10.0.192.5
221. Caller-ANI: 1000
222. Caller-Destination-Number: 319000980
223. Caller-Unique-ID: 6220a791-8f80-4e4b-9751-c5905c23eede
224. Caller-Source: mod_sofia
225. Caller-Context: default
226. Caller-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
227. Caller-Profile-Index: 1
228. Caller-Profile-Created-Time: 1671187173207380
229. Caller-Channel-Created-Time: 1671187173207380
230. Caller-Channel-Answered-Time: 0
231. Caller-Channel-Progress-Time: 0
232. Caller-Channel-Progress-Media-Time: 0
233. Caller-Channel-Hangup-Time: 0
234. Caller-Channel-Transfer-Time: 0
235. Caller-Channel-Resurrect-Time: 0
236. Caller-Channel-Bridged-Time: 0
237. Caller-Channel-Last-Hold: 0
238. Caller-Channel-Hold-Accum: 0
239. Caller-Screen-Bit: true
240. Caller-Privacy-Hide-Name: false
241. Caller-Privacy-Hide-Number: false
242. variable_direction: inbound
243. variable_uuid: 6220a791-8f80-4e4b-9751-c5905c23eede
244. variable_session_id: 7224
245. variable_sip_from_params: transport%3DTCP
246. variable_sip_from_user: 1000
247. variable_sip_from_uri: 1000%40141826.yoursipcloud.com
248. variable_sip_from_host: 141826.yoursipcloud.com
249. variable_video_media_flow: disabled
250. variable_text_media_flow: disabled
251. variable_channel_name: sofia/default/1000%40141826.yoursipcloud.com
252. variable_sip_call_id: rqYzQ8oALqUXPRlXr1qEQw..
253. variable_sip_local_network_addr: 185.249.40.236
254. variable_sip_network_ip: 10.0.192.5
255. variable_sip_network_port: 57036
256. variable_sip_invite_stamp: 1671187173187379
257. variable_sip_received_ip: 10.0.192.5
258. variable_sip_received_port: 57036
259. variable_sip_via_protocol: tcp
260. variable_sip_authorized: true
261. variable_Event-Name: REQUEST_PARAMS
262. variable_Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
263. variable_FreeSWITCH-Hostname: multitenant-1
264. variable_FreeSWITCH-Switchname: multitenant-1
265. variable_FreeSWITCH-IPv4: 185.249.40.236
266. variable_FreeSWITCH-IPv6: %3A%3A1
267. variable_Event-Date-Local: 2022-12-16%2011%3A39%3A33
268. variable_Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A33%20GMT
269. variable_Event-Date-Timestamp: 1671187173187379
270. variable_Event-Calling-File: sofia.c
271. variable_Event-Calling-Function: sofia_handle_sip_i_invite
272. variable_Event-Calling-Line-Number: 10542
273. variable_Event-Sequence: 639832
274. variable_sip_number_alias: 1000
275. variable_sip_auth_username: 1000
276. variable_sip_auth_realm: 141826.yoursipcloud.com
277. variable_number_alias: 1000
278. variable_requested_user_name: 1000
279. variable_requested_domain_name: 141826.yoursipcloud.com
280. variable_user_context: default
281. variable_sipcall: true
282. variable_sip_user: 1000
283. variable_accountcode: 141826
284. variable_user_name: 1000
285. variable_domain_name: 141826.yoursipcloud.com
286. variable_sip_from_user_stripped: 1000
287. variable_sip_from_tag: b47dd93b
288. variable_sofia_profile_name: default
289. variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
290. variable_recovery_profile_name: default
291. variable_sip_full_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---b7c232bf1a630aec%3Brport%3D57036
292. variable_sip_recover_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---b7c232bf1a630aec%3Brport%3D57036
293. variable_sip_full_from: %3Csip%3A1000%40141826.yoursipcloud.com%3Btransport%3DTCP%3E%3Btag%3Db47dd93b
294. variable_sip_full_to: %3Csip%3A319000980%40141826.yoursipcloud.com%3E
295. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20BYE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20INFO,%20SUBSCRIBE
296. variable_sip_req_params: transport%3DTCP
297. variable_sip_req_user: 319000980
298. variable_sip_req_uri: 319000980%40141826.yoursipcloud.com
299. variable_sip_req_host: 141826.yoursipcloud.com
300. variable_sip_to_user: 319000980
301. variable_sip_to_uri: 319000980%40141826.yoursipcloud.com
302. variable_sip_to_host: 141826.yoursipcloud.com
303. variable_sip_contact_params: transport%3Dtcp
304. variable_sip_contact_user: 1000
305. variable_sip_contact_port: 59984
306. variable_sip_contact_uri: 1000%4010.0.192.5%3A59984
307. variable_sip_contact_host: 10.0.192.5
308. variable_sip_user_agent: Z%205.5.13%20v2.10.18.3
309. variable_sip_via_host: 10.0.192.5
310. variable_sip_via_port: 59984
311. variable_sip_via_rport: 57036
312. variable_max_forwards: 70
313. variable_presence_id: 1000%40141826.yoursipcloud.com
314. variable_sip_nat_detected: true
315. variable_switch_r_sdp: v%3D0%0D%0Ao%3DZ%200%20414229242%20IN%20IP4%2010.0.192.5%0D%0As%3DZ%0D%0Ac%3DIN%20IP4%2010.0.192.5%0D%0At%3D0%200%0D%0Am%3Daudio%2061032%20RTP/AVP%20106%209%2098%20101%200%208%203%0D%0Aa%3Drtpmap%3A106%20opus/48000/2%0D%0Aa%3Dfmtp%3A106%20sprop-maxcapturerate%3D16000%3B%20minptime%3D20%3B%20useinbandfec%3D1%0D%0Aa%3Drtpmap%3A98%20telephone-event/48000%0D%0Aa%3Dfmtp%3A98%200-16%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0A
316. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
317. variable_rtp_use_codec_string: PCMA,PCMU
318. variable_remote_video_media_flow: inactive
319. variable_remote_text_media_flow: inactive
320. variable_remote_audio_media_flow: sendrecv
321. variable_audio_media_flow: sendrecv
322. variable_remote_media_ip: 10.0.192.5
323. variable_remote_media_port: 61032
324. variable_rtp_audio_recv_pt: 0
325. variable_rtp_use_codec_name: PCMU
326. variable_rtp_use_codec_rate: 8000
327. variable_rtp_use_codec_ptime: 20
328. variable_rtp_use_codec_channels: 1
329. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
330. variable_read_codec: PCMU
331. variable_original_read_codec: PCMU
332. variable_read_rate: 8000
333. variable_original_read_rate: 8000
334. variable_write_codec: PCMU
335. variable_write_rate: 8000
336. variable_dtmf_type: rfc2833
337. variable_endpoint_disposition: RECEIVED
338. variable_call_uuid: 6220a791-8f80-4e4b-9751-c5905c23eede
339. Hunt-Direction: inbound
340. Hunt-Logical-Direction: inbound
341. Hunt-Username: 1000
342. Hunt-Dialplan: XML
343. Hunt-Caller-ID-Name: 1000
344. Hunt-Caller-ID-Number: 1000
345. Hunt-Orig-Caller-ID-Name: 1000
346. Hunt-Orig-Caller-ID-Number: 1000
347. Hunt-Network-Addr: 10.0.192.5
348. Hunt-ANI: 1000
349. Hunt-Destination-Number: 319000980
350. Hunt-Unique-ID: 6220a791-8f80-4e4b-9751-c5905c23eede
351. Hunt-Source: mod_sofia
352. Hunt-Context: default
353. Hunt-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
354. Hunt-Profile-Index: 1
355. Hunt-Profile-Created-Time: 1671187173207380
356. Hunt-Channel-Created-Time: 1671187173207380
357. Hunt-Channel-Answered-Time: 0
358. Hunt-Channel-Progress-Time: 0
359. Hunt-Channel-Progress-Media-Time: 0
360. Hunt-Channel-Hangup-Time: 0
361. Hunt-Channel-Transfer-Time: 0
362. Hunt-Channel-Resurrect-Time: 0
363. Hunt-Channel-Bridged-Time: 0
364. Hunt-Channel-Last-Hold: 0
365. Hunt-Channel-Hold-Accum: 0
366. Hunt-Screen-Bit: true
367. Hunt-Privacy-Hide-Name: false
368. Hunt-Privacy-Hide-Number: false
369.  
370.  
371. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name:
372. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name:
373. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
374. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value:
375. 2022-12-16 11:39:33.227380 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID
376. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : Anonymous / 31232051002
377. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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 ="352621453076" LIMIT 1
378. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
379. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 319000980
380. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '319000980' limit 1
381. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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="352621453076"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
382. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '319000980' AND status=0 limit 1
383. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
384. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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("213.144.239.110") 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 "352621453076"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
385. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '319000980' and status=0 LIMIT 1
386. 2022-12-16 11:39:33.227380 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID
387. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 1000 / 1000
388. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
389. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 183284]
390. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "183284" AND deleted = 0 limit 1
391. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
392. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
393. 2022-12-16 11:39:33.227380 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 141826]
394. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "141826" AND deleted = 0 limit 1
395. 2022-12-16 11:39:33.227380 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^352621453076.*' OR blocked_patterns = '^35262145307.*' OR blocked_patterns = '^3526214530.*' OR blocked_patterns = '^352621453.*' OR blocked_patterns = '^35262145.*' OR blocked_patterns = '^3526214.*' OR blocked_patterns = '^352621.*' OR blocked_patterns = '^35262.*' OR blocked_patterns = '^3526.*' OR blocked_patterns = '^352.*' OR blocked_patterns = '^35.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 199 limit 1
396. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="352621453076" AND (accountid = 0 OR status = 1) LIMIT 1
397. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^352621453076.*' OR patterns = '^35262145307.*' OR patterns = '^3526214530.*' OR patterns = '^352621453.*' OR patterns = '^35262145.*' OR patterns = '^3526214.*' OR patterns = '^352621.*' OR patterns = '^35262.*' OR patterns = '^3526.*' OR patterns = '^352.*' OR patterns = '^35.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 199 ORDER BY LENGTH(PKGPTR.patterns) DESC
398. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="352621453076" AND A.accountid = '199' limit 1
399. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 352621453076
400. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="352621453076" LIMIT 1
401. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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="352621453076"  AND domains.domain="emolife138.yoursipcloud.com" limit 1
402. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
403. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '352621453076' OR number = '35262145307' OR number = '3526214530' OR number = '352621453' OR number = '35262145' OR number = '3526214' OR number = '352621' OR number = '35262' OR number = '3526' OR number = '352' OR number = '35' OR number = '3' OR number ='--') OR (number = '352621453076*' OR number = '35262145307*' OR number = '3526214530*' OR number = '352621453*' OR number = '35262145*' OR number = '3526214*' OR number = '352621*' OR number = '35262*' OR number = '3526*' OR number = '352*' OR number = '35*' OR number = '3*' OR number ='--') OR (number = '31232051002' OR number = '3123205100' OR number = '312320510' OR number = '31232051' OR number = '3123205' OR number = '312320' OR number = '31232' OR number = '3123' OR number = '312' OR number = '31' OR number = '3' OR number ='--') OR (number = '31232051002*' OR number = '3123205100*' OR number = '312320510*' OR number = '31232051*' OR number = '3123205*' OR number = '312320*' OR number = '31232*' OR number = '3123*' OR number = '312*' OR number = '31*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
404. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
405. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '352621453076' OR routing_prefix = '35262145307' OR routing_prefix = '3526214530' OR routing_prefix = '352621453' OR routing_prefix = '35262145' OR routing_prefix = '3526214' OR routing_prefix = '352621' OR routing_prefix = '35262' OR routing_prefix = '3526' OR routing_prefix = '352' OR routing_prefix = '35' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 189 limit 1
406. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 1178 limit 1
407. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="319000980" AND (accountid = 0 OR status = 1) LIMIT 1
408. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
409. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 199
410. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 183284
411. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 835.74349
412. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
413. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 25
414. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
415. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
416. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
417. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
418. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 25 AND status = 0
419. 2022-12-16 11:39:33.247382 [NOTICE] switch_cpp.cpp:1447 [ASTPP] Localization Id: 1
420. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
421. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^352621453076.*' OR pattern = '^35262145307.*' OR pattern = '^3526214530.*' OR pattern = '^352621453.*' OR pattern = '^35262145.*' OR pattern = '^3526214.*' OR pattern = '^352621.*' OR pattern = '^35262.*' OR pattern = '^3526.*' OR pattern = '^352.*' OR pattern = '^35.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 25 OR accountid=199)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
422. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
423. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
424. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^319000980.*' OR patterns = '^31900098.*' OR patterns = '^3190009.*' OR patterns = '^319000.*' OR patterns = '^31900.*' OR patterns = '^3190.*' OR patterns = '^319.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 1178 ORDER BY LENGTH(PKGPTR.patterns) DESC
425. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" AND A.accountid = '1178' limit 1
426. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
427. 2022-12-16 11:39:33.247382 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates Not Found :
428. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP]  Post Cdrs : 0
429. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 199
430. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 319000980
431. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
432. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
433. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
434. <document type="freeswitch/xml">
435. <section name="dialplan" description="ASTPP Dialplan">
436. <context name="default">
437. <extension name="352621453076">
438. <condition field="destination_number" expression="352621453076">
439. <action application="log" data="WARNING Accountcode . Dialed number (352621453076)  origination rates not found!!"/>
440. <action application="set" data="original_caller_id_name=Anonymous"/>
441. <action application="set" data="original_caller_id_number=Anonymous"/>
442. <action application="set" data="error_cdr=1"/>
443. <action application="set" data="callstart=2022-12-16 10:39:33"/>
444. <action application="set" data="account_id=199"/>
445. <action application="set" data="parent_id=189"/>
446. <action application="set" data="call_direction=outbound"/>
447. <action application="set" data="sip_ignore_remote_cause=true"/>
448. <action application="set" data="call_processed=internal"/>
449. <action application="set" data="effective_destination_number=352621453076"/>
450. <action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
451. <action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
452. </condition>
453. </extension>
454. </context>
455. </section>
456. </document>
457. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
458. Dialplan: sofia/default/183284@213.144.239.110:9933 parsing [default->352621453076] continue=false
459. Dialplan: sofia/default/183284@213.144.239.110:9933 Regex (PASS) [352621453076] destination_number(352621453076) =~ /352621453076/ break=on-false
460. Dialplan: sofia/default/183284@213.144.239.110:9933 Action log(WARNING Accountcode . Dialed number (352621453076)  origination rates not found!!)
461. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_name=Anonymous)
462. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(original_caller_id_number=Anonymous)
463. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(error_cdr=1)
464. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(callstart=2022-12-16 10:39:33)
465. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(account_id=199)
466. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(parent_id=189)
467. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_direction=outbound)
468. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(sip_ignore_remote_cause=true)
469. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(call_processed=internal)
470. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(effective_destination_number=352621453076)
471. Dialplan: sofia/default/183284@213.144.239.110:9933 Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
472. Dialplan: sofia/default/183284@213.144.239.110:9933 Action hangup(ORIGINATION_RATE_NOT_FOUND)
473. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:287 (sofia/default/183284@213.144.239.110:9933) State Change CS_ROUTING -> CS_EXECUTE
474. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING going to sleep
475. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_EXECUTE (Cur 29 Tot 7226)
476. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE
477. 2022-12-16 11:39:33.247382 [DEBUG] mod_sofia.c:209 sofia/default/183284@213.144.239.110:9933 SOFIA EXECUTE
478. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:329 sofia/default/183284@213.144.239.110:9933 Standard EXECUTE
479. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 log(WARNING Accountcode . Dialed number (352621453076)  origination rates not found!!)
480. 2022-12-16 11:39:33.247382 [WARNING] mod_dptools.c:1866 Accountcode . Dialed number (352621453076)  origination rates not found!!
481. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_name=Anonymous)
482. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_name]=[Anonymous]
483. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(original_caller_id_number=Anonymous)
484. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [original_caller_id_number]=[Anonymous]
485. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(error_cdr=1)
486. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [error_cdr]=[1]
487. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(callstart=2022-12-16 10:39:33)
488. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [callstart]=[2022-12-16 10:39:33]
489. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(account_id=199)
490. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [account_id]=[199]
491. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(parent_id=189)
492. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [parent_id]=[189]
493. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_direction=outbound)
494. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_direction]=[outbound]
495. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(sip_ignore_remote_cause=true)
496. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [sip_ignore_remote_cause]=[true]
497. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(call_processed=internal)
498. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [call_processed]=[internal]
499. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(effective_destination_number=352621453076)
500. 2022-12-16 11:39:33.247382 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
501. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '319000980' OR number = '31900098' OR number = '3190009' OR number = '319000' OR number = '31900' OR number = '3190' OR number = '319' OR number = '31' OR number = '3' OR number ='--') OR (number = '319000980*' OR number = '31900098*' OR number = '3190009*' OR number = '319000*' OR number = '31900*' OR number = '3190*' OR number = '319*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '1000' OR number = '100' OR number = '10' OR number = '1' OR number ='--') OR (number = '1000*' OR number = '100*' OR number = '10*' OR number = '1*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
502. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [effective_destination_number]=[352621453076]
503. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
504. 2022-12-16 11:39:33.247382 [DEBUG] mod_dptools.c:1672 SET sofia/default/183284@213.144.239.110:9933 [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
505. EXECUTE [depth=0] sofia/default/183284@213.144.239.110:9933 hangup(ORIGINATION_RATE_NOT_FOUND)
506. 2022-12-16 11:39:33.247382 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/183284@213.144.239.110:9933 [CS_EXECUTE] [NORMAL_CLEARING]
507. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_session.c:2905 sofia/default/183284@213.144.239.110:9933 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
508. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:651 (sofia/default/183284@213.144.239.110:9933) State EXECUTE going to sleep
509. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_HANGUP (Cur 29 Tot 7226)
510. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:848 (sofia/default/183284@213.144.239.110:9933) Callstate Change RINGING -> HANGUP
511. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP
512. 2022-12-16 11:39:33.247382 [DEBUG] mod_sofia.c:453 Channel sofia/default/183284@213.144.239.110:9933 hanging up, cause: NORMAL_CLEARING
513. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
514. 2022-12-16 11:39:33.247382 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '319000980' OR routing_prefix = '31900098' OR routing_prefix = '3190009' OR routing_prefix = '319000' OR routing_prefix = '31900' OR routing_prefix = '3190' OR routing_prefix = '319' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 12 limit 1
515. 2022-12-16 11:39:33.247382 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
516. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:60 sofia/default/183284@213.144.239.110:9933 Standard HANGUP, cause: NORMAL_CLEARING
517. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:850 (sofia/default/183284@213.144.239.110:9933) State HANGUP going to sleep
518. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:620 (sofia/default/183284@213.144.239.110:9933) State Change CS_HANGUP -> CS_REPORTING
519. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_REPORTING (Cur 29 Tot 7226)
520. 2022-12-16 11:39:33.247382 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING
521. 2022-12-16 11:39:33.247382 [INFO] mod_json_cdr.c:271 Process [501160ca-0747-4211-b6aa-8caed8d6b7cc.cdr.json]
522. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
523. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 1178
524. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 141826
525. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 95.05
526. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
527. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 6
528. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
529. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
530. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
531. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
532. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
533. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 6 OR accountid=1178)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
534. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
535. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
536. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 3742429
537. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.07200
538. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 1
539. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.05400
540. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : Netherland Special
541. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 136
542. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 1178
543. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID:
544. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type:
545. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
546. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your95.05 balance Accountid 1178 !!!
547. 2022-12-16 11:39:33.267384 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
548. 2022-12-16 11:39:33.267384 [NOTICE] switch_cpp.cpp:1447 [ASTPP] FINDING LIMIT FOR RESELLER: 12
549. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "12" AND deleted = 0 limit 1
550. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 12 limit 1
551. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Reseller Information ===================
552. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 12
553. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3847299300
554. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 98925.58531
555. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
556. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 44
557. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
558. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
559. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
560. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 44 OR accountid=12)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
561. 2022-12-16 11:39:33.267384 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
562. 2022-12-16 11:39:33.267384 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates Not Found :
563. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP]  Post Cdrs : 0
564. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 1178
565. 2022-12-16 11:39:33.267384 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
566. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
567. <document type="freeswitch/xml">
568. <section name="dialplan" description="ASTPP Dialplan">
569. <context name="default">
570. <extension name="319000980">
571. <condition field="destination_number" expression="319000980">
572. <action application="log" data="WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!"/>
573. <action application="set" data="original_caller_id_name=1000"/>
574. <action application="set" data="original_caller_id_number=1000"/>
575. <action application="set" data="error_cdr=1"/>
576. <action application="set" data="callstart=2022-12-16 10:39:33"/>
577. <action application="set" data="account_id=1178"/>
578. <action application="set" data="parent_id=12"/>
579. <action application="set" data="call_direction=outbound"/>
580. <action application="set" data="sip_ignore_remote_cause=true"/>
581. <action application="set" data="call_processed=internal"/>
582. <action application="set" data="effective_destination_number=319000980"/>
583. <action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
584. <action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
585. </condition>
586. </extension>
587. </context>
588. </section>
589. </document>
590. Dialplan: sofia/default/1000@141826.yoursipcloud.com parsing [default->319000980] continue=false
591. Dialplan: sofia/default/1000@141826.yoursipcloud.com Regex (PASS) [319000980] destination_number(319000980) =~ /319000980/ break=on-false
592. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!)
593. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_name=1000)
594. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_number=1000)
595. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(error_cdr=1)
596. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(callstart=2022-12-16 10:39:33)
597. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(account_id=1178)
598. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(parent_id=12)
599. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_direction=outbound)
600. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(sip_ignore_remote_cause=true)
601. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_processed=internal)
602. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(effective_destination_number=319000980)
603. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
604. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action hangup(ORIGINATION_RATE_NOT_FOUND)
605. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:287 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_ROUTING -> CS_EXECUTE
606. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:644 (sofia/default/1000@141826.yoursipcloud.com) State ROUTING going to sleep
607. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_EXECUTE (Cur 29 Tot 7226)
608. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE
609. 2022-12-16 11:39:33.267384 [DEBUG] mod_sofia.c:209 sofia/default/1000@141826.yoursipcloud.com SOFIA EXECUTE
610. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:329 sofia/default/1000@141826.yoursipcloud.com Standard EXECUTE
611. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!)
612. 2022-12-16 11:39:33.267384 [WARNING] mod_dptools.c:1866 Accountcode 141826. Dialed number (319000980)  origination rates not found!!
613. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_name=1000)
614. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_name]=[1000]
615. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_number=1000)
616. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_number]=[1000]
617. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(error_cdr=1)
618. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [error_cdr]=[1]
619. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(callstart=2022-12-16 10:39:33)
620. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [callstart]=[2022-12-16 10:39:33]
621. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(account_id=1178)
622. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [account_id]=[1178]
623. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(parent_id=12)
624. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [parent_id]=[12]
625. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_direction=outbound)
626. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_direction]=[outbound]
627. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(sip_ignore_remote_cause=true)
628. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [sip_ignore_remote_cause]=[true]
629. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_processed=internal)
630. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_processed]=[internal]
631. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(effective_destination_number=319000980)
632. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [effective_destination_number]=[319000980]
633. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
634. 2022-12-16 11:39:33.267384 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
635. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com hangup(ORIGINATION_RATE_NOT_FOUND)
636. 2022-12-16 11:39:33.267384 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/1000@141826.yoursipcloud.com [CS_EXECUTE] [NORMAL_CLEARING]
637. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_session.c:2905 sofia/default/1000@141826.yoursipcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
638. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE going to sleep
639. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_HANGUP (Cur 29 Tot 7226)
640. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:848 (sofia/default/1000@141826.yoursipcloud.com) Callstate Change RINGING -> HANGUP
641. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP
642. 2022-12-16 11:39:33.267384 [DEBUG] mod_sofia.c:453 Channel sofia/default/1000@141826.yoursipcloud.com hanging up, cause: NORMAL_CLEARING
643. 2022-12-16 11:39:33.267384 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
644. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:60 sofia/default/1000@141826.yoursipcloud.com Standard HANGUP, cause: NORMAL_CLEARING
645. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP going to sleep
646. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:620 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_HANGUP -> CS_REPORTING
647. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_REPORTING (Cur 29 Tot 7226)
648. 2022-12-16 11:39:33.267384 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING
649. 2022-12-16 11:39:33.267384 [INFO] mod_json_cdr.c:271 Process [6220a791-8f80-4e4b-9751-c5905c23eede.cdr.json]
650. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:174 sofia/default/183284@213.144.239.110:9933 Standard REPORTING, cause: NORMAL_CLEARING
651. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:936 (sofia/default/183284@213.144.239.110:9933) State REPORTING going to sleep
652. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:611 (sofia/default/183284@213.144.239.110:9933) State Change CS_REPORTING -> CS_DESTROY
653. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_session.c:1726 Session 7226 (sofia/default/183284@213.144.239.110:9933) Locked, Waiting on external entities
654. 2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1744 Session 7226 (sofia/default/183284@213.144.239.110:9933) Ended
655. 2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/183284@213.144.239.110:9933 [CS_DESTROY]
656. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:739 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_DESTROY (Cur 28 Tot 7226)
657. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY
658. 2022-12-16 11:39:33.287384 [DEBUG] mod_sofia.c:364 sofia/default/183284@213.144.239.110:9933 SOFIA DESTROY
659. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:181 sofia/default/183284@213.144.239.110:9933 Standard DESTROY
660. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/183284@213.144.239.110:9933) State DESTROY going to sleep
661. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:174 sofia/default/1000@141826.yoursipcloud.com Standard REPORTING, cause: NORMAL_CLEARING
662. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING going to sleep
663. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:611 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_REPORTING -> CS_DESTROY
664. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_session.c:1726 Session 7224 (sofia/default/1000@141826.yoursipcloud.com) Locked, Waiting on external entities
665. 2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1744 Session 7224 (sofia/default/1000@141826.yoursipcloud.com) Ended
666. 2022-12-16 11:39:33.287384 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/1000@141826.yoursipcloud.com [CS_DESTROY]
667. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:739 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_DESTROY (Cur 27 Tot 7226)
668. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY
669. 2022-12-16 11:39:33.287384 [DEBUG] mod_sofia.c:364 sofia/default/1000@141826.yoursipcloud.com SOFIA DESTROY
670. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:181 sofia/default/1000@141826.yoursipcloud.com Standard DESTROY
671. 2022-12-16 11:39:33.287384 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY going to sleep
672. 2022-12-16 11:39:33.347379 [DEBUG] sofia.c:7326 Channel sofia/default/31555334124 entering state [proceeding][180]
673. 2022-12-16 11:39:33.347379 [NOTICE] sofia.c:7437 Ring-Ready sofia/default/31555334124!
674. 2022-12-16 11:39:33.347379 [DEBUG] switch_channel.c:3437 (sofia/default/31555334124) Callstate Change DOWN -> RINGING
675. 2022-12-16 11:39:33.347379 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/default/201@261408.yoursipcloud.com!
676. 2022-12-16 11:39:33.347379 [DEBUG] sofia.c:7326 Channel sofia/default/201@261408.yoursipcloud.com entering state [early][180]
677. 2022-12-16 11:39:33.347379 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/default/201@261408.yoursipcloud.com!
678. 2022-12-16 11:39:34.247389 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [200@262378.yoursipcloud.com] from ip 95.211.34.130
679. 2022-12-16 11:39:34.287379 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@260278.yoursipcloud.com] from ip 45.129.144.58
680. 2022-12-16 11:39:35.167383 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [202@260278.yoursipcloud.com] from ip 45.129.144.58
681. 2022-12-16 11:39:36.527394 [WARNING] switch_core_state_machine.c:688 3dafb827-ca78-40a5-b2fe-5fcadc55f8c2 sofia/default/4005@185.249.40.236 Abandoned
682. 2022-12-16 11:39:36.527394 [NOTICE] switch_core_state_machine.c:691 Hangup sofia/default/4005@185.249.40.236 [CS_NEW] [WRONG_CALL_STATE]
683. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4005@185.249.40.236) Running State Change CS_HANGUP (Cur 27 Tot 7226)
684. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:848 (sofia/default/4005@185.249.40.236) Callstate Change DOWN -> HANGUP
685. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:850 (sofia/default/4005@185.249.40.236) State HANGUP
686. 2022-12-16 11:39:36.527394 [DEBUG] mod_sofia.c:453 Channel sofia/default/4005@185.249.40.236 hanging up, cause: WRONG_CALL_STATE
687. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:60 sofia/default/4005@185.249.40.236 Standard HANGUP, cause: WRONG_CALL_STATE
688. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:850 (sofia/default/4005@185.249.40.236) State HANGUP going to sleep
689. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:620 (sofia/default/4005@185.249.40.236) State Change CS_HANGUP -> CS_REPORTING
690. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4005@185.249.40.236) Running State Change CS_REPORTING (Cur 27 Tot 7226)
691. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:936 (sofia/default/4005@185.249.40.236) State REPORTING
692. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:174 sofia/default/4005@185.249.40.236 Standard REPORTING, cause: WRONG_CALL_STATE
693. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:936 (sofia/default/4005@185.249.40.236) State REPORTING going to sleep
694. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:611 (sofia/default/4005@185.249.40.236) State Change CS_REPORTING -> CS_DESTROY
695. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_session.c:1726 Session 7222 (sofia/default/4005@185.249.40.236) Locked, Waiting on external entities
696. 2022-12-16 11:39:36.527394 [NOTICE] switch_core_session.c:1744 Session 7222 (sofia/default/4005@185.249.40.236) Ended
697. 2022-12-16 11:39:36.527394 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/4005@185.249.40.236 [CS_DESTROY]
698. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:739 (sofia/default/4005@185.249.40.236) Running State Change CS_DESTROY (Cur 26 Tot 7226)
699. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:749 (sofia/default/4005@185.249.40.236) State DESTROY
700. 2022-12-16 11:39:36.527394 [DEBUG] mod_sofia.c:364 sofia/default/4005@185.249.40.236 SOFIA DESTROY
701. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:181 sofia/default/4005@185.249.40.236 Standard DESTROY
702. 2022-12-16 11:39:36.527394 [DEBUG] switch_core_state_machine.c:749 (sofia/default/4005@185.249.40.236) State DESTROY going to sleep
703. 2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7326 Channel sofia/default/31713412309 entering state [completing][200]
704. 2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7336 Remote SDP:
705. v=0
706. o=- 2410390152 1 IN IP4 185.249.40.253
707. s=-
708. c=IN IP4 185.249.40.253
709. t=0 0
710. m=audio 27614 RTP/AVP 8 101
711. a=rtpmap:101 telephone-event/8000
712. a=ptime:20
713.  
714. 2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7326 Channel sofia/default/31713412309 entering state [ready][200]
715. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
716. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
717. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
718. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/31713412309 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
719. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_codec.c:111 sofia/default/31713412309 Original read codec set to PCMA:8
720. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
721. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:5911 sofia/default/31713412309 Set 2833 dtmf send payload to 101 recv payload to 101
722. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31713412309] 185.249.40.236 port 23844 -> 185.249.40.253 port 27614 codec: 8 ms: 20
723. 2022-12-16 11:39:36.967386 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
724. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8977 sofia/default/31713412309 Set 2833 dtmf send payload to 101
725. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8984 sofia/default/31713412309 Set 2833 dtmf receive payload to 101
726. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:9007 sofia/default/31713412309 Set rtp dtmf delay to 40
727. 2022-12-16 11:39:36.967386 [NOTICE] sofia.c:8504 Channel [sofia/default/31713412309] has been answered
728. 2022-12-16 11:39:36.967386 [DEBUG] switch_channel.c:3865 (sofia/default/31713412309) Callstate Change RINGING -> ACTIVE
729. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/31725037039@pilkes142.yoursipcloud.com] 185.249.40.236 port 31310 -> 54.36.114.148 port 9860 codec: 0 ms: 20
730. 2022-12-16 11:39:36.967386 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
731. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8977 sofia/default/31725037039@pilkes142.yoursipcloud.com Set 2833 dtmf send payload to 101
732. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:8984 sofia/default/31725037039@pilkes142.yoursipcloud.com Set 2833 dtmf receive payload to 101
733. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_media.c:9007 sofia/default/31725037039@pilkes142.yoursipcloud.com Set rtp dtmf delay to 40
734. 2022-12-16 11:39:36.967386 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/31725037039@pilkes142.yoursipcloud.com:
735. v=0
736. o=FreeSWITCH 1671155866 1671155867 IN IP4 185.249.40.236
737. s=FreeSWITCH
738. c=IN IP4 185.249.40.236
739. t=0 0
740. m=audio 31310 RTP/AVP 0 101
741. a=rtpmap:0 PCMU/8000
742. a=rtpmap:101 telephone-event/8000
743. a=fmtp:101 0-16
744. a=ptime:20
745. a=sendrecv
746.  
747. 2022-12-16 11:39:36.967386 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/default/31725037039@pilkes142.yoursipcloud.com] has been answered
748. 2022-12-16 11:39:36.967386 [DEBUG] switch_channel.c:3865 (sofia/default/31725037039@pilkes142.yoursipcloud.com) Callstate Change RINGING -> ACTIVE
749. 2022-12-16 11:39:36.967386 [DEBUG] sofia.c:7326 Channel sofia/default/31725037039@pilkes142.yoursipcloud.com entering state [completed][200]
750. 2022-12-16 11:39:36.967386 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/31713412309]
751. 2022-12-16 11:39:36.967386 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/31713412309) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
752. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31713412309) Running State Change CS_EXCHANGE_MEDIA (Cur 26 Tot 7226)
753. 2022-12-16 11:39:36.967386 [DEBUG] switch_core_state_machine.c:654 (sofia/default/31713412309) State EXCHANGE_MEDIA
754. 2022-12-16 11:39:36.967386 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
755. 2022-12-16 11:39:37.027380 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
756. 2022-12-16 11:39:37.047381 [DEBUG] sofia.c:7326 Channel sofia/default/31725037039@pilkes142.yoursipcloud.com entering state [ready][200]
757. 2022-12-16 11:39:37.087378 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
758. 2022-12-16 11:39:37.167383 [DEBUG] switch_ivr_bridge.c:655 sofia/default/31713412309 Bridge execute app sched_hangup(+6000 normal_clearing)
759. 2022-12-16 11:39:37.187379 [DEBUG] switch_ivr.c:632 sofia/default/31713412309 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
760. EXECUTE [depth=0] sofia/default/31713412309 sched_hangup(+6000 normal_clearing)
761. 2022-12-16 11:39:37.187379 [DEBUG] switch_scheduler.c:252 Added task 1368 switch_ivr_schedule_hangup (99458322-5246-4e2b-b789-d7ec0f273153) to run at 1671193177
762. 2022-12-16 11:39:38.107392 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [201@00135948.yoursipcloud.com] from ip 185.249.42.190
763. 2022-12-16 11:39:38.787380 [NOTICE] switch_channel.c:1118 New Channel sofia/default/1000@141826.yoursipcloud.com [d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57]
764. 2022-12-16 11:39:38.787380 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_NEW (Cur 27 Tot 7227)
765. 2022-12-16 11:39:38.787380 [DEBUG] sofia.c:10280 sofia/default/1000@141826.yoursipcloud.com receiving invite from 10.0.192.5:57036 version: 1.10.5 -release-17-25569c1631 64bit
766. 2022-12-16 11:39:38.787380 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 10.0.192.5:0.
767. 2022-12-16 11:39:38.787380 [WARNING] sofia_reg.c:1794 SIP auth challenge (INVITE) on sofia profile 'default' for [319000980@141826.yoursipcloud.com] from ip 10.0.192.5
768. 2022-12-16 11:39:38.787380 [DEBUG] switch_core_state_machine.c:604 (sofia/default/1000@141826.yoursipcloud.com) State NEW
769. 2022-12-16 11:39:38.787380 [DEBUG] sofia.c:2434 detaching session d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
770. 2022-12-16 11:39:39.207467 [DEBUG] sofia.c:2544 Re-attaching to session d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
771. 2022-12-16 11:39:39.207467 [DEBUG] sofia.c:10280 sofia/default/1000@141826.yoursipcloud.com receiving invite from 10.0.192.5:57036 version: 1.10.5 -release-17-25569c1631 64bit
772. 2022-12-16 11:39:39.207467 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 10.0.192.5:0.
773. 2022-12-16 11:39:39.207467 [DEBUG] sofia.c:11477 Setting NAT mode based on via port
774. 2022-12-16 11:39:39.207467 [DEBUG] sofia.c:7326 Channel sofia/default/1000@141826.yoursipcloud.com entering state [received][100]
775. 2022-12-16 11:39:39.207467 [DEBUG] sofia.c:7336 Remote SDP:
776. v=0
777. o=Z 0 414235312 IN IP4 10.0.192.5
778. s=Z
779. c=IN IP4 10.0.192.5
780. t=0 0
781. m=audio 61033 RTP/AVP 106 9 98 101 0 8 3
782. a=rtpmap:106 opus/48000/2
783. a=fmtp:106 sprop-maxcapturerate=16000; minptime=20; useinbandfec=1
784. a=rtpmap:98 telephone-event/48000
785. a=fmtp:98 0-16
786. a=rtpmap:101 telephone-event/8000
787. a=fmtp:101 0-16
788.  
789. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:106:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
790. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [opus:106:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
791. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
792. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
793. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 98@48000
794. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
795. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
796. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
797. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
798. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
799. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
800. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
801. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
802. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/1000@141826.yoursipcloud.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
803. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_codec.c:111 sofia/default/1000@141826.yoursipcloud.com Original read codec set to PCMU:0
804. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
805. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_media.c:5911 sofia/default/1000@141826.yoursipcloud.com Set 2833 dtmf send payload to 101 recv payload to 101
806. 2022-12-16 11:39:39.207467 [DEBUG] sofia.c:7760 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_NEW -> CS_INIT
807. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_INIT (Cur 27 Tot 7227)
808. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:628 (sofia/default/1000@141826.yoursipcloud.com) State INIT
809. 2022-12-16 11:39:39.207467 [DEBUG] mod_sofia.c:93 sofia/default/1000@141826.yoursipcloud.com SOFIA INIT
810. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:40 sofia/default/1000@141826.yoursipcloud.com Standard INIT
811. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:48 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_INIT -> CS_ROUTING
812. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:628 (sofia/default/1000@141826.yoursipcloud.com) State INIT going to sleep
813. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_ROUTING (Cur 27 Tot 7227)
814. 2022-12-16 11:39:39.207467 [DEBUG] switch_channel.c:2332 (sofia/default/1000@141826.yoursipcloud.com) Callstate Change DOWN -> RINGING
815. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:644 (sofia/default/1000@141826.yoursipcloud.com) State ROUTING
816. 2022-12-16 11:39:39.207467 [DEBUG] mod_sofia.c:154 sofia/default/1000@141826.yoursipcloud.com SOFIA ROUTING
817. 2022-12-16 11:39:39.207467 [DEBUG] switch_core_state_machine.c:236 sofia/default/1000@141826.yoursipcloud.com Standard ROUTING
818. 2022-12-16 11:39:39.207467 [INFO] mod_dialplan_xml.c:637 Processing 1000 <1000>->319000980 in context default
819. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
820. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
821. 2022-12-16 11:39:39.247381 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
822. Event-Name: REQUEST_PARAMS
823. Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
824. FreeSWITCH-Hostname: multitenant-1
825. FreeSWITCH-Switchname: multitenant-1
826. FreeSWITCH-IPv4: 185.249.40.236
827. FreeSWITCH-IPv6: %3A%3A1
828. Event-Date-Local: 2022-12-16%2011%3A39%3A39
829. Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A39%20GMT
830. Event-Date-Timestamp: 1671187179207467
831. Event-Calling-File: mod_dialplan_xml.c
832. Event-Calling-Function: dialplan_xml_locate
833. Event-Calling-Line-Number: 608
834. Event-Sequence: 639996
835. Channel-State: CS_ROUTING
836. Channel-Call-State: RINGING
837. Channel-State-Number: 2
838. Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
839. Unique-ID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
840. Call-Direction: inbound
841. Presence-Call-Direction: inbound
842. Channel-HIT-Dialplan: true
843. Channel-Presence-ID: 1000%40141826.yoursipcloud.com
844. Channel-Call-UUID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
845. Answer-State: ringing
846. Channel-Read-Codec-Name: PCMU
847. Channel-Read-Codec-Rate: 8000
848. Channel-Read-Codec-Bit-Rate: 64000
849. Channel-Write-Codec-Name: PCMU
850. Channel-Write-Codec-Rate: 8000
851. Channel-Write-Codec-Bit-Rate: 64000
852. Caller-Direction: inbound
853. Caller-Logical-Direction: inbound
854. Caller-Username: 1000
855. Caller-Dialplan: XML
856. Caller-Caller-ID-Name: 1000
857. Caller-Caller-ID-Number: 1000
858. Caller-Orig-Caller-ID-Name: 1000
859. Caller-Orig-Caller-ID-Number: 1000
860. Caller-Network-Addr: 10.0.192.5
861. Caller-ANI: 1000
862. Caller-Destination-Number: 319000980
863. Caller-Unique-ID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
864. Caller-Source: mod_sofia
865. Caller-Context: default
866. Caller-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
867. Caller-Profile-Index: 1
868. Caller-Profile-Created-Time: 1671187179207467
869. Caller-Channel-Created-Time: 1671187179207467
870. Caller-Channel-Answered-Time: 0
871. Caller-Channel-Progress-Time: 0
872. Caller-Channel-Progress-Media-Time: 0
873. Caller-Channel-Hangup-Time: 0
874. Caller-Channel-Transfer-Time: 0
875. Caller-Channel-Resurrect-Time: 0
876. Caller-Channel-Bridged-Time: 0
877. Caller-Channel-Last-Hold: 0
878. Caller-Channel-Hold-Accum: 0
879. Caller-Screen-Bit: true
880. Caller-Privacy-Hide-Name: false
881. Caller-Privacy-Hide-Number: false
882. variable_direction: inbound
883. variable_uuid: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
884. variable_session_id: 7227
885. variable_sip_from_params: transport%3DTCP
886. variable_sip_from_user: 1000
887. variable_sip_from_uri: 1000%40141826.yoursipcloud.com
888. variable_sip_from_host: 141826.yoursipcloud.com
889. variable_video_media_flow: disabled
890. variable_text_media_flow: disabled
891. variable_channel_name: sofia/default/1000%40141826.yoursipcloud.com
892. variable_sip_call_id: sy9uprcDM_cz0vTQk5WNrQ..
893. variable_sip_local_network_addr: 185.249.40.236
894. variable_sip_network_ip: 10.0.192.5
895. variable_sip_network_port: 57036
896. variable_sip_invite_stamp: 1671187179207467
897. variable_sip_received_ip: 10.0.192.5
898. variable_sip_received_port: 57036
899. variable_sip_via_protocol: tcp
900. variable_sip_authorized: true
901. variable_Event-Name: REQUEST_PARAMS
902. variable_Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
903. variable_FreeSWITCH-Hostname: multitenant-1
904. variable_FreeSWITCH-Switchname: multitenant-1
905. variable_FreeSWITCH-IPv4: 185.249.40.236
906. variable_FreeSWITCH-IPv6: %3A%3A1
907. variable_Event-Date-Local: 2022-12-16%2011%3A39%3A39
908. variable_Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A39%20GMT
909. variable_Event-Date-Timestamp: 1671187179207467
910. variable_Event-Calling-File: sofia.c
911. variable_Event-Calling-Function: sofia_handle_sip_i_invite
912. variable_Event-Calling-Line-Number: 10542
913. variable_Event-Sequence: 639987
914. variable_sip_number_alias: 1000
915. variable_sip_auth_username: 1000
916. variable_sip_auth_realm: 141826.yoursipcloud.com
917. variable_number_alias: 1000
918. variable_requested_user_name: 1000
919. variable_requested_domain_name: 141826.yoursipcloud.com
920. variable_user_context: default
921. variable_sipcall: true
922. variable_sip_user: 1000
923. variable_accountcode: 141826
924. variable_user_name: 1000
925. variable_domain_name: 141826.yoursipcloud.com
926. variable_sip_from_user_stripped: 1000
927. variable_sip_from_tag: ec253225
928. variable_sofia_profile_name: default
929. variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
930. variable_recovery_profile_name: default
931. variable_sip_full_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---eb12fc7a2defd632%3Brport%3D57036
932. variable_sip_recover_via: SIP/2.0/TCP%2010.0.192.5%3A59984%3Bbranch%3Dz9hG4bK-524287-1---eb12fc7a2defd632%3Brport%3D57036
933. variable_sip_full_from: %3Csip%3A1000%40141826.yoursipcloud.com%3Btransport%3DTCP%3E%3Btag%3Dec253225
934. variable_sip_full_to: %3Csip%3A319000980%40141826.yoursipcloud.com%3E
935. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20BYE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20INFO,%20SUBSCRIBE
936. variable_sip_req_params: transport%3DTCP
937. variable_sip_req_user: 319000980
938. variable_sip_req_uri: 319000980%40141826.yoursipcloud.com
939. variable_sip_req_host: 141826.yoursipcloud.com
940. variable_sip_to_user: 319000980
941. variable_sip_to_uri: 319000980%40141826.yoursipcloud.com
942. variable_sip_to_host: 141826.yoursipcloud.com
943. variable_sip_contact_params: transport%3Dtcp
944. variable_sip_contact_user: 1000
945. variable_sip_contact_port: 59984
946. variable_sip_contact_uri: 1000%4010.0.192.5%3A59984
947. variable_sip_contact_host: 10.0.192.5
948. variable_sip_user_agent: Z%205.5.13%20v2.10.18.3
949. variable_sip_via_host: 10.0.192.5
950. variable_sip_via_port: 59984
951. variable_sip_via_rport: 57036
952. variable_max_forwards: 70
953. variable_presence_id: 1000%40141826.yoursipcloud.com
954. variable_sip_nat_detected: true
955. variable_switch_r_sdp: v%3D0%0D%0Ao%3DZ%200%20414235312%20IN%20IP4%2010.0.192.5%0D%0As%3DZ%0D%0Ac%3DIN%20IP4%2010.0.192.5%0D%0At%3D0%200%0D%0Am%3Daudio%2061033%20RTP/AVP%20106%209%2098%20101%200%208%203%0D%0Aa%3Drtpmap%3A106%20opus/48000/2%0D%0Aa%3Dfmtp%3A106%20sprop-maxcapturerate%3D16000%3B%20minptime%3D20%3B%20useinbandfec%3D1%0D%0Aa%3Drtpmap%3A98%20telephone-event/48000%0D%0Aa%3Dfmtp%3A98%200-16%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0A
956. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
957. variable_rtp_use_codec_string: PCMA,PCMU
958. variable_remote_video_media_flow: inactive
959. variable_remote_text_media_flow: inactive
960. variable_remote_audio_media_flow: sendrecv
961. variable_audio_media_flow: sendrecv
962. variable_remote_media_ip: 10.0.192.5
963. variable_remote_media_port: 61033
964. variable_rtp_audio_recv_pt: 0
965. variable_rtp_use_codec_name: PCMU
966. variable_rtp_use_codec_rate: 8000
967. variable_rtp_use_codec_ptime: 20
968. variable_rtp_use_codec_channels: 1
969. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
970. variable_read_codec: PCMU
971. variable_original_read_codec: PCMU
972. variable_read_rate: 8000
973. variable_original_read_rate: 8000
974. variable_write_codec: PCMU
975. variable_write_rate: 8000
976. variable_dtmf_type: rfc2833
977. variable_endpoint_disposition: RECEIVED
978. variable_call_uuid: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
979. Hunt-Direction: inbound
980. Hunt-Logical-Direction: inbound
981. Hunt-Username: 1000
982. Hunt-Dialplan: XML
983. Hunt-Caller-ID-Name: 1000
984. Hunt-Caller-ID-Number: 1000
985. Hunt-Orig-Caller-ID-Name: 1000
986. Hunt-Orig-Caller-ID-Number: 1000
987. Hunt-Network-Addr: 10.0.192.5
988. Hunt-ANI: 1000
989. Hunt-Destination-Number: 319000980
990. Hunt-Unique-ID: d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57
991. Hunt-Source: mod_sofia
992. Hunt-Context: default
993. Hunt-Channel-Name: sofia/default/1000%40141826.yoursipcloud.com
994. Hunt-Profile-Index: 1
995. Hunt-Profile-Created-Time: 1671187179207467
996. Hunt-Channel-Created-Time: 1671187179207467
997. Hunt-Channel-Answered-Time: 0
998. Hunt-Channel-Progress-Time: 0
999. Hunt-Channel-Progress-Media-Time: 0
1000. Hunt-Channel-Hangup-Time: 0
1001. Hunt-Channel-Transfer-Time: 0
1002. Hunt-Channel-Resurrect-Time: 0
1003. Hunt-Channel-Bridged-Time: 0
1004. Hunt-Channel-Last-Hold: 0
1005. Hunt-Channel-Hold-Accum: 0
1006. Hunt-Screen-Bit: true
1007. Hunt-Privacy-Hide-Name: false
1008. Hunt-Privacy-Hide-Number: false
1009.  
1010.  
1011. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value:
1012. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
1013. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name:
1014. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name:
1015. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
1016. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 319000980
1017. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '319000980' limit 1
1018. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '319000980' AND status=0 limit 1
1019. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '319000980' and status=0 LIMIT 1
1020. 2022-12-16 11:39:39.247381 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FAXER] Custom Caller ID
1021. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 1000 / 1000
1022. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
1023. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
1024. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
1025. 2022-12-16 11:39:39.247381 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 141826]
1026. 2022-12-16 11:39:39.247381 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "141826" AND deleted = 0 limit 1
1027. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 1178 limit 1
1028. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="319000980" AND (accountid = 0 OR status = 1) LIMIT 1
1029. 2022-12-16 11:39:39.267383 [NOTICE] switch_cpp.cpp:1447 [ASTPP] Localization Id: 1
1030. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
1031. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
1032. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....319000980
1033. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^319000980.*' OR patterns = '^31900098.*' OR patterns = '^3190009.*' OR patterns = '^319000.*' OR patterns = '^31900.*' OR patterns = '^3190.*' OR patterns = '^319.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 1178 ORDER BY LENGTH(PKGPTR.patterns) DESC
1034. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" AND A.accountid = '1178' limit 1
1035. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 319000980
1036. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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 ="319000980" LIMIT 1
1037. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [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="319000980"  AND domains.domain="141826.yoursipcloud.com" limit 1
1038. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call Direction : outbound
1039. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '319000980' OR number = '31900098' OR number = '3190009' OR number = '319000' OR number = '31900' OR number = '3190' OR number = '319' OR number = '31' OR number = '3' OR number ='--') OR (number = '319000980*' OR number = '31900098*' OR number = '3190009*' OR number = '319000*' OR number = '31900*' OR number = '3190*' OR number = '319*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '1000' OR number = '100' OR number = '10' OR number = '1' OR number ='--') OR (number = '1000*' OR number = '100*' OR number = '10*' OR number = '1*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
1040. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
1041. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '319000980' OR routing_prefix = '31900098' OR routing_prefix = '3190009' OR routing_prefix = '319000' OR routing_prefix = '31900' OR routing_prefix = '3190' OR routing_prefix = '319' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 12 limit 1
1042. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
1043. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 1178
1044. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 141826
1045. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 95.05
1046. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
1047. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 6
1048. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
1049. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
1050. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
1051. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
1052. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
1053. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 6 OR accountid=1178)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
1054. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
1055. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
1056. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 3742429
1057. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.07200
1058. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 1
1059. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.05400
1060. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Comment : Netherland Special
1061. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 136
1062. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 1178
1063. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID:
1064. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type:
1065. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
1066. 2022-12-16 11:39:39.267383 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your95.05 balance Accountid 1178 !!!
1067. 2022-12-16 11:39:39.267383 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
1068. 2022-12-16 11:39:39.267383 [NOTICE] switch_cpp.cpp:1447 [ASTPP] FINDING LIMIT FOR RESELLER: 12
1069. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "12" AND deleted = 0 limit 1
1070. 2022-12-16 11:39:39.267383 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^319000980.*' OR blocked_patterns = '^31900098.*' OR blocked_patterns = '^3190009.*' OR blocked_patterns = '^319000.*' OR blocked_patterns = '^31900.*' OR blocked_patterns = '^3190.*' OR blocked_patterns = '^319.*' OR blocked_patterns = '^31.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 12 limit 1
1071. 2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Reseller Information ===================
1072. 2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 12
1073. 2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3847299300
1074. 2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 98925.58531
1075. 2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
1076. 2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 44
1077. 2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
1078. 2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
1079. 2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
1080. 2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^319000980.*' OR pattern = '^31900098.*' OR pattern = '^3190009.*' OR pattern = '^319000.*' OR pattern = '^31900.*' OR pattern = '^3190.*' OR pattern = '^319.*' OR pattern = '^31.*' OR pattern = '^3.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 44 OR accountid=12)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
1081. 2022-12-16 11:39:39.287387 [INFO] switch_cpp.cpp:1447 [ASTPP]  Call Direction : outbound
1082. 2022-12-16 11:39:39.287387 [WARNING] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Rates Not Found :
1083. 2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP]  Post Cdrs : 0
1084. 2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 1178
1085. 2022-12-16 11:39:39.287387 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Generated XML:
1086. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
1087. <document type="freeswitch/xml">
1088. <section name="dialplan" description="ASTPP Dialplan">
1089. <context name="default">
1090. <extension name="319000980">
1091. <condition field="destination_number" expression="319000980">
1092. <action application="log" data="WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!"/>
1093. <action application="set" data="original_caller_id_name=1000"/>
1094. <action application="set" data="original_caller_id_number=1000"/>
1095. <action application="set" data="error_cdr=1"/>
1096. <action application="set" data="callstart=2022-12-16 10:39:39"/>
1097. <action application="set" data="account_id=1178"/>
1098. <action application="set" data="parent_id=12"/>
1099. <action application="set" data="call_direction=outbound"/>
1100. <action application="set" data="sip_ignore_remote_cause=true"/>
1101. <action application="set" data="call_processed=internal"/>
1102. <action application="set" data="effective_destination_number=319000980"/>
1103. <action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
1104. <action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
1105. </condition>
1106. </extension>
1107. </context>
1108. </section>
1109. </document>
1110. Dialplan: sofia/default/1000@141826.yoursipcloud.com parsing [default->319000980] continue=false
1111. Dialplan: sofia/default/1000@141826.yoursipcloud.com Regex (PASS) [319000980] destination_number(319000980) =~ /319000980/ break=on-false
1112. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!)
1113. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_name=1000)
1114. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(original_caller_id_number=1000)
1115. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(error_cdr=1)
1116. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(callstart=2022-12-16 10:39:39)
1117. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(account_id=1178)
1118. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(parent_id=12)
1119. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_direction=outbound)
1120. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(sip_ignore_remote_cause=true)
1121. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(call_processed=internal)
1122. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(effective_destination_number=319000980)
1123. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
1124. Dialplan: sofia/default/1000@141826.yoursipcloud.com Action hangup(ORIGINATION_RATE_NOT_FOUND)
1125. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:287 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_ROUTING -> CS_EXECUTE
1126. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:644 (sofia/default/1000@141826.yoursipcloud.com) State ROUTING going to sleep
1127. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_EXECUTE (Cur 27 Tot 7227)
1128. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE
1129. 2022-12-16 11:39:39.287387 [DEBUG] mod_sofia.c:209 sofia/default/1000@141826.yoursipcloud.com SOFIA EXECUTE
1130. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:329 sofia/default/1000@141826.yoursipcloud.com Standard EXECUTE
1131. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com log(WARNING Accountcode 141826. Dialed number (319000980)  origination rates not found!!)
1132. 2022-12-16 11:39:39.287387 [WARNING] mod_dptools.c:1866 Accountcode 141826. Dialed number (319000980)  origination rates not found!!
1133. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_name=1000)
1134. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_name]=[1000]
1135. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(original_caller_id_number=1000)
1136. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [original_caller_id_number]=[1000]
1137. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(error_cdr=1)
1138. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [error_cdr]=[1]
1139. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(callstart=2022-12-16 10:39:39)
1140. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [callstart]=[2022-12-16 10:39:39]
1141. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(account_id=1178)
1142. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [account_id]=[1178]
1143. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(parent_id=12)
1144. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [parent_id]=[12]
1145. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_direction=outbound)
1146. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_direction]=[outbound]
1147. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(sip_ignore_remote_cause=true)
1148. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [sip_ignore_remote_cause]=[true]
1149. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(call_processed=internal)
1150. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [call_processed]=[internal]
1151. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(effective_destination_number=319000980)
1152. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [effective_destination_number]=[319000980]
1153. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
1154. 2022-12-16 11:39:39.287387 [DEBUG] mod_dptools.c:1672 SET sofia/default/1000@141826.yoursipcloud.com [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
1155. EXECUTE [depth=0] sofia/default/1000@141826.yoursipcloud.com hangup(ORIGINATION_RATE_NOT_FOUND)
1156. 2022-12-16 11:39:39.287387 [NOTICE] mod_dptools.c:1380 Hangup sofia/default/1000@141826.yoursipcloud.com [CS_EXECUTE] [NORMAL_CLEARING]
1157. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_session.c:2905 sofia/default/1000@141826.yoursipcloud.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1158. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:651 (sofia/default/1000@141826.yoursipcloud.com) State EXECUTE going to sleep
1159. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_HANGUP (Cur 27 Tot 7227)
1160. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:848 (sofia/default/1000@141826.yoursipcloud.com) Callstate Change RINGING -> HANGUP
1161. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP
1162. 2022-12-16 11:39:39.287387 [DEBUG] mod_sofia.c:453 Channel sofia/default/1000@141826.yoursipcloud.com hanging up, cause: NORMAL_CLEARING
1163. 2022-12-16 11:39:39.287387 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
1164. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:60 sofia/default/1000@141826.yoursipcloud.com Standard HANGUP, cause: NORMAL_CLEARING
1165. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:850 (sofia/default/1000@141826.yoursipcloud.com) State HANGUP going to sleep
1166. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:620 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_HANGUP -> CS_REPORTING
1167. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:585 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_REPORTING (Cur 27 Tot 7227)
1168. 2022-12-16 11:39:39.287387 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING
1169. 2022-12-16 11:39:39.287387 [INFO] mod_json_cdr.c:271 Process [d03b5e8f-d15c-4cc9-910e-3bcca4ed1d57.cdr.json]
1170. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:174 sofia/default/1000@141826.yoursipcloud.com Standard REPORTING, cause: NORMAL_CLEARING
1171. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:936 (sofia/default/1000@141826.yoursipcloud.com) State REPORTING going to sleep
1172. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:611 (sofia/default/1000@141826.yoursipcloud.com) State Change CS_REPORTING -> CS_DESTROY
1173. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_session.c:1726 Session 7227 (sofia/default/1000@141826.yoursipcloud.com) Locked, Waiting on external entities
1174. 2022-12-16 11:39:39.307380 [NOTICE] switch_core_session.c:1744 Session 7227 (sofia/default/1000@141826.yoursipcloud.com) Ended
1175. 2022-12-16 11:39:39.307380 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/1000@141826.yoursipcloud.com [CS_DESTROY]
1176. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:739 (sofia/default/1000@141826.yoursipcloud.com) Running State Change CS_DESTROY (Cur 26 Tot 7227)
1177. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY
1178. 2022-12-16 11:39:39.307380 [DEBUG] mod_sofia.c:364 sofia/default/1000@141826.yoursipcloud.com SOFIA DESTROY
1179. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:181 sofia/default/1000@141826.yoursipcloud.com Standard DESTROY
1180. 2022-12-16 11:39:39.307380 [DEBUG] switch_core_state_machine.c:749 (sofia/default/1000@141826.yoursipcloud.com) State DESTROY going to sleep
1181. 2022-12-16 11:39:42.107379 [WARNING] sofia_reg.c:1794 SIP auth challenge (REGISTER) on sofia profile 'default' for [202@00187517.yoursipcloud.com] from ip 89.255.40.6
1182. 2022-12-16 11:39:42.327386 [NOTICE] sofia.c:1089 Hangup sofia/default/205@192.168.178.20:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
1183. 2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/205@192.168.178.20:5060]
1184. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:654 (sofia/default/205@192.168.178.20:5060) State EXCHANGE_MEDIA going to sleep
1185. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/205@192.168.178.20:5060) Running State Change CS_HANGUP (Cur 26 Tot 7227)
1186. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:848 (sofia/default/205@192.168.178.20:5060) Callstate Change ACTIVE -> HANGUP
1187. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/205@192.168.178.20:5060) State HANGUP
1188. 2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:453 Channel sofia/default/205@192.168.178.20:5060 hanging up, cause: NORMAL_CLEARING
1189. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:60 sofia/default/205@192.168.178.20:5060 Standard HANGUP, cause: NORMAL_CLEARING
1190. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/205@192.168.178.20:5060) State HANGUP going to sleep
1191. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:620 (sofia/default/205@192.168.178.20:5060) State Change CS_HANGUP -> CS_REPORTING
1192. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/205@192.168.178.20:5060) Running State Change CS_REPORTING (Cur 26 Tot 7227)
1193. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:936 (sofia/default/205@192.168.178.20:5060) State REPORTING
1194. 2022-12-16 11:39:42.327386 [INFO] mod_json_cdr.c:271 Process [e6084f90-64c3-4a47-9731-977030d49c3d.cdr.json]
1195. 2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:820 sofia/default/205@192.168.178.20:5060 ending bridge by request from write function
1196. 2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/31628471807@185.249.40.253]
1197. 2022-12-16 11:39:42.327386 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/205@192.168.178.20:5060 skip receive message [UNBRIDGE] (channel is hungup already)
1198. 2022-12-16 11:39:42.327386 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/default/31628471807@185.249.40.253 [CS_EXECUTE] [NORMAL_CLEARING]
1199. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_session.c:2905 sofia/default/31628471807@185.249.40.253 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1200. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:651 (sofia/default/31628471807@185.249.40.253) State EXECUTE going to sleep
1201. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31628471807@185.249.40.253) Running State Change CS_HANGUP (Cur 26 Tot 7227)
1202. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:848 (sofia/default/31628471807@185.249.40.253) Callstate Change ACTIVE -> HANGUP
1203. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31628471807@185.249.40.253) State HANGUP
1204. 2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:447 sofia/default/31628471807@185.249.40.253 Overriding SIP cause 480 with 200 from the other leg
1205. 2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:453 Channel sofia/default/31628471807@185.249.40.253 hanging up, cause: NORMAL_CLEARING
1206. 2022-12-16 11:39:42.327386 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/default/31628471807@185.249.40.253
1207. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:60 sofia/default/31628471807@185.249.40.253 Standard HANGUP, cause: NORMAL_CLEARING
1208. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:850 (sofia/default/31628471807@185.249.40.253) State HANGUP going to sleep
1209. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:620 (sofia/default/31628471807@185.249.40.253) State Change CS_HANGUP -> CS_REPORTING
1210. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:585 (sofia/default/31628471807@185.249.40.253) Running State Change CS_REPORTING (Cur 26 Tot 7227)
1211. 2022-12-16 11:39:42.327386 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31628471807@185.249.40.253) State REPORTING
1212. 2022-12-16 11:39:42.327386 [INFO] mod_json_cdr.c:271 Process [09f1ec01-7fab-4d4d-b46e-ee100039709d.cdr.json]
1213. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:174 sofia/default/205@192.168.178.20:5060 Standard REPORTING, cause: NORMAL_CLEARING
1214. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:936 (sofia/default/205@192.168.178.20:5060) State REPORTING going to sleep
1215. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:611 (sofia/default/205@192.168.178.20:5060) State Change CS_REPORTING -> CS_DESTROY
1216. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_session.c:1726 Session 7079 (sofia/default/205@192.168.178.20:5060) Locked, Waiting on external entities
1217. 2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1744 Session 7079 (sofia/default/205@192.168.178.20:5060) Ended
1218. 2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/205@192.168.178.20:5060 [CS_DESTROY]
1219. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:739 (sofia/default/205@192.168.178.20:5060) Running State Change CS_DESTROY (Cur 25 Tot 7227)
1220. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/205@192.168.178.20:5060) State DESTROY
1221. 2022-12-16 11:39:42.347383 [DEBUG] mod_sofia.c:364 sofia/default/205@192.168.178.20:5060 SOFIA DESTROY
1222. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:181 sofia/default/205@192.168.178.20:5060 Standard DESTROY
1223. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/205@192.168.178.20:5060) State DESTROY going to sleep
1224. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:174 sofia/default/31628471807@185.249.40.253 Standard REPORTING, cause: NORMAL_CLEARING
1225. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:936 (sofia/default/31628471807@185.249.40.253) State REPORTING going to sleep
1226. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:611 (sofia/default/31628471807@185.249.40.253) State Change CS_REPORTING -> CS_DESTROY
1227. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_session.c:1726 Session 7078 (sofia/default/31628471807@185.249.40.253) Locked, Waiting on external entities
1228. 2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1744 Session 7078 (sofia/default/31628471807@185.249.40.253) Ended
1229. 2022-12-16 11:39:42.347383 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/31628471807@185.249.40.253 [CS_DESTROY]
1230. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:739 (sofia/default/31628471807@185.249.40.253) Running State Change CS_DESTROY (Cur 24 Tot 7227)
1231. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31628471807@185.249.40.253) State DESTROY
1232. 2022-12-16 11:39:42.347383 [DEBUG] mod_sofia.c:364 sofia/default/31628471807@185.249.40.253 SOFIA DESTROY
1233. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:181 sofia/default/31628471807@185.249.40.253 Standard DESTROY
1234. 2022-12-16 11:39:42.347383 [DEBUG] switch_core_state_machine.c:749 (sofia/default/31628471807@185.249.40.253) State DESTROY going to sleep
1235. 2022-12-16 11:39:42.687383 [DEBUG] switch_scheduler.c:147 Deleting task 1332 switch_ivr_schedule_hangup (e6084f90-64c3-4a47-9731-977030d49c3d)
1236. 2022-12-16 11:39:45.207441 [NOTICE] switch_channel.c:1118 New Channel sofia/default/183284@213.144.239.110:9933 [ff79c902-8124-4495-bf0a-c7efe83e3e6a]
1237. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_NEW (Cur 25 Tot 7228)
1238. 2022-12-16 11:39:45.207441 [DEBUG] sofia.c:10280 sofia/default/183284@213.144.239.110:9933 receiving invite from 213.144.239.110:9933 version: 1.10.5 -release-17-25569c1631 64bit
1239. 2022-12-16 11:39:45.207441 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 213.144.239.110:0.
1240. 2022-12-16 11:39:45.207441 [DEBUG] sofia.c:10403 IP 213.144.239.110 Approved by acl "default[]". Access Granted.
1241. 2022-12-16 11:39:45.207441 [DEBUG] sofia.c:7326 Channel sofia/default/183284@213.144.239.110:9933 entering state [received][100]
1242. 2022-12-16 11:39:45.207441 [DEBUG] sofia.c:7336 Remote SDP:
1243. v=0
1244. o=root 1803226258 1803226258 IN IP4 213.144.239.110
1245. s=Asterisk PBX 11.4.0
1246. c=IN IP4 213.144.239.110
1247. t=0 0
1248. m=audio 12472 RTP/AVP 0 8 101
1249. a=rtpmap:0 PCMU/8000
1250. a=rtpmap:8 PCMA/8000
1251. a=rtpmap:101 telephone-event/8000
1252. a=fmtp:101 0-16
1253. a=ptime:20
1254.  
1255. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1256. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1257. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
1258. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1259. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
1260. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1261. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
1262. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/183284@213.144.239.110:9933 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
1263. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_codec.c:111 sofia/default/183284@213.144.239.110:9933 Original read codec set to PCMU:0
1264. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
1265. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_media.c:5911 sofia/default/183284@213.144.239.110:9933 Set 2833 dtmf send payload to 101 recv payload to 101
1266. 2022-12-16 11:39:45.207441 [DEBUG] sofia.c:7760 (sofia/default/183284@213.144.239.110:9933) State Change CS_NEW -> CS_INIT
1267. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:604 (sofia/default/183284@213.144.239.110:9933) State NEW
1268. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_INIT (Cur 25 Tot 7228)
1269. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT
1270. 2022-12-16 11:39:45.207441 [DEBUG] mod_sofia.c:93 sofia/default/183284@213.144.239.110:9933 SOFIA INIT
1271. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:40 sofia/default/183284@213.144.239.110:9933 Standard INIT
1272. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:48 (sofia/default/183284@213.144.239.110:9933) State Change CS_INIT -> CS_ROUTING
1273. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:628 (sofia/default/183284@213.144.239.110:9933) State INIT going to sleep
1274. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:585 (sofia/default/183284@213.144.239.110:9933) Running State Change CS_ROUTING (Cur 25 Tot 7228)
1275. 2022-12-16 11:39:45.207441 [DEBUG] switch_channel.c:2332 (sofia/default/183284@213.144.239.110:9933) Callstate Change DOWN -> RINGING
1276. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:644 (sofia/default/183284@213.144.239.110:9933) State ROUTING
1277. 2022-12-16 11:39:45.207441 [DEBUG] mod_sofia.c:154 sofia/default/183284@213.144.239.110:9933 SOFIA ROUTING
1278. 2022-12-16 11:39:45.207441 [DEBUG] switch_core_state_machine.c:236 sofia/default/183284@213.144.239.110:9933 Standard ROUTING
1279. 2022-12-16 11:39:45.207441 [INFO] mod_dialplan_xml.c:637 Processing 31702007090 <31702007090>->31613388584 in context default
1280. 2022-12-16 11:39:45.207441 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
1281. 2022-12-16 11:39:45.207441 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1282. 2022-12-16 11:39:45.207441 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
1283. Event-Name: REQUEST_PARAMS
1284. Core-UUID: c687ffac-2ae7-42f3-9408-8ea92987c97f
1285. FreeSWITCH-Hostname: multitenant-1
1286. FreeSWITCH-Switchname: multitenant-1
1287. FreeSWITCH-IPv4: 185.249.40.236
1288. FreeSWITCH-IPv6: %3A%3A1
1289. Event-Date-Local: 2022-12-16%2011%3A39%3A45
1290. Event-Date-GMT: Fri,%2016%20Dec%202022%2010%3A39%3A45%20GMT
1291. Event-Date-Timestamp: 1671187185207441
1292. Event-Calling-File: mod_dialplan_xml.c
1293. Event-Calling-Function: dialplan_xml_locate
1294. Event-Calling-Line-Number: 608
1295. Event-Sequence: 640072
1296. Channel-State: CS_ROUTING
1297. Channel-Call-State: RINGING
1298. Channel-State-Number: 2
1299. Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
1300. Unique-ID: ff79c902-8124-4495-bf0a-c7efe83e3e6a
1301. Call-Direction: inbound
1302. Presence-Call-Direction: inbound
1303. Channel-HIT-Dialplan: true
1304. Channel-Presence-ID: 183284%40213.144.239.110
1305. Channel-Call-UUID: ff79c902-8124-4495-bf0a-c7efe83e3e6a
1306. Answer-State: ringing
1307. Channel-Read-Codec-Name: PCMU
1308. Channel-Read-Codec-Rate: 8000
1309. Channel-Read-Codec-Bit-Rate: 64000
1310. Channel-Write-Codec-Name: PCMU
1311. Channel-Write-Codec-Rate: 8000
1312. Channel-Write-Codec-Bit-Rate: 64000
1313. Caller-Direction: inbound
1314. Caller-Logical-Direction: inbound
1315. Caller-Username: 31702007090
1316. Caller-Dialplan: XML
1317. Caller-Caller-ID-Name: 31702007090
1318. Caller-Caller-ID-Number: 31702007090
1319. Caller-Orig-Caller-ID-Name: 31702007090
1320. Caller-Orig-Caller-ID-Number: 31702007090
1321. Caller-Network-Addr: 213.144.239.110
1322. Caller-ANI: 31702007090
1323. Caller-Destination-Number: 31613388584
1324. Caller-Unique-ID: ff79c902-8124-4495-bf0a-c7efe83e3e6a
1325. Caller-Source: mod_sofia
1326. Caller-Context: default
1327. Caller-Channel-Name: sofia/default/183284%40213.144.239.110%3A9933
1328. Caller-Profile-Index: 1
1329. Caller-Profile-Created-Time: 1671187185207441
1330. Caller-Channel-Created-Time: 1671187185207441
1331. Caller-Channel-Answered-Time: 0
1332. Caller-Channel-Progress-Time: 0
1333. Caller-Channel-Progress-Media-Time: 0
1334. Caller-Channel-Hangup-Time: 0
1335. Caller-Channel-Transfer-Time: 0
1336. Caller-Channel-Resurrect-Time: 0
1337. Caller-Channel-Bridged-Time: 0
1338. Caller-Channel-Last-Hold: 0
1339. Caller-Channel-Hold-Accum: 0
1340. Caller-Screen-Bit: false
1341. Caller-Privacy-Hide-Name: false
1342. Caller-Privacy-Hide-Number: false
1343. variable_direction: inbound
1344. variable_uuid: ff79c902-8124-4495-bf0a-c7efe83e3e6a
1345. variable_session_id: 7228
1346. variable_sip_from_user: 183284
1347. variable_sip_from_port: 9933
1348. variable_sip_from_uri: 183284%40213.144.239.110%3A9933
1349. variable_sip_from_host: 213.144.239.110
1350. variable_video_media_flow: disabled
1351. variable_text_media_flow: disabled
1352. variable_channel_name: sofia/default/183284%40213.144.239.110%3A9933
1353. variable_sip_call_id: 2e5842a2623b5d6b023c90d913bd0ae7%40213.144.239.110%3A9933
1354. variable_sip_local_network_addr: 185.249.40.236
1355. variable_sip_network_ip: 213.144.239.110
1356. variable_sip_network_port: 9933
1357. variable_sip_invite_stamp: 1671187185207441
1358. variable_sip_received_ip: 213.144.239.110
1359. variable_sip_received_port: 9933
1360. variable_sip_via_protocol: udp
1361. variable_sip_authorized: true
1362. variable_sip_acl_authed_by: default
1363. variable_sip_from_user_stripped: 183284
1364. variable_sip_from_tag: as46cc564e
1365. variable_sofia_profile_name: default
1366. variable_sofia_profile_url: sip%3Amod_sofia%40185.249.40.236%3A5060
1367. variable_recovery_profile_name: default
1368. variable_sip_Remote-Party-ID: %2231702007090%22%20%3Csip%3A31702007090%40213.144.239.110%3E%3Bparty%3Dcalling%3Bprivacy%3Doff%3Bscreen%3Dno
1369. variable_sip_cid_type: rpid
1370. variable_sip_full_via: SIP/2.0/UDP%20213.144.239.110%3A9933%3Bbranch%3Dz9hG4bK57ac30e7%3Brport%3D9933
1371. variable_sip_full_from: %3Csip%3A183284%40213.144.239.110%3A9933%3E%3Btag%3Das46cc564e
1372. variable_sip_full_to: %3Csip%3A31613388584%40emolife138.yoursipcloud.com%3E
1373. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
1374. variable_sip_req_user: 31613388584
1375. variable_sip_req_uri: 31613388584%40emolife138.yoursipcloud.com
1376. variable_sip_req_host: emolife138.yoursipcloud.com
1377. variable_sip_to_user: 31613388584
1378. variable_sip_to_uri: 31613388584%40emolife138.yoursipcloud.com
1379. variable_sip_to_host: emolife138.yoursipcloud.com
1380. variable_sip_contact_user: 183284
1381. variable_sip_contact_port: 9933
1382. variable_sip_contact_uri: 183284%40213.144.239.110%3A9933
1383. variable_sip_contact_host: 213.144.239.110
1384. variable_sip_user_agent: FPBX-2.11.0(11.4.0)
1385. variable_sip_via_host: 213.144.239.110
1386. variable_sip_via_port: 9933
1387. variable_sip_via_rport: 9933
1388. variable_max_forwards: 70
1389. variable_presence_id: 183284%40213.144.239.110
1390. variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201803226258%201803226258%20IN%20IP4%20213.144.239.110%0D%0As%3DAsterisk%20PBX%2011.4.0%0D%0Ac%3DIN%20IP4%20213.144.239.110%0D%0At%3D0%200%0D%0Am%3Daudio%2012472%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
1391. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
1392. variable_rtp_use_codec_string: PCMA,PCMU
1393. variable_remote_video_media_flow: inactive
1394. variable_remote_text_media_flow: inactive
1395. variable_remote_audio_media_flow: sendrecv
1396. variable_audio_media_flow: sendrecv
1397. variable_remote_media_ip: 213.144.239.110
1398. variable_remote_media_port: 12472
1399. variable_rtp_audio_recv_pt: 0
1400. variable_rtp_use_codec_name: PCMU
1401. variable_rtp_use_codec_rate: 8000
1402. variable_rtp_use_codec_ptime: 20
1403. variable_rtp_use_codec_channels: 1
1404. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
1405. variable_read_codec: PCMU
1406. variable_original_read_codec: PCMU
1407. variable_read_rate: 8000
1408. variable_original_read_rate: 8000
1409. variable_write_codec: PCMU
1410. variable_write_rate: 8000
1411. variable_dtmf_type: rfc2833
1412. variable_endpoint_disposition: RECEIVED
1413. variable_call_uuid: ff79c902-8124-4495-bf0a-c7efe83e3e6a
1414. Hunt-Direction: inbound
1415. Hunt-Logical-Direction: inbound
1416. Hunt-Username: 31702007090
1417. Hunt-Dialplan: XML
1418. Hunt-Caller-ID-Name: 31702007090
1419. Hunt-Caller-ID-Number: 31702007090
1420. Hunt-Orig-Caller-ID-Name: 31702007090
1421. Hunt-Orig-Caller-ID-Number: 31702007090
1422. Hunt-Network-Addr: 213.144.239.110