From RG-PSTN, 3 Years ago, written in FreeSWITCH.
- view diff
Embed Show code
  1. freeswitch@ip-172-31-23-227>
  2. freeswitch@ip-172-31-23-227>
  3. freeswitch@ip-172-31-23-227>
  4. 2021-02-22 10:18:39.304987 [NOTICE] switch_channel.c:1118 New Channel sofia/default/4000@13.127.159.248 [6aa87d48-d0be-4439-88a6-aa89e09dbe0c]
  5. 2021-02-22 10:18:39.304987 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_NEW (Cur 1 Tot 385)
  6. 2021-02-22 10:18:39.304987 [DEBUG] sofia.c:10280 sofia/default/4000@13.127.159.248 receiving invite from 13.127.159.248:5060 version: 1.10.5 -release-17-25569c1631 64bit
  7. 2021-02-22 10:18:39.324966 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 13.127.159.248:0.
  8. 2021-02-22 10:18:39.324966 [DEBUG] sofia.c:10403 IP 13.127.159.248 Approved by acl "default[]". Access Granted.
  9. 2021-02-22 10:18:39.324966 [DEBUG] sofia.c:7326 Channel sofia/default/4000@13.127.159.248 entering state [received][100]
  10. 2021-02-22 10:18:39.324966 [DEBUG] sofia.c:7336 Remote SDP:
  11. v=0
  12. o=FreeSWITCH 1613965557 1613965558 IN IP4 13.127.159.248
  13. s=FreeSWITCH
  14. c=IN IP4 13.127.159.248
  15. t=0 0
  16. m=audio 23562 RTP/AVP 8 0 18 101
  17. a=rtpmap:8 PCMA/8000
  18. a=rtpmap:0 PCMU/8000
  19. a=rtpmap:18 G729/8000
  20. a=rtpmap:101 telephone-event/8000
  21. a=fmtp:101 0-16
  22. a=ptime:20
  23.  
  24. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  25. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  26. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  27. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  28. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  29. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  30. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  31. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  32. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
  33. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/4000@13.127.159.248 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  34. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_codec.c:111 sofia/default/4000@13.127.159.248 Original read codec set to PCMA:8
  35. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
  36. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5911 sofia/default/4000@13.127.159.248 Set 2833 dtmf send payload to 101 recv payload to 101
  37. 2021-02-22 10:18:39.324966 [DEBUG] sofia.c:7760 (sofia/default/4000@13.127.159.248) State Change CS_NEW -> CS_INIT
  38. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:604 (sofia/default/4000@13.127.159.248) State NEW
  39. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_INIT (Cur 1 Tot 385)
  40. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:628 (sofia/default/4000@13.127.159.248) State INIT
  41. 2021-02-22 10:18:39.324966 [DEBUG] mod_sofia.c:93 sofia/default/4000@13.127.159.248 SOFIA INIT
  42. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:40 sofia/default/4000@13.127.159.248 Standard INIT
  43. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:48 (sofia/default/4000@13.127.159.248) State Change CS_INIT -> CS_ROUTING
  44. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:628 (sofia/default/4000@13.127.159.248) State INIT going to sleep
  45. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_ROUTING (Cur 1 Tot 385)
  46. 2021-02-22 10:18:39.324966 [DEBUG] switch_channel.c:2332 (sofia/default/4000@13.127.159.248) Callstate Change DOWN -> RINGING
  47. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:644 (sofia/default/4000@13.127.159.248) State ROUTING
  48. 2021-02-22 10:18:39.324966 [DEBUG] mod_sofia.c:154 sofia/default/4000@13.127.159.248 SOFIA ROUTING
  49. 2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:236 sofia/default/4000@13.127.159.248 Standard ROUTING
  50. 2021-02-22 10:18:39.324966 [INFO] mod_dialplan_xml.c:637 Processing 4000 <4000>->123456 in context default
  51. 2021-02-22 10:18:39.364967 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  52. 2021-02-22 10:18:39.364967 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  53. 2021-02-22 10:18:39.384964 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
  54. Event-Name: REQUEST_PARAMS
  55. Core-UUID: 22268e51-3187-474a-b82e-9ed977c04631
  56. FreeSWITCH-Hostname: ip-172-31-23-227
  57. FreeSWITCH-Switchname: ip-172-31-23-227
  58. FreeSWITCH-IPv4: 172.31.23.227
  59. FreeSWITCH-IPv6: %3A%3A1
  60. Event-Date-Local: 2021-02-22%2010%3A18%3A39
  61. Event-Date-GMT: Mon,%2022%20Feb%202021%2010%3A18%3A39%20GMT
  62. Event-Date-Timestamp: 1613989119324966
  63. Event-Calling-File: mod_dialplan_xml.c
  64. Event-Calling-Function: dialplan_xml_locate
  65. Event-Calling-Line-Number: 608
  66. Event-Sequence: 357714
  67. Channel-State: CS_ROUTING
  68. Channel-Call-State: RINGING
  69. Channel-State-Number: 2
  70. Channel-Name: sofia/default/4000%4013.127.159.248
  71. Unique-ID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
  72. Call-Direction: inbound
  73. Presence-Call-Direction: inbound
  74. Channel-HIT-Dialplan: true
  75. Channel-Presence-ID: 4000%4013.127.159.248
  76. Channel-Call-UUID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
  77. Answer-State: ringing
  78. Channel-Read-Codec-Name: PCMA
  79. Channel-Read-Codec-Rate: 8000
  80. Channel-Read-Codec-Bit-Rate: 64000
  81. Channel-Write-Codec-Name: PCMA
  82. Channel-Write-Codec-Rate: 8000
  83. Channel-Write-Codec-Bit-Rate: 64000
  84. Caller-Direction: inbound
  85. Caller-Logical-Direction: inbound
  86. Caller-Username: 4000
  87. Caller-Dialplan: XML
  88. Caller-Caller-ID-Name: 4000
  89. Caller-Caller-ID-Number: 4000
  90. Caller-Orig-Caller-ID-Name: 4000
  91. Caller-Orig-Caller-ID-Number: 4000
  92. Caller-Network-Addr: 13.127.159.248
  93. Caller-ANI: 4000
  94. Caller-Destination-Number: 123456
  95. Caller-Unique-ID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
  96. Caller-Source: mod_sofia
  97. Caller-Context: default
  98. Caller-Channel-Name: sofia/default/4000%4013.127.159.248
  99. Caller-Profile-Index: 1
  100. Caller-Profile-Created-Time: 1613989119324966
  101. Caller-Channel-Created-Time: 1613989119324966
  102. Caller-Channel-Answered-Time: 0
  103. Caller-Channel-Progress-Time: 0
  104. Caller-Channel-Progress-Media-Time: 0
  105. Caller-Channel-Hangup-Time: 0
  106. Caller-Channel-Transfer-Time: 0
  107. Caller-Channel-Resurrect-Time: 0
  108. Caller-Channel-Bridged-Time: 0
  109. Caller-Channel-Last-Hold: 0
  110. Caller-Channel-Hold-Accum: 0
  111. Caller-Screen-Bit: true
  112. Caller-Privacy-Hide-Name: false
  113. Caller-Privacy-Hide-Number: false
  114. variable_direction: inbound
  115. variable_uuid: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
  116. variable_session_id: 385
  117. variable_sip_from_user: 4000
  118. variable_sip_from_uri: 4000%4013.127.159.248
  119. variable_sip_from_host: 13.127.159.248
  120. variable_video_media_flow: disabled
  121. variable_text_media_flow: disabled
  122. variable_channel_name: sofia/default/4000%4013.127.159.248
  123. variable_sip_call_id: 2c185857-ef9a-1239-f6ab-029485f1bd1c
  124. variable_sip_local_network_addr: 18.221.51.90
  125. variable_sip_network_ip: 13.127.159.248
  126. variable_sip_network_port: 5060
  127. variable_sip_invite_stamp: 1613989119304987
  128. variable_sip_received_ip: 13.127.159.248
  129. variable_sip_received_port: 5060
  130. variable_sip_via_protocol: udp
  131. variable_sip_authorized: true
  132. variable_sip_acl_authed_by: default
  133. variable_sip_from_user_stripped: 4000
  134. variable_sip_from_tag: U4rBN5B6gUpNc
  135. variable_sofia_profile_name: default
  136. variable_sofia_profile_url: sip%3Amod_sofia%4018.221.51.90%3A7777
  137. variable_recovery_profile_name: default
  138. variable_sip_Remote-Party-ID: %224000%22%20%3Csip%3A4000%4013.127.159.248%3E%3Bparty%3Dcalling%3Bscreen%3Dyes%3Bprivacy%3Doff
  139. variable_sip_cid_type: rpid
  140. variable_sip_full_via: SIP/2.0/UDP%2013.127.159.248%3Brport%3D5060%3Bbranch%3Dz9hG4bK09gH20cD63XBH
  141. variable_sip_from_display: 4000
  142. variable_sip_full_from: %224000%22%20%3Csip%3A4000%4013.127.159.248%3E%3Btag%3DU4rBN5B6gUpNc
  143. variable_sip_full_to: %3Csip%3A123456%4018.221.51.90%3A7777%3E
  144. variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20OPTIONS,%20MESSAGE,%20INFO,%20UPDATE,%20REGISTER,%20REFER,%20NOTIFY,%20PUBLISH,%20SUBSCRIBE
  145. variable_sip_req_user: 123456
  146. variable_sip_req_port: 7777
  147. variable_sip_req_uri: 123456%4018.221.51.90%3A7777
  148. variable_sip_req_host: 18.221.51.90
  149. variable_sip_to_user: 123456
  150. variable_sip_to_port: 7777
  151. variable_sip_to_uri: 123456%4018.221.51.90%3A7777
  152. variable_sip_to_host: 18.221.51.90
  153. variable_sip_contact_params: transport%3Dudp%3Bgw%3DDhaval_gt
  154. variable_sip_contact_user: gw%2BDhaval_gt
  155. variable_sip_contact_port: 5060
  156. variable_sip_contact_uri: gw%2BDhaval_gt%4013.127.159.248%3A5060
  157. variable_sip_contact_host: 13.127.159.248
  158. variable_sip_user_agent: ASTPP
  159. variable_sip_via_host: 13.127.159.248
  160. variable_sip_via_rport: 5060
  161. variable_max_forwards: 69
  162. variable_presence_id: 4000%4013.127.159.248
  163. variable_switch_r_sdp: v%3D0%0D%0Ao%3DFreeSWITCH%201613965557%201613965558%20IN%20IP4%2013.127.159.248%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2013.127.159.248%0D%0At%3D0%200%0D%0Am%3Daudio%2023562%20RTP/AVP%208%200%2018%20101%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
  164. variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  165. variable_rtp_use_codec_string: PCMA,PCMU
  166. variable_remote_video_media_flow: inactive
  167. variable_remote_text_media_flow: inactive
  168. variable_remote_audio_media_flow: sendrecv
  169. variable_audio_media_flow: sendrecv
  170. variable_remote_media_ip: 13.127.159.248
  171. variable_remote_media_port: 23562
  172. variable_rtp_audio_recv_pt: 8
  173. variable_rtp_use_codec_name: PCMA
  174. variable_rtp_use_codec_rate: 8000
  175. variable_rtp_use_codec_ptime: 20
  176. variable_rtp_use_codec_channels: 1
  177. variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
  178. variable_read_codec: PCMA
  179. variable_original_read_codec: PCMA
  180. variable_read_rate: 8000
  181. variable_original_read_rate: 8000
  182. variable_write_codec: PCMA
  183. variable_write_rate: 8000
  184. variable_dtmf_type: rfc2833
  185. variable_endpoint_disposition: RECEIVED
  186. variable_call_uuid: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
  187. Hunt-Direction: inbound
  188. Hunt-Logical-Direction: inbound
  189. Hunt-Username: 4000
  190. Hunt-Dialplan: XML
  191. Hunt-Caller-ID-Name: 4000
  192. Hunt-Caller-ID-Number: 4000
  193. Hunt-Orig-Caller-ID-Name: 4000
  194. Hunt-Orig-Caller-ID-Number: 4000
  195. Hunt-Network-Addr: 13.127.159.248
  196. Hunt-ANI: 4000
  197. Hunt-Destination-Number: 123456
  198. Hunt-Unique-ID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
  199. Hunt-Source: mod_sofia
  200. Hunt-Context: default
  201. Hunt-Channel-Name: sofia/default/4000%4013.127.159.248
  202. Hunt-Profile-Index: 1
  203. Hunt-Profile-Created-Time: 1613989119324966
  204. Hunt-Channel-Created-Time: 1613989119324966
  205. Hunt-Channel-Answered-Time: 0
  206. Hunt-Channel-Progress-Time: 0
  207. Hunt-Channel-Progress-Media-Time: 0
  208. Hunt-Channel-Hangup-Time: 0
  209. Hunt-Channel-Transfer-Time: 0
  210. Hunt-Channel-Resurrect-Time: 0
  211. Hunt-Channel-Bridged-Time: 0
  212. Hunt-Channel-Last-Hold: 0
  213. Hunt-Channel-Hold-Accum: 0
  214. Hunt-Screen-Bit: true
  215. Hunt-Privacy-Hide-Name: false
  216. Hunt-Privacy-Hide-Number: false
  217.  
  218.  
  219. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value:
  220. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
  221. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name:
  222. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name:
  223. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] blind_flag: 0
  224. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 123456
  225. 2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '123456' limit 1
  226. 2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '123456' AND status=0 limit 1
  227. 2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '123456' and status=0 LIMIT 1
  228. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 4000 / 4000
  229. 2021-02-22 10:18:39.384964 [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 ="123456" LIMIT 1
  230. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : inbound
  231. 2021-02-22 10:18:39.384964 [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("13.127.159.248") 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 "123456"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  232. 2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 3673942894]
  233. 2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "3673942894" AND deleted = 0 limit 1
  234. 2021-02-22 10:18:39.404965 [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 ="123456" AND A.accountid = '3' limit 1
  235. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 123456
  236. 2021-02-22 10:18:39.404965 [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 ="123456" LIMIT 1
  237. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call direction : inbound
  238. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="123456" AND (accountid = 0 OR status = 1) LIMIT 1
  239. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
  240. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :4
  241. 2021-02-22 10:18:39.404965 [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 (patterns = '^123456.*' OR patterns = '^12345.*' OR patterns = '^1234.*' OR patterns = '^123.*' OR patterns = '^12.*' OR patterns = '^1.*' OR patterns ='--') AND accountid = 4 ORDER BY LENGTH(PKGPTR.patterns) DESC
  242. 2021-02-22 10:18:39.404965 [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 = '123456' OR number = '12345' OR number = '1234' OR number = '123' OR number = '12' OR number = '1' OR number ='--') OR (number = '123456*' OR number = '12345*' OR number = '1234*' OR number = '123*' OR number = '12*' OR number = '1*' OR number ='--') OR (number = '4000' OR number = '400' OR number = '40' OR number = '4' OR number ='--') OR (number = '4000*' OR number = '400*' OR number = '40*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  243. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
  244. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '123456' OR routing_prefix = '12345' OR routing_prefix = '1234' OR routing_prefix = '123' OR routing_prefix = '12' OR routing_prefix = '1' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  245. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
  246. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 3
  247. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3673942894
  248. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 10000
  249. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  250. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 1
  251. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
  252. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
  253. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  254. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :inbound
  255. 2021-02-22 10:18:39.404965 [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 ="123456" LIMIT 1
  256. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] call_direction:::::: inbound
  257. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
  258. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 5
  259. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
  260. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0
  261. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.00000
  262. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] comment : 123456
  263. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 85
  264. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 3
  265. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
  266. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 3 !!!
  267. 2021-02-22 10:18:39.404965 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  268. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Max length duration : 100 minutes
  269. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] INB_FREE:TRUE
  270. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] free_inbound:1
  271. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 3 AND status=0 LIMIT 1
  272. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "4" AND deleted = 0 limit 1
  273. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Actual CustomerInfo XML:3
  274. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Userinfo XML:3
  275. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Actual CustomerInfo XML : 3
  276. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 4 limit 1
  277. 2021-02-22 10:18:39.404965 [WARNING] switch_cpp.cpp:1447 [ASTPP] [user_domain] user_domaindhaval.dhaval.astppbilling.org
  278. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] maxlength::::::::: 100
  279. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  280. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] customer_userinfo['is_recording'] : 1
  281. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] call_direction : inbound
  282. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing  WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "1" AND accounts.status = 0
  283. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 4 limit 1
  284. 2021-02-22 10:18:39.404965 [WARNING] switch_cpp.cpp:1447 [ASTPP] [user_domain] user_domaindhaval.dhaval.astppbilling.org
  285. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_7
  286. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] call_type : 7
  287. 2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] call_type : Ring group
  288. 2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT id as ringgroup_id,name as ringgroup_name,strategy as ringgroup_strategy,destinations as ringgroup_destinations,description as ringgroup_description,announcementid as ringgroup_announcementid,call_record as ringgroup_call_record,skip_busy as ringgroup_skip_busy,ringbackid as ringgroup_ringbackid,calleridname as ringgroup_calleridname,calleridnumber as ringgroup_calleridnumber,no_answer as ringgroup_no_answer,no_answer_call_type as no_answer_call_type,no_answer_call_type_value from pbx_ringgroup WHERE id = 1 and status = 0 LIMIT 1
  289. 2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== ringgroup_dlr_str h========Count==========={"destination_1":"91234355#","delay_1":"0","time_out_1":"5","Promptdropdown_1":"1"}
  290. span style="color: green; font-weight: bold;"> 2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== announcement====Count===============4
  291. 2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== announcement===================0
  292. 2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== final_count========final_count===========0===
  293. 2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== ring_group_recording===================0
  294. 2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== ringgroup_dlr_str===================[sip_h_p-call_type='custom_forward',sip_h_P-Accountcode=3,call_timeout=5,confirm=false,announcement=no_file,dialed_user=91234355#,leg_timeout=5,leg_delay_start=0]sofia/${sofia_profile_name}/91234355@dhaval.dhaval.astppbilling.org,
  295. span style="color: green; font-weight: bold;"> 2021-02-22 10:18:39.424966 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '' LIMIT 1
  296. 2021-02-22 10:18:39.424966 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  297. <document type="freeswitch/xml">
  298. <section name="dialplan" description="ASTPP Dialplan">
  299. <context name="default">
  300. <extension name="123456">
  301. <condition field="destination_number" expression="123456">
  302. <action application="set" data="effective_destination_number=123456"/>
  303. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  304. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  305. <action application="set" data="callstart=2021-02-22 10:18:39"/>
  306. <action application="set" data="hangup_after_bridge=true"/>
  307. <action application="set" data="continue_on_fail=TRUE"/>
  308. <action application="set" data="account_id=3"/>
  309. <action application="set" data="parent_id=0"/>
  310. <action application="set" data="entity_id=3"/>
  311. <action application="set" data="call_processed=internal"/>
  312. <action application="set" data="call_direction=inbound"/>
  313. <action application="set" data="accountname=Tinku bhai"/>
  314. <action application="set" data="origination_rates_did=ID:5|CODE:^123456.*|DESTINATION:123456|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:85|ACCID:4"/>
  315. <action application="set" data="origination_rates=0"/>
  316. <action application="set" data="original_caller_id_name=4000"/>
  317. <action application="set" data="original_caller_id_number=4000"/>
  318. <action application="set" data="effective_caller_id_name=4000"/>
  319. <action application="set" data="effective_caller_id_number=4000"/>
  320. <action application="set" data="receiver_accid=4"/>
  321. <action application="export" data="domain_name=dhaval.dhaval.astppbilling.org"/>
  322. <action application="export" data="sip_to_host=dhaval.dhaval.astppbilling.org"/>
  323. <action application="export" data="presence_data=x|||default(3673942894)|||^123456.* // 123456 // 0.00000||||||DID|||13.127.159.248"/>
  324. <action application="export" data="call_type=7"/>
  325. <action application="set" data="did_calltype=7"/>
  326. <action application="set" data="did_extensions=1"/>
  327. <action application="set" data="group_confirm_key=exec"/>
  328. <action application="set" data="group_confirm_file=lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua"/>
  329. <action application="export" data="is_recording=1"/>
  330. <action application="export" data="media_bug_answer_req=true"/>
  331. <action application="export" data="RECORD_STEREO=true"/>
  332. <action application="export" data="record_sample_rate=8000"/>
  333. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
  334. <action application="set" data="ringback="/>
  335. <action application="set" data="module_name=pbx"/>
  336. <action application="bridge" data="{sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=3}[sip_h_p-call_type='custom_forward',sip_h_P-Accountcode=3,call_timeout=5,confirm=false,announcement=no_file,dialed_user=91234355#,leg_timeout=5,leg_delay_start=0]sofia/${sofia_profile_name}/91234355@dhaval.dhaval.astppbilling.org,"/>
  337. <action application="set" data="original_did_number=123456"/>
  338. </condition>
  339. </extension>
  340. </context>
  341. </section>
  342. </document>
  343. Dialplan: sofia/default/4000@13.127.159.248 parsing [default->123456] continue=false
  344. Dialplan: sofia/default/4000@13.127.159.248 Regex (PASS) [123456] destination_number(123456) =~ /123456/ break=on-false
  345. Dialplan: sofia/default/4000@13.127.159.248 Action set(effective_destination_number=123456)
  346. Dialplan: sofia/default/4000@13.127.159.248 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  347. Dialplan: sofia/default/4000@13.127.159.248 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  348. Dialplan: sofia/default/4000@13.127.159.248 Action set(callstart=2021-02-22 10:18:39)
  349. Dialplan: sofia/default/4000@13.127.159.248 Action set(hangup_after_bridge=true)
  350. Dialplan: sofia/default/4000@13.127.159.248 Action set(continue_on_fail=TRUE)
  351. Dialplan: sofia/default/4000@13.127.159.248 Action set(account_id=3)
  352. Dialplan: sofia/default/4000@13.127.159.248 Action set(parent_id=0)
  353. Dialplan: sofia/default/4000@13.127.159.248 Action set(entity_id=3)
  354. Dialplan: sofia/default/4000@13.127.159.248 Action set(call_processed=internal)
  355. Dialplan: sofia/default/4000@13.127.159.248 Action set(call_direction=inbound)
  356. Dialplan: sofia/default/4000@13.127.159.248 Action set(accountname=Tinku bhai)
  357. Dialplan: sofia/default/4000@13.127.159.248 Action set(origination_rates_did=ID:5|CODE:^123456.*|DESTINATION:123456|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:85|ACCID:4)
  358. Dialplan: sofia/default/4000@13.127.159.248 Action set(origination_rates=0)
  359. Dialplan: sofia/default/4000@13.127.159.248 Action set(original_caller_id_name=4000)
  360. Dialplan: sofia/default/4000@13.127.159.248 Action set(original_caller_id_number=4000)
  361. Dialplan: sofia/default/4000@13.127.159.248 Action set(effective_caller_id_name=4000)
  362. Dialplan: sofia/default/4000@13.127.159.248 Action set(effective_caller_id_number=4000)
  363. Dialplan: sofia/default/4000@13.127.159.248 Action set(receiver_accid=4)
  364. Dialplan: sofia/default/4000@13.127.159.248 Action export(domain_name=dhaval.dhaval.astppbilling.org)
  365. Dialplan: sofia/default/4000@13.127.159.248 Action export(sip_to_host=dhaval.dhaval.astppbilling.org)
  366. Dialplan: sofia/default/4000@13.127.159.248 Action export(presence_data=x|||default(3673942894)|||^123456.* // 123456 // 0.00000||||||DID|||13.127.159.248)
  367. Dialplan: sofia/default/4000@13.127.159.248 Action export(call_type=7)
  368. Dialplan: sofia/default/4000@13.127.159.248 Action set(did_calltype=7)
  369. Dialplan: sofia/default/4000@13.127.159.248 Action set(did_extensions=1)
  370. Dialplan: sofia/default/4000@13.127.159.248 Action set(group_confirm_key=exec)
  371. Dialplan: sofia/default/4000@13.127.159.248 Action set(group_confirm_file=lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
  372. Dialplan: sofia/default/4000@13.127.159.248 Action export(is_recording=1)
  373. Dialplan: sofia/default/4000@13.127.159.248 Action export(media_bug_answer_req=true)
  374. Dialplan: sofia/default/4000@13.127.159.248 Action export(RECORD_STEREO=true)
  375. Dialplan: sofia/default/4000@13.127.159.248 Action export(record_sample_rate=8000)
  376. Dialplan: sofia/default/4000@13.127.159.248 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
  377. Dialplan: sofia/default/4000@13.127.159.248 Action set(ringback=)
  378. Dialplan: sofia/default/4000@13.127.159.248 Action set(module_name=pbx)
  379. Dialplan: sofia/default/4000@13.127.159.248 Action bridge({sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=3}[sip_h_p-call_type='custom_forward',sip_h_P-Accountcode=3,call_timeout=5,confirm=false,announcement=no_file,dialed_user=91234355#,leg_timeout=5,leg_delay_start=0]sofia/${sofia_profile_name}/91234355@dhaval.dhaval.astppbilling.org,)
  380. Dialplan: sofia/default/4000@13.127.159.248 Action set(original_did_number=123456)
  381. 2021-02-22 10:18:39.424966 [DEBUG] switch_core_state_machine.c:287 (sofia/default/4000@13.127.159.248) State Change CS_ROUTING -> CS_EXECUTE
  382. 2021-02-22 10:18:39.424966 [DEBUG] switch_core_state_machine.c:644 (sofia/default/4000@13.127.159.248) State ROUTING going to sleep
  383. 2021-02-22 10:18:39.424966 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_EXECUTE (Cur 1 Tot 385)
  384. 2021-02-22 10:18:39.424966 [DEBUG] switch_core_state_machine.c:651 (sofia/default/4000@13.127.159.248) State EXECUTE
  385. 2021-02-22 10:18:39.424966 [DEBUG] mod_sofia.c:209 sofia/default/4000@13.127.159.248 SOFIA EXECUTE
  386. 2021-02-22 10:18:39.424966 [DEBUG] switch_core_state_machine.c:329 sofia/default/4000@13.127.159.248 Standard EXECUTE
  387. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(effective_destination_number=123456)
  388. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [effective_destination_number]=[123456]
  389. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(bridge_pre_execute_bleg_app=sched_hangup)
  390. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [bridge_pre_execute_bleg_app]=[sched_hangup]
  391. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  392. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  393. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(callstart=2021-02-22 10:18:39)
  394. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [callstart]=[2021-02-22 10:18:39]
  395. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(hangup_after_bridge=true)
  396. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [hangup_after_bridge]=[true]
  397. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(continue_on_fail=TRUE)
  398. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [continue_on_fail]=[TRUE]
  399. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(account_id=3)
  400. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [account_id]=[3]
  401. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(parent_id=0)
  402. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [parent_id]=[0]
  403. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(entity_id=3)
  404. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [entity_id]=[3]
  405. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(call_processed=internal)
  406. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [call_processed]=[internal]
  407. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(call_direction=inbound)
  408. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [call_direction]=[inbound]
  409. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(accountname=Tinku bhai)
  410. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [accountname]=[Tinku bhai]
  411. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(origination_rates_did=ID:5|CODE:^123456.*|DESTINATION:123456|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:85|ACCID:4)
  412. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [origination_rates_did]=[ID:5|CODE:^123456.*|DESTINATION:123456|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:85|ACCID:4]
  413. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(origination_rates=0)
  414. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [origination_rates]=[0]
  415. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(original_caller_id_name=4000)
  416. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [original_caller_id_name]=[4000]
  417. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(original_caller_id_number=4000)
  418. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [original_caller_id_number]=[4000]
  419. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(effective_caller_id_name=4000)
  420. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [effective_caller_id_name]=[4000]
  421. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(effective_caller_id_number=4000)
  422. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [effective_caller_id_number]=[4000]
  423. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(receiver_accid=4)
  424. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [receiver_accid]=[4]
  425. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(domain_name=dhaval.dhaval.astppbilling.org)
  426. 2021-02-22 10:18:39.424966 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[dhaval.dhaval.astppbilling.org]
  427. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(sip_to_host=dhaval.dhaval.astppbilling.org)
  428. 2021-02-22 10:18:39.424966 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [sip_to_host]=[dhaval.dhaval.astppbilling.org]
  429. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(presence_data=x|||default(3673942894)|||^123456.* // 123456 // 0.00000||||||DID|||13.127.159.248)
  430. 2021-02-22 10:18:39.424966 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||default(3673942894)|||^123456.* // 123456 // 0.00000||||||DID|||13.127.159.248]
  431. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(call_type=7)
  432. 2021-02-22 10:18:39.424966 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_type]=[7]
  433. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(did_calltype=7)
  434. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [did_calltype]=[7]
  435. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(did_extensions=1)
  436. 2021-02-22 10:18:39.424966 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [did_extensions]=[1]
  437. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(group_confirm_key=exec)
  438. 2021-02-22 10:18:39.444962 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [group_confirm_key]=[exec]
  439. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(group_confirm_file=lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
  440. 2021-02-22 10:18:39.444962 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua]
  441. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(is_recording=1)
  442. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [is_recording]=[1]
  443. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(media_bug_answer_req=true)
  444. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [media_bug_answer_req]=[true]
  445. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(RECORD_STEREO=true)
  446. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RECORD_STEREO]=[true]
  447. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(record_sample_rate=8000)
  448. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [record_sample_rate]=[8000]
  449. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/6aa87d48-d0be-4439-88a6-aa89e09dbe0c.wav)
  450. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/6aa87d48-d0be-4439-88a6-aa89e09dbe0c.wav]
  451. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(ringback=)
  452. 2021-02-22 10:18:39.444962 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [ringback]=[UNDEF]
  453. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(module_name=pbx)
  454. 2021-02-22 10:18:39.444962 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [module_name]=[pbx]
  455. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 bridge({sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=3}[sip_h_p-call_type='custom_forward',sip_h_P-Accountcode=3,call_timeout=5,confirm=false,announcement=no_file,dialed_user=91234355#,leg_timeout=5,leg_delay_start=0]sofia/default/91234355@dhaval.dhaval.astppbilling.org,)
  456. span style="color: goldenrod; font-weight: bold;"> 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [domain_name]=[dhaval.dhaval.astppbilling.org] to event
  457. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [sip_to_host]=[dhaval.dhaval.astppbilling.org] to event
  458. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [presence_data]=[x|||default(3673942894)|||^123456.* // 123456 // 0.00000||||||DID|||13.127.159.248] to event
  459. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [call_type]=[7] to event
  460. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [is_recording]=[1] to event
  461. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
  462. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
  463. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
  464. 2021-02-22 10:18:39.444962 [DEBUG] switch_channel.c:1264 sofia/default/4000@13.127.159.248 EXPORTING[export_vars] [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/6aa87d48-d0be-4439-88a6-aa89e09dbe0c.wav] to event
  465. 2021-02-22 10:18:39.444962 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
  466. 2021-02-22 10:18:39.444962 [DEBUG] switch_ivr_originate.c:2804 Parsing session specific variables
  467. 2021-02-22 10:18:39.444962 [NOTICE] switch_channel.c:1118 New Channel sofia/default/91234355@dhaval.dhaval.astppbilling.org [d083a1e4-0d73-4108-a230-e2324ad8b285]
  468. 2021-02-22 10:18:39.444962 [DEBUG] mod_sofia.c:5089 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State Change CS_NEW -> CS_INIT
  469. 2021-02-22 10:18:39.444962 [DEBUG] switch_ivr_originate.c:3111 sofia/default/91234355@dhaval.dhaval.astppbilling.org Setting leg timeout to 5
  470. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:585 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Running State Change CS_INIT (Cur 2 Tot 386)
  471. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:628 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State INIT
  472. 2021-02-22 10:18:39.444962 [DEBUG] mod_sofia.c:93 sofia/default/91234355@dhaval.dhaval.astppbilling.org SOFIA INIT
  473. 2021-02-22 10:18:39.444962 [DEBUG] sofia_glue.c:1618 sofia/default/91234355@dhaval.dhaval.astppbilling.org sending invite version: 1.10.5 -release-17-25569c1631 64bit
  474. Local SDP:
  475. v=0
  476. o=FreeSWITCH 1613966853 1613966854 IN IP4 18.221.51.90
  477. s=FreeSWITCH
  478. c=IN IP4 18.221.51.90
  479. t=0 0
  480. m=audio 22266 RTP/AVP 8 101
  481. a=rtpmap:8 PCMA/8000
  482. a=rtpmap:101 telephone-event/8000
  483. a=fmtp:101 0-16
  484. a=ptime:20
  485. a=sendrecv
  486.  
  487. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:40 sofia/default/91234355@dhaval.dhaval.astppbilling.org Standard INIT
  488. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:48 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State Change CS_INIT -> CS_ROUTING
  489. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:628 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State INIT going to sleep
  490. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:585 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Running State Change CS_ROUTING (Cur 2 Tot 386)
  491. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:644 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State ROUTING
  492. 2021-02-22 10:18:39.444962 [DEBUG] mod_sofia.c:154 sofia/default/91234355@dhaval.dhaval.astppbilling.org SOFIA ROUTING
  493. 2021-02-22 10:18:39.444962 [DEBUG] switch_ivr_originate.c:67 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  494. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:644 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State ROUTING going to sleep
  495. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:585 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 386)
  496. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:663 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State CONSUME_MEDIA
  497. 2021-02-22 10:18:39.444962 [DEBUG] switch_core_state_machine.c:663 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State CONSUME_MEDIA going to sleep
  498. 2021-02-22 10:18:39.444962 [DEBUG] sofia.c:7326 Channel sofia/default/91234355@dhaval.dhaval.astppbilling.org entering state [calling][0]
  499. 2021-02-22 10:18:42.084967 [DEBUG] sofia.c:7326 Channel sofia/default/91234355@dhaval.dhaval.astppbilling.org entering state [terminated][483]
  500. 2021-02-22 10:18:42.084967 [NOTICE] sofia.c:8559 Hangup sofia/default/91234355@dhaval.dhaval.astppbilling.org [CS_CONSUME_MEDIA] [EXCHANGE_ROUTING_ERROR]
  501. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:585 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Running State Change CS_HANGUP (Cur 2 Tot 386)
  502. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:848 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Callstate Change DOWN -> HANGUP
  503. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:850 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State HANGUP
  504. 2021-02-22 10:18:42.084967 [DEBUG] mod_sofia.c:453 Channel sofia/default/91234355@dhaval.dhaval.astppbilling.org hanging up, cause: EXCHANGE_ROUTING_ERROR
  505. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:60 sofia/default/91234355@dhaval.dhaval.astppbilling.org Standard HANGUP, cause: EXCHANGE_ROUTING_ERROR
  506. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:850 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State HANGUP going to sleep
  507. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:620 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State Change CS_HANGUP -> CS_REPORTING
  508. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:585 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Running State Change CS_REPORTING (Cur 2 Tot 386)
  509. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:936 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State REPORTING
  510. 2021-02-22 10:18:42.084967 [INFO] mod_json_cdr.c:271 Process [d083a1e4-0d73-4108-a230-e2324ad8b285.cdr.json]
  511. 2021-02-22 10:18:42.084967 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 25 [EXCHANGE_ROUTING_ERROR]
  512. 2021-02-22 10:18:42.084967 [INFO] mod_dptools.c:3631 Originate Failed.  Cause: EXCHANGE_ROUTING_ERROR
  513. EXECUTE [depth=0] sofia/default/4000@13.127.159.248 set(original_did_number=123456)
  514. 2021-02-22 10:18:42.084967 [DEBUG] mod_dptools.c:1672 SET sofia/default/4000@13.127.159.248 [original_did_number]=[123456]
  515. 2021-02-22 10:18:42.084967 [NOTICE] switch_core_state_machine.c:386 sofia/default/4000@13.127.159.248 has executed the last dialplan instruction, hanging up.
  516. 2021-02-22 10:18:42.084967 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/4000@13.127.159.248 [CS_EXECUTE] [NORMAL_CLEARING]
  517. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:651 (sofia/default/4000@13.127.159.248) State EXECUTE going to sleep
  518. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_HANGUP (Cur 2 Tot 386)
  519. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:848 (sofia/default/4000@13.127.159.248) Callstate Change RINGING -> HANGUP
  520. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:850 (sofia/default/4000@13.127.159.248) State HANGUP
  521. 2021-02-22 10:18:42.084967 [DEBUG] mod_sofia.c:447 sofia/default/4000@13.127.159.248 Overriding SIP cause 480 with 483 from the other leg
  522. 2021-02-22 10:18:42.084967 [DEBUG] mod_sofia.c:453 Channel sofia/default/4000@13.127.159.248 hanging up, cause: NORMAL_CLEARING
  523. 2021-02-22 10:18:42.084967 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 483
  524. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:60 sofia/default/4000@13.127.159.248 Standard HANGUP, cause: NORMAL_CLEARING
  525. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:850 (sofia/default/4000@13.127.159.248) State HANGUP going to sleep
  526. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:620 (sofia/default/4000@13.127.159.248) State Change CS_HANGUP -> CS_REPORTING
  527. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_REPORTING (Cur 2 Tot 386)
  528. 2021-02-22 10:18:42.084967 [DEBUG] switch_core_state_machine.c:936 (sofia/default/4000@13.127.159.248) State REPORTING
  529. 2021-02-22 10:18:42.084967 [INFO] mod_json_cdr.c:271 Process [6aa87d48-d0be-4439-88a6-aa89e09dbe0c.cdr.json]
  530. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_state_machine.c:174 sofia/default/91234355@dhaval.dhaval.astppbilling.org Standard REPORTING, cause: EXCHANGE_ROUTING_ERROR
  531. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_state_machine.c:936 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State REPORTING going to sleep
  532. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_state_machine.c:611 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State Change CS_REPORTING -> CS_DESTROY
  533. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_session.c:1726 Session 386 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Locked, Waiting on external entities
  534. 2021-02-22 10:18:42.104965 [NOTICE] switch_core_session.c:1744 Session 386 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Ended
  535. 2021-02-22 10:18:42.104965 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/91234355@dhaval.dhaval.astppbilling.org [CS_DESTROY]
  536. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_state_machine.c:739 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) Running State Change CS_DESTROY (Cur 1 Tot 386)
  537. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_state_machine.c:749 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State DESTROY
  538. 2021-02-22 10:18:42.104965 [DEBUG] mod_sofia.c:364 sofia/default/91234355@dhaval.dhaval.astppbilling.org SOFIA DESTROY
  539. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_state_machine.c:181 sofia/default/91234355@dhaval.dhaval.astppbilling.org Standard DESTROY
  540. 2021-02-22 10:18:42.104965 [DEBUG] switch_core_state_machine.c:749 (sofia/default/91234355@dhaval.dhaval.astppbilling.org) State DESTROY going to sleep
  541. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_state_machine.c:174 sofia/default/4000@13.127.159.248 Standard REPORTING, cause: NORMAL_CLEARING
  542. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_state_machine.c:936 (sofia/default/4000@13.127.159.248) State REPORTING going to sleep
  543. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_state_machine.c:611 (sofia/default/4000@13.127.159.248) State Change CS_REPORTING -> CS_DESTROY
  544. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_session.c:1726 Session 385 (sofia/default/4000@13.127.159.248) Locked, Waiting on external entities
  545. 2021-02-22 10:18:42.124965 [NOTICE] switch_core_session.c:1744 Session 385 (sofia/default/4000@13.127.159.248) Ended
  546. 2021-02-22 10:18:42.124965 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/4000@13.127.159.248 [CS_DESTROY]
  547. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_state_machine.c:739 (sofia/default/4000@13.127.159.248) Running State Change CS_DESTROY (Cur 0 Tot 386)
  548. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_state_machine.c:749 (sofia/default/4000@13.127.159.248) State DESTROY
  549. 2021-02-22 10:18:42.124965 [DEBUG] mod_sofia.c:364 sofia/default/4000@13.127.159.248 SOFIA DESTROY
  550. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_state_machine.c:181 sofia/default/4000@13.127.159.248 Standard DESTROY
  551. 2021-02-22 10:18:42.124965 [DEBUG] switch_core_state_machine.c:749 (sofia/default/4000@13.127.159.248) State DESTROY going to sleep
  552. freeswitch@ip-172-31-23-227> /exit
  553.  
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
259
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
276
277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
 
freeswitch@ip-172-31-23-227> 
freeswitch@ip-172-31-23-227> 
freeswitch@ip-172-31-23-227> 
2021-02-22 10:18:39.304987 [NOTICE] switch_channel.c:1118 New Channel sofia/default/4000@13.127.159.248 [6aa87d48-d0be-4439-88a6-aa89e09dbe0c]
2021-02-22 10:18:39.304987 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_NEW (Cur 1 Tot 385)
2021-02-22 10:18:39.304987 [DEBUG] sofia.c:10280 sofia/default/4000@13.127.159.248 receiving invite from 13.127.159.248:5060 version: 1.10.5 -release-17-25569c1631 64bit
2021-02-22 10:18:39.324966 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 13.127.159.248:0.
2021-02-22 10:18:39.324966 [DEBUG] sofia.c:10403 IP 13.127.159.248 Approved by acl "default[]". Access Granted.
2021-02-22 10:18:39.324966 [DEBUG] sofia.c:7326 Channel sofia/default/4000@13.127.159.248 entering state [received][100]
2021-02-22 10:18:39.324966 [DEBUG] sofia.c:7336 Remote SDP:
v=0
o=FreeSWITCH 1613965557 1613965558 IN IP4 13.127.159.248
s=FreeSWITCH
c=IN IP4 13.127.159.248
t=0 0
m=audio 23562 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
 
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/4000@13.127.159.248 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2021-02-22 10:18:39.324966 [DEBUG] switch_core_codec.c:111 sofia/default/4000@13.127.159.248 Original read codec set to PCMA:8
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
2021-02-22 10:18:39.324966 [DEBUG] switch_core_media.c:5911 sofia/default/4000@13.127.159.248 Set 2833 dtmf send payload to 101 recv payload to 101
2021-02-22 10:18:39.324966 [DEBUG] sofia.c:7760 (sofia/default/4000@13.127.159.248) State Change CS_NEW -> CS_INIT
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:604 (sofia/default/4000@13.127.159.248) State NEW
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_INIT (Cur 1 Tot 385)
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:628 (sofia/default/4000@13.127.159.248) State INIT
2021-02-22 10:18:39.324966 [DEBUG] mod_sofia.c:93 sofia/default/4000@13.127.159.248 SOFIA INIT
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:40 sofia/default/4000@13.127.159.248 Standard INIT
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:48 (sofia/default/4000@13.127.159.248) State Change CS_INIT -> CS_ROUTING
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:628 (sofia/default/4000@13.127.159.248) State INIT going to sleep
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4000@13.127.159.248) Running State Change CS_ROUTING (Cur 1 Tot 385)
2021-02-22 10:18:39.324966 [DEBUG] switch_channel.c:2332 (sofia/default/4000@13.127.159.248) Callstate Change DOWN -> RINGING
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:644 (sofia/default/4000@13.127.159.248) State ROUTING
2021-02-22 10:18:39.324966 [DEBUG] mod_sofia.c:154 sofia/default/4000@13.127.159.248 SOFIA ROUTING
2021-02-22 10:18:39.324966 [DEBUG] switch_core_state_machine.c:236 sofia/default/4000@13.127.159.248 Standard ROUTING
2021-02-22 10:18:39.324966 [INFO] mod_dialplan_xml.c:637 Processing 4000 <4000>->123456 in context default
2021-02-22 10:18:39.364967 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
2021-02-22 10:18:39.364967 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2021-02-22 10:18:39.384964 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [xml_handler] Params:
Event-Name: REQUEST_PARAMS
Core-UUID: 22268e51-3187-474a-b82e-9ed977c04631
FreeSWITCH-Hostname: ip-172-31-23-227
FreeSWITCH-Switchname: ip-172-31-23-227
FreeSWITCH-IPv4: 172.31.23.227
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2021-02-22%2010%3A18%3A39
Event-Date-GMT: Mon,%2022%20Feb%202021%2010%3A18%3A39%20GMT
Event-Date-Timestamp: 1613989119324966
Event-Calling-File: mod_dialplan_xml.c
Event-Calling-Function: dialplan_xml_locate
Event-Calling-Line-Number: 608
Event-Sequence: 357714
Channel-State: CS_ROUTING
Channel-Call-State: RINGING
Channel-State-Number: 2
Channel-Name: sofia/default/4000%4013.127.159.248
Unique-ID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 4000%4013.127.159.248
Channel-Call-UUID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
Answer-State: ringing
Channel-Read-Codec-Name: PCMA
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMA
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Logical-Direction: inbound
Caller-Username: 4000
Caller-Dialplan: XML
Caller-Caller-ID-Name: 4000
Caller-Caller-ID-Number: 4000
Caller-Orig-Caller-ID-Name: 4000
Caller-Orig-Caller-ID-Number: 4000
Caller-Network-Addr: 13.127.159.248
Caller-ANI: 4000
Caller-Destination-Number: 123456
Caller-Unique-ID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
Caller-Source: mod_sofia
Caller-Context: default
Caller-Channel-Name: sofia/default/4000%4013.127.159.248
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1613989119324966
Caller-Channel-Created-Time: 1613989119324966
Caller-Channel-Answered-Time: 0
Caller-Channel-Progress-Time: 0
Caller-Channel-Progress-Media-Time: 0
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: inbound
variable_uuid: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
variable_session_id: 385
variable_sip_from_user: 4000
variable_sip_from_uri: 4000%4013.127.159.248
variable_sip_from_host: 13.127.159.248
variable_video_media_flow: disabled
variable_text_media_flow: disabled
variable_channel_name: sofia/default/4000%4013.127.159.248
variable_sip_call_id: 2c185857-ef9a-1239-f6ab-029485f1bd1c
variable_sip_local_network_addr: 18.221.51.90
variable_sip_network_ip: 13.127.159.248
variable_sip_network_port: 5060
variable_sip_invite_stamp: 1613989119304987
variable_sip_received_ip: 13.127.159.248
variable_sip_received_port: 5060
variable_sip_via_protocol: udp
variable_sip_authorized: true
variable_sip_acl_authed_by: default
variable_sip_from_user_stripped: 4000
variable_sip_from_tag: U4rBN5B6gUpNc
variable_sofia_profile_name: default
variable_sofia_profile_url: sip%3Amod_sofia%4018.221.51.90%3A7777
variable_recovery_profile_name: default
variable_sip_Remote-Party-ID: %224000%22%20%3Csip%3A4000%4013.127.159.248%3E%3Bparty%3Dcalling%3Bscreen%3Dyes%3Bprivacy%3Doff
variable_sip_cid_type: rpid
variable_sip_full_via: SIP/2.0/UDP%2013.127.159.248%3Brport%3D5060%3Bbranch%3Dz9hG4bK09gH20cD63XBH
variable_sip_from_display: 4000
variable_sip_full_from: %224000%22%20%3Csip%3A4000%4013.127.159.248%3E%3Btag%3DU4rBN5B6gUpNc
variable_sip_full_to: %3Csip%3A123456%4018.221.51.90%3A7777%3E
variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20OPTIONS,%20MESSAGE,%20INFO,%20UPDATE,%20REGISTER,%20REFER,%20NOTIFY,%20PUBLISH,%20SUBSCRIBE
variable_sip_req_user: 123456
variable_sip_req_port: 7777
variable_sip_req_uri: 123456%4018.221.51.90%3A7777
variable_sip_req_host: 18.221.51.90
variable_sip_to_user: 123456
variable_sip_to_port: 7777
variable_sip_to_uri: 123456%4018.221.51.90%3A7777
variable_sip_to_host: 18.221.51.90
variable_sip_contact_params: transport%3Dudp%3Bgw%3DDhaval_gt
variable_sip_contact_user: gw%2BDhaval_gt
variable_sip_contact_port: 5060
variable_sip_contact_uri: gw%2BDhaval_gt%4013.127.159.248%3A5060
variable_sip_contact_host: 13.127.159.248
variable_sip_user_agent: ASTPP
variable_sip_via_host: 13.127.159.248
variable_sip_via_rport: 5060
variable_max_forwards: 69
variable_presence_id: 4000%4013.127.159.248
variable_switch_r_sdp: v%3D0%0D%0Ao%3DFreeSWITCH%201613965557%201613965558%20IN%20IP4%2013.127.159.248%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2013.127.159.248%0D%0At%3D0%200%0D%0Am%3Daudio%2023562%20RTP/AVP%208%200%2018%20101%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0A
variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
variable_rtp_use_codec_string: PCMA,PCMU
variable_remote_video_media_flow: inactive
variable_remote_text_media_flow: inactive
variable_remote_audio_media_flow: sendrecv
variable_audio_media_flow: sendrecv
variable_remote_media_ip: 13.127.159.248
variable_remote_media_port: 23562
variable_rtp_audio_recv_pt: 8
variable_rtp_use_codec_name: PCMA
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
variable_read_codec: PCMA
variable_original_read_codec: PCMA
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: PCMA
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_endpoint_disposition: RECEIVED
variable_call_uuid: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
Hunt-Direction: inbound
Hunt-Logical-Direction: inbound
Hunt-Username: 4000
Hunt-Dialplan: XML
Hunt-Caller-ID-Name: 4000
Hunt-Caller-ID-Number: 4000
Hunt-Orig-Caller-ID-Name: 4000
Hunt-Orig-Caller-ID-Number: 4000
Hunt-Network-Addr: 13.127.159.248
Hunt-ANI: 4000
Hunt-Destination-Number: 123456
Hunt-Unique-ID: 6aa87d48-d0be-4439-88a6-aa89e09dbe0c
Hunt-Source: mod_sofia
Hunt-Context: default
Hunt-Channel-Name: sofia/default/4000%4013.127.159.248
Hunt-Profile-Index: 1
Hunt-Profile-Created-Time: 1613989119324966
Hunt-Channel-Created-Time: 1613989119324966
Hunt-Channel-Answered-Time: 0
Hunt-Channel-Progress-Time: 0
Hunt-Channel-Progress-Media-Time: 0
Hunt-Channel-Hangup-Time: 0
Hunt-Channel-Transfer-Time: 0
Hunt-Channel-Resurrect-Time: 0
Hunt-Channel-Bridged-Time: 0
Hunt-Channel-Last-Hold: 0
Hunt-Channel-Hold-Accum: 0
Hunt-Screen-Bit: true
Hunt-Privacy-Hide-Name: false
Hunt-Privacy-Hide-Number: false
 
 
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_value: 
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] section: dialplan
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] tag_name: 
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [xml_REQUEST] key_name: 
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] blind_flag: 0
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 123456
2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '123456' limit 1
2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '123456' AND status=0 limit 1
2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '123456' and status=0 LIMIT 1
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 4000 / 4000
2021-02-22 10:18:39.384964 [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 ="123456" LIMIT 1
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : inbound
2021-02-22 10:18:39.384964 [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("13.127.159.248") 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 "123456"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
2021-02-22 10:18:39.384964 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 3673942894]
2021-02-22 10:18:39.384964 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "3673942894" AND deleted = 0 limit 1
2021-02-22 10:18:39.404965 [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 ="123456" AND A.accountid = '3' limit 1
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 123456
2021-02-22 10:18:39.404965 [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 ="123456" LIMIT 1
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call direction : inbound
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="123456" AND (accountid = 0 OR status = 1) LIMIT 1
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :4
2021-02-22 10:18:39.404965 [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 (patterns = '^123456.*' OR patterns = '^12345.*' OR patterns = '^1234.*' OR patterns = '^123.*' OR patterns = '^12.*' OR patterns = '^1.*' OR patterns ='--') AND accountid = 4 ORDER BY LENGTH(PKGPTR.patterns) DESC
2021-02-22 10:18:39.404965 [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 = '123456' OR number = '12345' OR number = '1234' OR number = '123' OR number = '12' OR number = '1' OR number ='--') OR (number = '123456*' OR number = '12345*' OR number = '1234*' OR number = '123*' OR number = '12*' OR number = '1*' OR number ='--') OR (number = '4000' OR number = '400' OR number = '40' OR number = '4' OR number ='--') OR (number = '4000*' OR number = '400*' OR number = '40*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '123456' OR routing_prefix = '12345' OR routing_prefix = '1234' OR routing_prefix = '123' OR routing_prefix = '12' OR routing_prefix = '1' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 3
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3673942894
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 10000
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 1
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :inbound
2021-02-22 10:18:39.404965 [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 ="123456" LIMIT 1
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] call_direction:::::: inbound
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 5
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.00000
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] comment : 123456
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 85
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 3
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 3 !!!
2021-02-22 10:18:39.404965 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Max length duration : 100 minutes
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] INB_FREE:TRUE
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] free_inbound:1
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 3 AND status=0 LIMIT 1
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "4" AND deleted = 0 limit 1
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Actual CustomerInfo XML:3
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Userinfo XML:3
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [userinfo] Actual CustomerInfo XML : 3
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 4 limit 1
2021-02-22 10:18:39.404965 [WARNING] switch_cpp.cpp:1447 [ASTPP] [user_domain] user_domaindhaval.dhaval.astppbilling.org
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] maxlength::::::::: 100
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] customer_userinfo['is_recording'] : 1
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] call_direction : inbound
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing  WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "1" AND accounts.status = 0 
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 4 limit 1
2021-02-22 10:18:39.404965 [WARNING] switch_cpp.cpp:1447 [ASTPP] [user_domain] user_domaindhaval.dhaval.astppbilling.org
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_7
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] call_type : 7
2021-02-22 10:18:39.404965 [INFO] switch_cpp.cpp:1447 [ASTPP] [PBX] call_type : Ring group
2021-02-22 10:18:39.404965 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT id as ringgroup_id,name as ringgroup_name,strategy as ringgroup_strategy,destinations as ringgroup_destinations,description as ringgroup_description,announcementid as ringgroup_announcementid,call_record as ringgroup_call_record,skip_busy as ringgroup_skip_busy,ringbackid as ringgroup_ringbackid,calleridname as ringgroup_calleridname,calleridnumber as ringgroup_calleridnumber,no_answer as ringgroup_no_answer,no_answer_call_type as no_answer_call_type,no_answer_call_type_value from pbx_ringgroup WHERE id = 1 and status = 0 LIMIT 1
2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== ringgroup_dlr_str h========Count==========={"destination_1":"91234355#","delay_1":"0","time_out_1":"5","Promptdropdown_1":"1"}
2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== announcement====Count===============4
2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== announcement===================0
2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== final_count========final_count===========0===
2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== ring_group_recording===================0
2021-02-22 10:18:39.424966 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== ringgroup_dlr_str===================[sip_h_p-call_type='custom_forward',sip_h_P-Accountcode=3,call_timeout=5,confirm=false,announcement=no_file,dialed_user=91234355#,leg_timeout=5,leg_delay_start=0]sofia/${sofia_profile_name}/91234355@dhaval.dhaval.astppbilling.org,
2021-02-22 10:18:39.424966 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '' LIMIT 1
2021-02-22 10:18:39.424966 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>