From Sahil, 3 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: goldenrod; font-weight: bold;"> 2021-04-09 09:28:24.390319 [DEBUG] sofia.c:10337 verifying acl "default" for ip/port 37.9.63.182:0.
  2. 2021-04-09 09:28:24.390319 [DEBUG] sofia.c:10366 IP 37.9.63.182 Approved by acl "default[]". Access Granted.
  3. 2021-04-09 09:28:24.390319 [DEBUG] sofia.c:7290 Channel sofia/default/09025791118@37.9.63.182:1024 entering state [received][100]
  4. 2021-04-09 09:28:24.390319 [DEBUG] sofia.c:7300 Remote SDP:
  5. v=0
  6. o=- 509224170 509224171 IN IP4 37.9.63.182
  7. s=VoipSIP
  8. c=IN IP4 37.9.63.182
  9. t=0 0
  10. m=audio 8228 RTP/AVP 18 8 0
  11. a=rtpmap:18 G729/8000/1
  12. a=fmtp:18 annexb=no
  13. a=rtpmap:8 PCMA/8000
  14. a=rtpmap:0 PCMU/8000
  15.  
  16. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  17. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  18. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  19. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  20. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  21. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  22. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  23. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  24. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  25. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  26. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  27. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  28. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:3834 Set Codec sofia/default/09025791118@37.9.63.182:1024 G729/8000 20 ms 160 samples 8000 bits 1 channels
  29. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_codec.c:111 sofia/default/09025791118@37.9.63.182:1024 Original read codec set to G729:18
  30. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5878 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  31. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_media.c:5907 sofia/default/09025791118@37.9.63.182:1024 Set 2833 dtmf send payload to 101 recv payload to 101
  32. 2021-04-09 09:28:24.390319 [DEBUG] sofia.c:7724 (sofia/default/09025791118@37.9.63.182:1024) State Change CS_NEW -> CS_INIT
  33. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:604 (sofia/default/09025791118@37.9.63.182:1024) State NEW
  34. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:585 (sofia/default/09025791118@37.9.63.182:1024) Running State Change CS_INIT (Cur 3 Tot 234746)
  35. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:628 (sofia/default/09025791118@37.9.63.182:1024) State INIT
  36. 2021-04-09 09:28:24.390319 [DEBUG] mod_sofia.c:93 sofia/default/09025791118@37.9.63.182:1024 SOFIA INIT
  37. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:40 sofia/default/09025791118@37.9.63.182:1024 Standard INIT
  38. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:48 (sofia/default/09025791118@37.9.63.182:1024) State Change CS_INIT -> CS_ROUTING
  39. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:628 (sofia/default/09025791118@37.9.63.182:1024) State INIT going to sleep
  40. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:585 (sofia/default/09025791118@37.9.63.182:1024) Running State Change CS_ROUTING (Cur 3 Tot 234746)
  41. 2021-04-09 09:28:24.390319 [DEBUG] switch_channel.c:2332 (sofia/default/09025791118@37.9.63.182:1024) Callstate Change DOWN -> RINGING
  42. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:644 (sofia/default/09025791118@37.9.63.182:1024) State ROUTING
  43. 2021-04-09 09:28:24.390319 [DEBUG] mod_sofia.c:154 sofia/default/09025791118@37.9.63.182:1024 SOFIA ROUTING
  44. 2021-04-09 09:28:24.390319 [DEBUG] switch_core_state_machine.c:236 sofia/default/09025791118@37.9.63.182:1024 Standard ROUTING
  45. 2021-04-09 09:28:24.390319 [INFO] mod_dialplan_xml.c:637 Processing 09025791118 <09025791118>->23418887384 in context default
  46. 2021-04-09 09:28:24.390319 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  47. 2021-04-09 09:28:24.390319 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  48. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
  49. Event-Name: REQUEST_PARAMS
  50. Core-UUID: ab3e105f-e6f4-458f-b102-a4065f4859ae
  51. FreeSWITCH-Hostname: soultech
  52. FreeSWITCH-Switchname: soultech
  53. FreeSWITCH-IPv4: 192.168.8.3
  54. FreeSWITCH-IPv6: %3A%3A1
  55. Event-Date-Local: 2021-04-09%2009%3A28%3A24
  56. Event-Date-GMT: Fri,%2009%20Apr%202021%2008%3A28%3A24%20GMT
  57. Event-Date-Timestamp: 1617956904390319
  58. Event-Calling-File: mod_dialplan_xml.c
  59. Event-Calling-Function: dialplan_xml_locate
  60. Event-Calling-Line-Number: 608
  61. Event-Sequence: 11797823
  62. Channel-State: CS_ROUTING
  63. Channel-Call-State: RINGING
  64. Channel-State-Number: 2
  65. Channel-Name: sofia/default/09025791118%4037.9.63.182%3A1024
  66. Unique-ID: c80ae7f1-12de-49ee-a833-4e4abe038411
  67. Call-Direction: inbound
  68. Presence-Call-Direction: inbound
  69. Channel-HIT-Dialplan: true
  70. Channel-Presence-ID: 09025791118%4037.9.63.182
  71. Channel-Call-UUID: c80ae7f1-12de-49ee-a833-4e4abe038411
  72. Answer-State: ringing
  73. Channel-Read-Codec-Name: G729
  74. Channel-Read-Codec-Rate: 8000
  75. Channel-Read-Codec-Bit-Rate: 8000
  76. Channel-Write-Codec-Name: G729
  77. Channel-Write-Codec-Rate: 8000
  78. Channel-Write-Codec-Bit-Rate: 8000
  79. Caller-Direction: inbound
  80. Caller-Logical-Direction: inbound
  81. Caller-Username: 09025791118
  82. Caller-Dialplan: XML
  83. Caller-Caller-ID-Name: 09025791118
  84. Caller-Caller-ID-Number: 09025791118
  85. Caller-Orig-Caller-ID-Name: 09025791118
  86. Caller-Orig-Caller-ID-Number: 09025791118
  87. Caller-Network-Addr: 37.9.63.182
  88. Caller-ANI: 09025791118
  89. Caller-Destination-Number: 23418887384
  90. Caller-Unique-ID: c80ae7f1-12de-49ee-a833-4e4abe038411
  91. Caller-Source: mod_sofia
  92. Caller-Context: default
  93. Caller-Channel-Name: sofia/default/09025791118%4037.9.63.182%3A1024
  94. Caller-Profile-Index: 1
  95. Caller-Profile-Created-Time: 1617956904390319
  96. Caller-Channel-Created-Time: 1617956904390319
  97. Caller-Channel-Answered-Time: 0
  98. Caller-Channel-Progress-Time: 0
  99. Caller-Channel-Progress-Media-Time: 0
  100. Caller-Channel-Hangup-Time: 0
  101. Caller-Channel-Transfer-Time: 0
  102. Caller-Channel-Resurrect-Time: 0
  103. Caller-Channel-Bridged-Time: 0
  104. Caller-Channel-Last-Hold: 0
  105. Caller-Channel-Hold-Accum: 0
  106. Caller-Screen-Bit: true
  107. Caller-Privacy-Hide-Name: false
  108. Caller-Privacy-Hide-Number: false
  109. variable_direction: inbound
  110. variable_uuid: c80ae7f1-12de-49ee-a833-4e4abe038411
  111. variable_session_id: 234746
  112. variable_sip_from_user: 09025791118
  113. variable_sip_from_port: 1024
  114. variable_sip_from_uri: 09025791118%4037.9.63.182%3A1024
  115. variable_sip_from_host: 37.9.63.182
  116. variable_video_media_flow: disabled
  117. variable_text_media_flow: disabled
  118. variable_channel_name: sofia/default/09025791118%4037.9.63.182%3A1024
  119. variable_sip_call_id: lWhyidxM2k%4037.9.63.182
  120. variable_sip_local_network_addr: 192.168.8.3
  121. variable_sip_network_ip: 37.9.63.182
  122. variable_sip_network_port: 1024
  123. variable_sip_invite_stamp: 1617956904390319
  124. variable_sip_received_ip: 37.9.63.182
  125. variable_sip_received_port: 1024
  126. variable_sip_via_protocol: udp
  127. variable_sip_authorized: true
  128. variable_sip_acl_authed_by: default
  129. variable_sip_from_user_stripped: 09025791118
  130. variable_sip_from_tag: 09282195803890
  131. variable_sofia_profile_name: default
  132. variable_sofia_profile_url: sip%3Amod_sofia%40192.168.8.3%3A5060
  133. variable_recovery_profile_name: default
  134. variable_sip_full_via: SIP/2.0/UDP%2037.9.63.182%3A1024%3Bbranch%3Dz9hG4bK28092112466560495803890%3Brport%3D1024
  135. variable_sip_from_display: 09025791118
  136. variable_sip_full_from: %2209025791118%22%20%3Csip%3A09025791118%4037.9.63.182%3A1024%3E%3Btag%3D09282195803890
  137. variable_sip_full_to: %3Csip%3A23418887384%40192.168.8.3%3A5060%3E
  138. variable_sip_allow: INVITE,%20ACK,%20OPTIONS,%20BYE,%20CANCEL,%20REGISTER,%20INFO,%20NOTIFY,%20MESSAGE,%20SUBSCRIBE,%20REFER,%20PUBLISH,%20UPDATE
  139. variable_sip_req_user: 23418887384
  140. variable_sip_req_port: 5060
  141. variable_sip_req_uri: 23418887384%40192.168.8.3%3A5060
  142. variable_sip_req_host: 192.168.8.3
  143. variable_sip_to_user: 23418887384
  144. variable_sip_to_port: 5060
  145. variable_sip_to_uri: 23418887384%40192.168.8.3%3A5060
  146. variable_sip_to_host: 192.168.8.3
  147. variable_sip_contact_params: transport%3Dudp
  148. variable_sip_contact_user: nobody
  149. variable_sip_contact_port: 1024
  150. variable_sip_contact_uri: nobody%4037.9.63.182%3A1024
  151. variable_sip_contact_host: 37.9.63.182
  152. variable_sip_via_host: 37.9.63.182
  153. variable_sip_via_port: 1024
  154. variable_sip_via_rport: 1024
  155. variable_max_forwards: 70
  156. variable_presence_id: 09025791118%4037.9.63.182
  157. variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%20509224170%20509224171%20IN%20IP4%2037.9.63.182%0D%0As%3DVoipSIP%0D%0Ac%3DIN%20IP4%2037.9.63.182%0D%0At%3D0%200%0D%0Am%3Daudio%208228%20RTP/AVP%2018%208%200%0D%0Aa%3Drtpmap%3A18%20G729/8000/1%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0A
  158. variable_ep_codec_string: mod_g729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  159. variable_rtp_use_codec_string: PCMA,PCMU,G729
  160. variable_remote_video_media_flow: inactive
  161. variable_remote_text_media_flow: inactive
  162. variable_remote_audio_media_flow: sendrecv
  163. variable_audio_media_flow: sendrecv
  164. variable_remote_media_ip: 37.9.63.182
  165. variable_remote_media_port: 8228
  166. variable_rtp_audio_recv_pt: 18
  167. variable_rtp_use_codec_name: G729
  168. variable_rtp_use_codec_fmtp: annexb%3Dno
  169. variable_rtp_use_codec_rate: 8000
  170. variable_rtp_use_codec_ptime: 20
  171. variable_rtp_use_codec_channels: 1
  172. variable_rtp_last_audio_codec_string: G729%408000h%4020i%401c
  173. variable_read_codec: G729
  174. variable_original_read_codec: G729
  175. variable_read_rate: 8000
  176. variable_original_read_rate: 8000
  177. variable_write_codec: G729
  178. variable_write_rate: 8000
  179. variable_dtmf_type: rfc2833
  180. variable_endpoint_disposition: RECEIVED
  181. variable_call_uuid: c80ae7f1-12de-49ee-a833-4e4abe038411
  182. Hunt-Direction: inbound
  183. Hunt-Logical-Direction: inbound
  184. Hunt-Username: 09025791118
  185. Hunt-Dialplan: XML
  186. Hunt-Caller-ID-Name: 09025791118
  187. Hunt-Caller-ID-Number: 09025791118
  188. Hunt-Orig-Caller-ID-Name: 09025791118
  189. Hunt-Orig-Caller-ID-Number: 09025791118
  190. Hunt-Network-Addr: 37.9.63.182
  191. Hunt-ANI: 09025791118
  192. Hunt-Destination-Number: 23418887384
  193. Hunt-Unique-ID: c80ae7f1-12de-49ee-a833-4e4abe038411
  194. Hunt-Source: mod_sofia
  195. Hunt-Context: default
  196. Hunt-Channel-Name: sofia/default/09025791118%4037.9.63.182%3A1024
  197. Hunt-Profile-Index: 1
  198. Hunt-Profile-Created-Time: 1617956904390319
  199. Hunt-Channel-Created-Time: 1617956904390319
  200. Hunt-Channel-Answered-Time: 0
  201. Hunt-Channel-Progress-Time: 0
  202. Hunt-Channel-Progress-Media-Time: 0
  203. Hunt-Channel-Hangup-Time: 0
  204. Hunt-Channel-Transfer-Time: 0
  205. Hunt-Channel-Resurrect-Time: 0
  206. Hunt-Channel-Bridged-Time: 0
  207. Hunt-Channel-Last-Hold: 0
  208. Hunt-Channel-Hold-Accum: 0
  209. Hunt-Screen-Bit: true
  210. Hunt-Privacy-Hide-Name: false
  211. Hunt-Privacy-Hide-Number: false
  212.  
  213.  
  214. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value:
  215. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name:
  216. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name:
  217. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
  218. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] blind_flag: 0
  219. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 23418887384
  220. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '23418887384' limit 1
  221. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '23418887384' AND status=0 limit 1
  222. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '23418887384' and status=0 LIMIT 1
  223. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : 09025791118 / 09025791118
  224. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  225. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  226. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 23418887384
  227. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 018887384
  228. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887384" LIMIT 1
  229. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : inbound
  230. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("37.9.63.182") 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 "23418887384"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  231. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 8090000000]
  232. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "8090000000" AND deleted = 0 limit 1
  233. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE number = "8090000000" AND deleted = 0 limit 1
  234. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="23418887384" AND A.accountid = '49' limit 1
  235. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 23418887384
  236. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  237. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  238. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 23418887384
  239. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 018887384
  240. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887384" LIMIT 1
  241. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : inbound
  242. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  243. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  244. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 23418887384
  245. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 018887384
  246. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="018887384" AND (accountid = 0 OR status = 1) LIMIT 1
  247. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
  248. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :175
  249. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^23418887384.*' OR patterns = '^2341888738.*' OR patterns = '^234188873.*' OR patterns = '^23418887.*' OR patterns = '^2341888.*' OR patterns = '^234188.*' OR patterns = '^23418.*' OR patterns = '^2341.*' OR patterns = '^234.*' OR patterns = '^23.*' OR patterns = '^2.*' OR patterns ='--') AND accountid = 175 ORDER BY LENGTH(PKGPTR.patterns) DESC
  250. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '23418887384' OR number = '2341888738' OR number = '234188873' OR number = '23418887' OR number = '2341888' OR number = '234188' OR number = '23418' OR number = '2341' OR number = '234' OR number = '23' OR number = '2' OR number ='--') OR (number = '23418887384*' OR number = '2341888738*' OR number = '234188873*' OR number = '23418887*' OR number = '2341888*' OR number = '234188*' OR number = '23418*' OR number = '2341*' OR number = '234*' OR number = '23*' OR number = '2*' OR number ='--') OR (number = '09025791118' OR number = '0902579111' OR number = '090257911' OR number = '09025791' OR number = '0902579' OR number = '090257' OR number = '09025' OR number = '0902' OR number = '090' OR number = '09' OR number = '0' OR number ='--') OR (number = '09025791118*' OR number = '0902579111*' OR number = '090257911*' OR number = '09025791*' OR number = '0902579*' OR number = '090257*' OR number = '09025*' OR number = '0902*' OR number = '090*' OR number = '09*' OR number = '0*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  251. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
  252. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '23418887384' OR routing_prefix = '2341888738' OR routing_prefix = '234188873' OR routing_prefix = '23418887' OR routing_prefix = '2341888' OR routing_prefix = '234188' OR routing_prefix = '23418' OR routing_prefix = '2341' OR routing_prefix = '234' OR routing_prefix = '23' OR routing_prefix = '2' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  253. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
  254. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 49
  255. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 8090000000
  256. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 10000
  257. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  258. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 1
  259. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 0
  260. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
  261. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH1: 1
  262. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  263. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  264. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :inbound
  265. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  266. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  267. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887384" LIMIT 1
  268. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: inbound
  269. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information ===================
  270. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 87
  271. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
  272. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0
  273. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.00000
  274. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : 018887384
  275. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 141
  276. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 49
  277. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================
  278. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 49 !!!
  279. 2021-04-09 09:28:24.470329 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  280. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 100 minutes
  281. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] INB_FREE:TRUE
  282. 2021-04-09 09:28:24.470329 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] free_inbound:1
  283. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 49 AND status=0 LIMIT 1
  284. 2021-04-09 09:28:24.470329 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "175" AND deleted = 0 limit 1
  285. 2021-04-09 09:28:24.510279 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE id = "175" AND deleted = 0 limit 1
  286. 2021-04-09 09:28:24.510279 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Actual CustomerInfo XML:49
  287. 2021-04-09 09:28:24.510279 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Userinfo XML:49
  288. 2021-04-09 09:28:24.510279 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Actual CustomerInfo XML : 49
  289. 2021-04-09 09:28:24.510279 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 100
  290. 2021-04-09 09:28:24.510279 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 175 limit 1
  291. 2021-04-09 09:28:24.510279 [WARNING] switch_cpp.cpp:1443 [ASTPP] [user_domain] user_domainInbound Routes81.9mobile.nativetalk.com.ng
  292. 2021-04-09 09:28:24.510279 [DEBUG] switch_cpp.cpp:1443 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_10
  293. 2021-04-09 09:28:24.510279 [DEBUG] switch_cpp.cpp:1443 [ASTPP] custom_function_name::::::::::1343:::::::::::::::87
  294. 2021-04-09 09:28:24.510279 [INFO] switch_cpp.cpp:1443 [ASTPP] [PBX] call_type : 10
  295. 2021-04-09 09:28:24.510279 [INFO] switch_cpp.cpp:1443 [ASTPP] [PBX] call_type : IVR
  296. 2021-04-09 09:28:24.510279 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_IVR_INFO] Query :SELECT * from pbx_ivr_specification WHERE id = 19 and status=0 LIMIT 1
  297. 2021-04-09 09:28:24.510279 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '0' LIMIT 1
  298. 2021-04-09 09:28:24.510279 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  299. <document type="freeswitch/xml">
  300. <section name="dialplan" description="ASTPP Dialplan">
  301. <context name="default">
  302. <extension name="018887384">
  303. <condition field="destination_number" expression="23418887384">
  304. <action application="set" data="effective_destination_number=23418887384"/>
  305. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  306. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  307. <action application="set" data="callstart=2021-04-09 08:28:20"/>
  308. <action application="set" data="hangup_after_bridge=true"/>
  309. <action application="set" data="continue_on_fail=TRUE"/>
  310. <action application="set" data="account_id=49"/>
  311. <action application="set" data="parent_id=0"/>
  312. <action application="set" data="entity_id=3"/>
  313. <action application="set" data="call_processed=internal"/>
  314. <action application="set" data="call_direction=inbound"/>
  315. <action application="set" data="accountname=VSM"/>
  316. <action application="set" data="origination_rates_did=ID:87|CODE:^018887384.*|DESTINATION:018887384|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175"/>
  317. <action application="set" data="origination_rates=0"/>
  318. <action application="set" data="original_caller_id_name=09025791118"/>
  319. <action application="set" data="original_caller_id_number=09025791118"/>
  320. <action application="set" data="effective_caller_id_name=09025791118"/>
  321. <action application="set" data="effective_caller_id_number=09025791118"/>
  322. <action application="set" data="receiver_accid=175"/>
  323. <action application="set" data="domain_name=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  324. <action application="set" data="sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  325. <action application="export" data="domain_name=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  326. <action application="export" data="sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  327. <action application="export" data="presence_data=x|||9mobile(8090000000)|||^018887384.* // 018887384 // 0.00000||||||DID"/>
  328. <action application="export" data="call_type=10"/>
  329. <action application="set" data="did_calltype=10"/>
  330. <action application="set" data="did_extensions=19"/>
  331. <action application="set" data="ringback=0"/>
  332. <action application="set" data="module_name=pbx"/>
  333.     <action application="answer"/>
  334. <action application="ivr" data="multichoice IVR0"/>
  335. </condition>
  336. </extension>
  337. </context>
  338. </section>
  339. </document>
  340. Dialplan: sofia/default/09025791118@37.9.63.182:1024 parsing [default->018887384] continue=false
  341. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Regex (PASS) [018887384] destination_number(23418887384) =~ /23418887384/ break=on-false
  342. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(effective_destination_number=23418887384)
  343. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  344. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  345. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(callstart=2021-04-09 08:28:20)
  346. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(hangup_after_bridge=true)
  347. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(continue_on_fail=TRUE)
  348. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(account_id=49)
  349. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(parent_id=0)
  350. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(entity_id=3)
  351. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(call_processed=internal)
  352. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(call_direction=inbound)
  353. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(accountname=VSM)
  354. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(origination_rates_did=ID:87|CODE:^018887384.*|DESTINATION:018887384|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175)
  355. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(origination_rates=0)
  356. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(original_caller_id_name=09025791118)
  357. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(original_caller_id_number=09025791118)
  358. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(effective_caller_id_name=09025791118)
  359. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(effective_caller_id_number=09025791118)
  360. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(receiver_accid=175)
  361. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  362. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  363. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action export(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  364. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action export(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  365. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action export(presence_data=x|||9mobile(8090000000)|||^018887384.* // 018887384 // 0.00000||||||DID)
  366. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action export(call_type=10)
  367. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(did_calltype=10)
  368. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(did_extensions=19)
  369. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(ringback=0)
  370. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action set(module_name=pbx)
  371. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action answer()
  372. Dialplan: sofia/default/09025791118@37.9.63.182:1024 Action ivr(multichoice IVR0)
  373. 2021-04-09 09:28:24.510279 [DEBUG] switch_core_state_machine.c:287 (sofia/default/09025791118@37.9.63.182:1024) State Change CS_ROUTING -> CS_EXECUTE
  374. 2021-04-09 09:28:24.510279 [DEBUG] switch_core_state_machine.c:644 (sofia/default/09025791118@37.9.63.182:1024) State ROUTING going to sleep
  375. 2021-04-09 09:28:24.510279 [DEBUG] switch_core_state_machine.c:585 (sofia/default/09025791118@37.9.63.182:1024) Running State Change CS_EXECUTE (Cur 3 Tot 234746)
  376. 2021-04-09 09:28:24.510279 [DEBUG] switch_core_state_machine.c:651 (sofia/default/09025791118@37.9.63.182:1024) State EXECUTE
  377. 2021-04-09 09:28:24.510279 [DEBUG] mod_sofia.c:209 sofia/default/09025791118@37.9.63.182:1024 SOFIA EXECUTE
  378. 2021-04-09 09:28:24.510279 [DEBUG] switch_core_state_machine.c:329 sofia/default/09025791118@37.9.63.182:1024 Standard EXECUTE
  379. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(effective_destination_number=23418887384)
  380. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [effective_destination_number]=[23418887384]
  381. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(bridge_pre_execute_bleg_app=sched_hangup)
  382. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [bridge_pre_execute_bleg_app]=[sched_hangup]
  383. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  384. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  385. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(callstart=2021-04-09 08:28:20)
  386. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [callstart]=[2021-04-09 08:28:20]
  387. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(hangup_after_bridge=true)
  388. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [hangup_after_bridge]=[true]
  389. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(continue_on_fail=TRUE)
  390. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [continue_on_fail]=[TRUE]
  391. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(account_id=49)
  392. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [account_id]=[49]
  393. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(parent_id=0)
  394. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [parent_id]=[0]
  395. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(entity_id=3)
  396. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [entity_id]=[3]
  397. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(call_processed=internal)
  398. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [call_processed]=[internal]
  399. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(call_direction=inbound)
  400. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [call_direction]=[inbound]
  401. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(accountname=VSM)
  402. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [accountname]=[VSM]
  403. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(origination_rates_did=ID:87|CODE:^018887384.*|DESTINATION:018887384|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175)
  404. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [origination_rates_did]=[ID:87|CODE:^018887384.*|DESTINATION:018887384|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175]
  405. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(origination_rates=0)
  406. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [origination_rates]=[0]
  407. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(original_caller_id_name=09025791118)
  408. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [original_caller_id_name]=[09025791118]
  409. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(original_caller_id_number=09025791118)
  410. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [original_caller_id_number]=[09025791118]
  411. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(effective_caller_id_name=09025791118)
  412. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [effective_caller_id_name]=[09025791118]
  413. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(effective_caller_id_number=09025791118)
  414. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [effective_caller_id_number]=[09025791118]
  415. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(receiver_accid=175)
  416. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [receiver_accid]=[175]
  417. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  418. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [domain_name]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  419. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  420. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [sip_to_host]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  421. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 export(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  422. 2021-04-09 09:28:24.510279 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  423. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 export(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  424. 2021-04-09 09:28:24.510279 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [sip_to_host]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  425. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 export(presence_data=x|||9mobile(8090000000)|||^018887384.* // 018887384 // 0.00000||||||DID)
  426. 2021-04-09 09:28:24.510279 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||9mobile(8090000000)|||^018887384.* // 018887384 // 0.00000||||||DID]
  427. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 export(call_type=10)
  428. 2021-04-09 09:28:24.510279 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_type]=[10]
  429. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(did_calltype=10)
  430. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [did_calltype]=[10]
  431. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(did_extensions=19)
  432. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [did_extensions]=[19]
  433. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(ringback=0)
  434. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [ringback]=[0]
  435. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 set(module_name=pbx)
  436. 2021-04-09 09:28:24.510279 [DEBUG] mod_dptools.c:1672 SET sofia/default/09025791118@37.9.63.182:1024 [module_name]=[pbx]
  437. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 answer()
  438. 2021-04-09 09:28:24.510279 [DEBUG] switch_core_media.c:8658 AUDIO RTP [sofia/default/09025791118@37.9.63.182:1024] 192.168.8.3 port 17288 -> 37.9.63.182 port 8228 codec: 18 ms: 20
  439. 2021-04-09 09:28:24.510279 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  440. 2021-04-09 09:28:24.530250 [DEBUG] switch_core_media.c:8972 sofia/default/09025791118@37.9.63.182:1024 Set 2833 dtmf send payload to 101
  441. 2021-04-09 09:28:24.530250 [DEBUG] switch_core_media.c:8979 sofia/default/09025791118@37.9.63.182:1024 Set 2833 dtmf receive payload to 101
  442. 2021-04-09 09:28:24.530250 [DEBUG] switch_core_media.c:9002 sofia/default/09025791118@37.9.63.182:1024 Set rtp dtmf delay to 40
  443. 2021-04-09 09:28:24.530250 [DEBUG] mod_sofia.c:905 Local SDP sofia/default/09025791118@37.9.63.182:1024:
  444. v=0
  445. o=FreeSWITCH 1617939616 1617939617 IN IP4 192.168.8.3
  446. s=FreeSWITCH
  447. c=IN IP4 192.168.8.3
  448. t=0 0
  449. m=audio 17288 RTP/AVP 18 101
  450. a=rtpmap:18 G729/8000
  451. a=fmtp:18 annexb=no
  452. a=rtpmap:101 telephone-event/8000
  453. a=fmtp:101 0-16
  454. a=ptime:20
  455. a=sendrecv
  456.  
  457. 2021-04-09 09:28:24.530250 [NOTICE] mod_dptools.c:1406 Channel [sofia/default/09025791118@37.9.63.182:1024] has been answered
  458. 2021-04-09 09:28:24.530250 [DEBUG] switch_channel.c:3865 (sofia/default/09025791118@37.9.63.182:1024) Callstate Change RINGING -> ACTIVE
  459. 2021-04-09 09:28:24.530250 [DEBUG] sofia.c:7290 Channel sofia/default/09025791118@37.9.63.182:1024 entering state [completed][200]
  460. EXECUTE [depth=0] sofia/default/09025791118@37.9.63.182:1024 ivr(multichoice IVR0)
  461. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-exit'
  462. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-sub'
  463. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
  464. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
  465. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-back'
  466. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:746 switch_ivr_menu_stack_xml_add binding 'menu-top'
  467. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:883 building menu 'multichoice IVR0'
  468. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:968 binding menu action 'menu-exec-app' to '/^(\d{2,20})$/'
  469. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:486 Executing IVR menu multichoice IVR0
  470. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms
  471. 2021-04-09 09:28:24.630417 [ERR] mod_g729.c:102 This codec is only usable in passthrough mode!
  472. 2021-04-09 09:28:24.630417 [ERR] switch_core_media.c:16090 Codec G.729 encoder error!
  473. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/pbx/Multichoice1_220321031142.wav
  474. 2021-04-09 09:28:24.630417 [DEBUG] switch_ivr_menu.c:378 waiting for 20/20 digits t/o 2000
  475. 2021-04-09 09:28:24.770285 [DEBUG] sofia.c:7290 Channel sofia/default/09025791118@37.9.63.182:1024 entering state [ready][200]
  476. 2021-04-09 09:28:24.930251 [DEBUG] switch_rtp.c:7720 Correct audio ip/port confirmed.
  477. 2021-04-09 09:28:25.650314 [DEBUG] switch_ivr_menu.c:425 digits ''
  478. 2021-04-09 09:28:25.650314 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms
  479. 2021-04-09 09:28:25.650314 [ERR] mod_g729.c:102 This codec is only usable in passthrough mode!
  480. 2021-04-09 09:28:25.650314 [ERR] switch_core_media.c:16090 Codec G.729 encoder error!
  481. 2021-04-09 09:28:25.650314 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/pbx/Multichoice1_220321031142.wav
  482. 2021-04-09 09:28:25.650314 [DEBUG] switch_ivr_menu.c:378 waiting for 20/20 digits t/o 2000
  483. 2021-04-09 09:28:26.650324 [DEBUG] switch_ivr_menu.c:425 digits ''
  484. 2021-04-09 09:28:26.650324 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms
  485. 2021-04-09 09:28:26.650324 [ERR] mod_g729.c:102 This codec is only usable in passthrough mode!
  486. 2021-04-09 09:28:26.650324 [ERR] switch_core_media.c:16090 Codec G.729 encoder error!
  487. 2021-04-09 09:28:26.650324 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/pbx/Multichoice1_220321031142.wav
  488. 2021-04-09 09:28:26.650324 [DEBUG] switch_ivr_menu.c:378 waiting for 20/20 digits t/o 2000
  489. 2021-04-09 09:28:26.830316 [DEBUG] switch_ivr_play_say.c:1933 done playing file /var/www/html/astpp/upload/pbx/Palmpay_220321120940.wav
  490. 2021-04-09 09:28:26.830316 [DEBUG] switch_ivr_menu.c:378 waiting for 20/20 digits t/o 2000
  491. 2021-04-09 09:28:27.670318 [DEBUG] switch_ivr_menu.c:425 digits ''
  492. 2021-04-09 09:28:27.670318 [DEBUG] switch_ivr_menu.c:516 Maximum timeouts
  493. 2021-04-09 09:28:27.670318 [DEBUG] switch_ivr_menu.c:676 exit-sound 'voicemail/vm-goodbye.wav'
  494. 2021-04-09 09:28:27.670318 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms
  495. 2021-04-09 09:28:27.670318 [ERR] mod_g729.c:102 This codec is only usable in passthrough mode!
  496. 2021-04-09 09:28:27.670318 [ERR] switch_core_media.c:16090 Codec G.729 encoder error!
  497. 2021-04-09 09:28:27.670318 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-goodbye.wav
  498. 2021-04-09 09:28:27.670318 [NOTICE] switch_core_state_machine.c:386 sofia/default/09025791118@37.9.63.182:1024 has executed the last dialplan instruction, hanging up.
  499. 2021-04-09 09:28:27.670318 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/09025791118@37.9.63.182:1024 [CS_EXECUTE] [NORMAL_CLEARING]
  500. 2021-04-09 09:28:27.670318 [DEBUG] switch_core_state_machine.c:651 (sofia/default/09025791118@37.9.63.182:1024) State EXECUTE going to sleep
  501. 2021-04-09 09:28:27.670318 [DEBUG] switch_core_state_machine.c:585 (sofia/default/09025791118@37.9.63.182:1024) Running State Change CS_HANGUP (Cur 3 Tot 234746)
  502. 2021-04-09 09:28:27.670318 [DEBUG] switch_core_state_machine.c:848 (sofia/default/09025791118@37.9.63.182:1024) Callstate Change ACTIVE -> HANGUP
  503. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:850 (sofia/default/09025791118@37.9.63.182:1024) State HANGUP
  504. 2021-04-09 09:28:27.710989 [DEBUG] mod_sofia.c:460 Channel sofia/default/09025791118@37.9.63.182:1024 hanging up, cause: NORMAL_CLEARING
  505. 2021-04-09 09:28:27.710989 [DEBUG] mod_sofia.c:514 Sending BYE to sofia/default/09025791118@37.9.63.182:1024
  506. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:60 sofia/default/09025791118@37.9.63.182:1024 Standard HANGUP, cause: NORMAL_CLEARING
  507. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:850 (sofia/default/09025791118@37.9.63.182:1024) State HANGUP going to sleep
  508. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:620 (sofia/default/09025791118@37.9.63.182:1024) State Change CS_HANGUP -> CS_REPORTING
  509. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:585 (sofia/default/09025791118@37.9.63.182:1024) Running State Change CS_REPORTING (Cur 3 Tot 234746)
  510. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:936 (sofia/default/09025791118@37.9.63.182:1024) State REPORTING
  511. 2021-04-09 09:28:27.710989 [INFO] mod_json_cdr.c:271 Process [c80ae7f1-12de-49ee-a833-4e4abe038411.cdr.json]
  512. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:174 sofia/default/09025791118@37.9.63.182:1024 Standard REPORTING, cause: NORMAL_CLEARING
  513. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:936 (sofia/default/09025791118@37.9.63.182:1024) State REPORTING going to sleep
  514. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:611 (sofia/default/09025791118@37.9.63.182:1024) State Change CS_REPORTING -> CS_DESTROY
  515. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_session.c:1726 Session 234746 (sofia/default/09025791118@37.9.63.182:1024) Locked, Waiting on external entities
  516. 2021-04-09 09:28:27.710989 [NOTICE] switch_core_session.c:1744 Session 234746 (sofia/default/09025791118@37.9.63.182:1024) Ended
  517. 2021-04-09 09:28:27.710989 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/09025791118@37.9.63.182:1024 [CS_DESTROY]
  518. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:739 (sofia/default/09025791118@37.9.63.182:1024) Running State Change CS_DESTROY (Cur 2 Tot 234746)
  519. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:749 (sofia/default/09025791118@37.9.63.182:1024) State DESTROY
  520. 2021-04-09 09:28:27.710989 [DEBUG] mod_sofia.c:365 sofia/default/09025791118@37.9.63.182:1024 SOFIA DESTROY
  521. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:181 sofia/default/09025791118@37.9.63.182:1024 Standard DESTROY
  522. 2021-04-09 09:28:27.710989 [DEBUG] switch_core_state_machine.c:749 (sofia/default/09025791118@37.9.63.182:1024) State DESTROY going to sleep
  523. 2021-04-09 09:28:27.830322 [DEBUG] switch_ivr_menu.c:425 digits ''
  524. 2021-04-09 09:28:27.830322 [DEBUG] switch_ivr_menu.c:516 Maximum timeouts
  525. 2021-04-09 09:28:27.830322 [DEBUG] switch_ivr_menu.c:676 exit-sound 'voicemail/vm-goodbye.wav'
  526. 2021-04-09 09:28:27.830322 [DEBUG] switch_ivr_play_say.c:1492 Codec Activated L16@8000hz 1 channels 20ms
  527. 2021-04-09 09:28:28.310314 [DEBUG] switch_ivr_play_say.c:1933 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-goodbye.wav
  528. 2021-04-09 09:28:28.310314 [NOTICE] switch_core_state_machine.c:386 sofia/default/09064081708@37.9.63.182:1024 has executed the last dialplan instruction, hanging up.
  529. 2021-04-09 09:28:28.310314 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/default/09064081708@37.9.63.182:1024 [CS_EXECUTE] [NORMAL_CLEARING]
  530. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:651 (sofia/default/09064081708@37.9.63.182:1024) State EXECUTE going to sleep
  531. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:585 (sofia/default/09064081708@37.9.63.182:1024) Running State Change CS_HANGUP (Cur 2 Tot 234746)
  532. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:848 (sofia/default/09064081708@37.9.63.182:1024) Callstate Change ACTIVE -> HANGUP
  533. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:850 (sofia/default/09064081708@37.9.63.182:1024) State HANGUP
  534. 2021-04-09 09:28:28.310314 [DEBUG] mod_sofia.c:460 Channel sofia/default/09064081708@37.9.63.182:1024 hanging up, cause: NORMAL_CLEARING
  535. 2021-04-09 09:28:28.310314 [DEBUG] mod_sofia.c:514 Sending BYE to sofia/default/09064081708@37.9.63.182:1024
  536. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:60 sofia/default/09064081708@37.9.63.182:1024 Standard HANGUP, cause: NORMAL_CLEARING
  537. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:850 (sofia/default/09064081708@37.9.63.182:1024) State HANGUP going to sleep
  538. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:620 (sofia/default/09064081708@37.9.63.182:1024) State Change CS_HANGUP -> CS_REPORTING
  539. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:585 (sofia/default/09064081708@37.9.63.182:1024) Running State Change CS_REPORTING (Cur 2 Tot 234746)
  540. 2021-04-09 09:28:28.310314 [DEBUG] switch_core_state_machine.c:936 (sofia/default/09064081708@37.9.63.182:1024) State REPORTING
  541. 2021-04-09 09:28:28.310314 [INFO] mod_json_cdr.c:271 Process [14782293-4c57-4f6d-bf93-28e446355b6e.cdr.json]
  542. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_state_machine.c:174 sofia/default/09064081708@37.9.63.182:1024 Standard REPORTING, cause: NORMAL_CLEARING
  543. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_state_machine.c:936 (sofia/default/09064081708@37.9.63.182:1024) State REPORTING going to sleep
  544. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_state_machine.c:611 (sofia/default/09064081708@37.9.63.182:1024) State Change CS_REPORTING -> CS_DESTROY
  545. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_session.c:1726 Session 234742 (sofia/default/09064081708@37.9.63.182:1024) Locked, Waiting on external entities
  546. 2021-04-09 09:28:28.350254 [NOTICE] switch_core_session.c:1744 Session 234742 (sofia/default/09064081708@37.9.63.182:1024) Ended
  547. 2021-04-09 09:28:28.350254 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/09064081708@37.9.63.182:1024 [CS_DESTROY]
  548. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_state_machine.c:739 (sofia/default/09064081708@37.9.63.182:1024) Running State Change CS_DESTROY (Cur 1 Tot 234746)
  549. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_state_machine.c:749 (sofia/default/09064081708@37.9.63.182:1024) State DESTROY
  550. 2021-04-09 09:28:28.350254 [DEBUG] mod_sofia.c:365 sofia/default/09064081708@37.9.63.182:1024 SOFIA DESTROY
  551. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_state_machine.c:181 sofia/default/09064081708@37.9.63.182:1024 Standard DESTROY
  552. 2021-04-09 09:28:28.350254 [DEBUG] switch_core_state_machine.c:749 (sofia/default/09064081708@37.9.63.182:1024) State DESTROY going to sleep
  553. 2021-04-09 09:28:29.470323 [NOTICE] switch_channel.c:1118 New Channel sofia/default/08163464460@37.9.63.182:1024 [db9e84c5-b50d-470c-be53-2f3f2b7f5ee2]
  554. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:585 (sofia/default/08163464460@37.9.63.182:1024) Running State Change CS_NEW (Cur 2 Tot 234747)
  555. 2021-04-09 09:28:29.470323 [DEBUG] sofia.c:10243 sofia/default/08163464460@37.9.63.182:1024 receiving invite from 37.9.63.182:1024 version: 1.10.1 -release-12-f9990221e6 64bit
  556. 2021-04-09 09:28:29.470323 [DEBUG] sofia.c:10337 verifying acl "default" for ip/port 37.9.63.182:0.
  557. 2021-04-09 09:28:29.470323 [DEBUG] sofia.c:10366 IP 37.9.63.182 Approved by acl "default[]". Access Granted.
  558. 2021-04-09 09:28:29.470323 [DEBUG] sofia.c:7290 Channel sofia/default/08163464460@37.9.63.182:1024 entering state [received][100]
  559. 2021-04-09 09:28:29.470323 [DEBUG] sofia.c:7300 Remote SDP:
  560. v=0
  561. o=- 423599848 423599849 IN IP4 37.9.63.182
  562. s=VoipSIP
  563. c=IN IP4 37.9.63.182
  564. t=0 0
  565. m=audio 7138 RTP/AVP 0 8 18
  566. a=rtpmap:0 PCMU/8000
  567. a=rtpmap:8 PCMA/8000
  568. a=rtpmap:18 G729/8000/1
  569. a=fmtp:18 annexb=no
  570.  
  571. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  572. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  573. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  574. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  575. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  576. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  577. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  578. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  579. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  580. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  581. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5590 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  582. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5645 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  583. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:3834 Set Codec sofia/default/08163464460@37.9.63.182:1024 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  584. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_codec.c:111 sofia/default/08163464460@37.9.63.182:1024 Original read codec set to PCMU:0
  585. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5878 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
  586. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_media.c:5907 sofia/default/08163464460@37.9.63.182:1024 Set 2833 dtmf send payload to 101 recv payload to 101
  587. 2021-04-09 09:28:29.470323 [DEBUG] sofia.c:7724 (sofia/default/08163464460@37.9.63.182:1024) State Change CS_NEW -> CS_INIT
  588. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:604 (sofia/default/08163464460@37.9.63.182:1024) State NEW
  589. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:585 (sofia/default/08163464460@37.9.63.182:1024) Running State Change CS_INIT (Cur 2 Tot 234747)
  590. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:628 (sofia/default/08163464460@37.9.63.182:1024) State INIT
  591. 2021-04-09 09:28:29.470323 [DEBUG] mod_sofia.c:93 sofia/default/08163464460@37.9.63.182:1024 SOFIA INIT
  592. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:40 sofia/default/08163464460@37.9.63.182:1024 Standard INIT
  593. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:48 (sofia/default/08163464460@37.9.63.182:1024) State Change CS_INIT -> CS_ROUTING
  594. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:628 (sofia/default/08163464460@37.9.63.182:1024) State INIT going to sleep
  595. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:585 (sofia/default/08163464460@37.9.63.182:1024) Running State Change CS_ROUTING (Cur 2 Tot 234747)
  596. 2021-04-09 09:28:29.470323 [DEBUG] switch_channel.c:2332 (sofia/default/08163464460@37.9.63.182:1024) Callstate Change DOWN -> RINGING
  597. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:644 (sofia/default/08163464460@37.9.63.182:1024) State ROUTING
  598. 2021-04-09 09:28:29.470323 [DEBUG] mod_sofia.c:154 sofia/default/08163464460@37.9.63.182:1024 SOFIA ROUTING
  599. 2021-04-09 09:28:29.470323 [DEBUG] switch_core_state_machine.c:236 sofia/default/08163464460@37.9.63.182:1024 Standard ROUTING
  600. 2021-04-09 09:28:29.470323 [INFO] mod_dialplan_xml.c:637 Processing 08163464460 <08163464460>->23418887395 in context default
  601. 2021-04-09 09:28:29.470323 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  602. 2021-04-09 09:28:29.470323 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  603. 2021-04-09 09:28:29.512268 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [xml_handler] Params:
  604. Event-Name: REQUEST_PARAMS
  605. Core-UUID: ab3e105f-e6f4-458f-b102-a4065f4859ae
  606. FreeSWITCH-Hostname: soultech
  607. FreeSWITCH-Switchname: soultech
  608. FreeSWITCH-IPv4: 192.168.8.3
  609. FreeSWITCH-IPv6: %3A%3A1
  610. Event-Date-Local: 2021-04-09%2009%3A28%3A29
  611. Event-Date-GMT: Fri,%2009%20Apr%202021%2008%3A28%3A29%20GMT
  612. Event-Date-Timestamp: 1617956909470323
  613. Event-Calling-File: mod_dialplan_xml.c
  614. Event-Calling-Function: dialplan_xml_locate
  615. Event-Calling-Line-Number: 608
  616. Event-Sequence: 11797930
  617. Channel-State: CS_ROUTING
  618. Channel-Call-State: RINGING
  619. Channel-State-Number: 2
  620. Channel-Name: sofia/default/08163464460%4037.9.63.182%3A1024
  621. Unique-ID: db9e84c5-b50d-470c-be53-2f3f2b7f5ee2
  622. Call-Direction: inbound
  623. Presence-Call-Direction: inbound
  624. Channel-HIT-Dialplan: true
  625. Channel-Presence-ID: 08163464460%4037.9.63.182
  626. Channel-Call-UUID: db9e84c5-b50d-470c-be53-2f3f2b7f5ee2
  627. Answer-State: ringing
  628. Channel-Read-Codec-Name: PCMU
  629. Channel-Read-Codec-Rate: 8000
  630. Channel-Read-Codec-Bit-Rate: 64000
  631. Channel-Write-Codec-Name: PCMU
  632. Channel-Write-Codec-Rate: 8000
  633. Channel-Write-Codec-Bit-Rate: 64000
  634. Caller-Direction: inbound
  635. Caller-Logical-Direction: inbound
  636. Caller-Username: 08163464460
  637. Caller-Dialplan: XML
  638. Caller-Caller-ID-Name: 08163464460
  639. Caller-Caller-ID-Number: 08163464460
  640. Caller-Orig-Caller-ID-Name: 08163464460
  641. Caller-Orig-Caller-ID-Number: 08163464460
  642. Caller-Network-Addr: 37.9.63.182
  643. Caller-ANI: 08163464460
  644. Caller-Destination-Number: 23418887395
  645. Caller-Unique-ID: db9e84c5-b50d-470c-be53-2f3f2b7f5ee2
  646. Caller-Source: mod_sofia
  647. Caller-Context: default
  648. Caller-Channel-Name: sofia/default/08163464460%4037.9.63.182%3A1024
  649. Caller-Profile-Index: 1
  650. Caller-Profile-Created-Time: 1617956909470323
  651. Caller-Channel-Created-Time: 1617956909470323
  652. Caller-Channel-Answered-Time: 0
  653. Caller-Channel-Progress-Time: 0
  654. Caller-Channel-Progress-Media-Time: 0
  655. Caller-Channel-Hangup-Time: 0
  656. Caller-Channel-Transfer-Time: 0
  657. Caller-Channel-Resurrect-Time: 0
  658. Caller-Channel-Bridged-Time: 0
  659. Caller-Channel-Last-Hold: 0
  660. Caller-Channel-Hold-Accum: 0
  661. Caller-Screen-Bit: true
  662. Caller-Privacy-Hide-Name: false
  663. Caller-Privacy-Hide-Number: false
  664. variable_direction: inbound
  665. variable_uuid: db9e84c5-b50d-470c-be53-2f3f2b7f5ee2
  666. variable_session_id: 234747
  667. variable_sip_from_user: 08163464460
  668. variable_sip_from_port: 1024
  669. variable_sip_from_uri: 08163464460%4037.9.63.182%3A1024
  670. variable_sip_from_host: 37.9.63.182
  671. variable_video_media_flow: disabled
  672. variable_text_media_flow: disabled
  673. variable_channel_name: sofia/default/08163464460%4037.9.63.182%3A1024
  674. variable_sip_call_id: mmtmjZOUEY%4037.9.63.182
  675. variable_sip_local_network_addr: 192.168.8.3
  676. variable_sip_network_ip: 37.9.63.182
  677. variable_sip_network_port: 1024
  678. variable_sip_invite_stamp: 1617956909470323
  679. variable_sip_received_ip: 37.9.63.182
  680. variable_sip_received_port: 1024
  681. variable_sip_via_protocol: udp
  682. variable_sip_authorized: true
  683. variable_sip_acl_authed_by: default
  684. variable_sip_from_user_stripped: 08163464460
  685. variable_sip_from_tag: 09282695808968
  686. variable_sofia_profile_name: default
  687. variable_sofia_profile_url: sip%3Amod_sofia%40192.168.8.3%3A5060
  688. variable_recovery_profile_name: default
  689. variable_sip_full_via: SIP/2.0/UDP%2037.9.63.182%3A1024%3Bbranch%3Dz9hG4bK28092612431600495808968%3Brport%3D1024
  690. variable_sip_from_display: 08163464460
  691. variable_sip_full_from: %2208163464460%22%20%3Csip%3A08163464460%4037.9.63.182%3A1024%3E%3Btag%3D09282695808968
  692. variable_sip_full_to: %3Csip%3A23418887395%40192.168.8.3%3A5060%3E
  693. variable_sip_allow: INVITE,%20ACK,%20OPTIONS,%20BYE,%20CANCEL,%20REGISTER,%20INFO,%20NOTIFY,%20MESSAGE,%20SUBSCRIBE,%20REFER,%20PUBLISH,%20UPDATE
  694. variable_sip_req_user: 23418887395
  695. variable_sip_req_port: 5060
  696. variable_sip_req_uri: 23418887395%40192.168.8.3%3A5060
  697. variable_sip_req_host: 192.168.8.3
  698. variable_sip_to_user: 23418887395
  699. variable_sip_to_port: 5060
  700. variable_sip_to_uri: 23418887395%40192.168.8.3%3A5060
  701. variable_sip_to_host: 192.168.8.3
  702. variable_sip_contact_params: transport%3Dudp
  703. variable_sip_contact_user: nobody
  704. variable_sip_contact_port: 1024
  705. variable_sip_contact_uri: nobody%4037.9.63.182%3A1024
  706. variable_sip_contact_host: 37.9.63.182
  707. variable_sip_via_host: 37.9.63.182
  708. variable_sip_via_port: 1024
  709. variable_sip_via_rport: 1024
  710. variable_max_forwards: 70
  711. variable_presence_id: 08163464460%4037.9.63.182
  712. variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%20423599848%20423599849%20IN%20IP4%2037.9.63.182%0D%0As%3DVoipSIP%0D%0Ac%3DIN%20IP4%2037.9.63.182%0D%0At%3D0%200%0D%0Am%3Daudio%207138%20RTP/AVP%200%208%2018%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000/1%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0A
  713. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_g729.G729%408000h%4020i%408000b
  714. variable_rtp_use_codec_string: PCMA,PCMU,G729
  715. variable_remote_video_media_flow: inactive
  716. variable_remote_text_media_flow: inactive
  717. variable_remote_audio_media_flow: sendrecv
  718. variable_audio_media_flow: sendrecv
  719. variable_remote_media_ip: 37.9.63.182
  720. variable_remote_media_port: 7138
  721. variable_rtp_audio_recv_pt: 0
  722. variable_rtp_use_codec_name: PCMU
  723. variable_rtp_use_codec_rate: 8000
  724. variable_rtp_use_codec_ptime: 20
  725. variable_rtp_use_codec_channels: 1
  726. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
  727. variable_read_codec: PCMU
  728. variable_original_read_codec: PCMU
  729. variable_read_rate: 8000
  730. variable_original_read_rate: 8000
  731. variable_write_codec: PCMU
  732. variable_write_rate: 8000
  733. variable_dtmf_type: rfc2833
  734. variable_endpoint_disposition: RECEIVED
  735. variable_call_uuid: db9e84c5-b50d-470c-be53-2f3f2b7f5ee2
  736. Hunt-Direction: inbound
  737. Hunt-Logical-Direction: inbound
  738. Hunt-Username: 08163464460
  739. Hunt-Dialplan: XML
  740. Hunt-Caller-ID-Name: 08163464460
  741. Hunt-Caller-ID-Number: 08163464460
  742. Hunt-Orig-Caller-ID-Name: 08163464460
  743. Hunt-Orig-Caller-ID-Number: 08163464460
  744. Hunt-Network-Addr: 37.9.63.182
  745. Hunt-ANI: 08163464460
  746. Hunt-Destination-Number: 23418887395
  747. Hunt-Unique-ID: db9e84c5-b50d-470c-be53-2f3f2b7f5ee2
  748. Hunt-Source: mod_sofia
  749. Hunt-Context: default
  750. Hunt-Channel-Name: sofia/default/08163464460%4037.9.63.182%3A1024
  751. Hunt-Profile-Index: 1
  752. Hunt-Profile-Created-Time: 1617956909470323
  753. Hunt-Channel-Created-Time: 1617956909470323
  754. Hunt-Channel-Answered-Time: 0
  755. Hunt-Channel-Progress-Time: 0
  756. Hunt-Channel-Progress-Media-Time: 0
  757. Hunt-Channel-Hangup-Time: 0
  758. Hunt-Channel-Transfer-Time: 0
  759. Hunt-Channel-Resurrect-Time: 0
  760. Hunt-Channel-Bridged-Time: 0
  761. Hunt-Channel-Last-Hold: 0
  762. Hunt-Channel-Hold-Accum: 0
  763. Hunt-Screen-Bit: true
  764. Hunt-Privacy-Hide-Name: false
  765. Hunt-Privacy-Hide-Number: false
  766.  
  767.  
  768. 2021-04-09 09:28:29.512268 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_value:
  769. 2021-04-09 09:28:29.512268 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] key_name:
  770. 2021-04-09 09:28:29.512268 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] tag_name:
  771. 2021-04-09 09:28:29.512268 [INFO] switch_cpp.cpp:1443 [ASTPP] [xml_REQUEST] section: dialplan
  772. 2021-04-09 09:28:29.550254 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] blind_flag: 0
  773. 2021-04-09 09:28:29.550254 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Dialed number : 23418887395
  774. 2021-04-09 09:28:29.550254 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '23418887395' limit 1
  775. 2021-04-09 09:28:29.550254 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '23418887395' AND status=0 limit 1
  776. 2021-04-09 09:28:29.550254 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_queue_INFO] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '23418887395' and status=0 LIMIT 1
  777. 2021-04-09 09:28:29.550254 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Caller Id name / number  : 08163464460 / 08163464460
  778. 2021-04-09 09:28:29.550254 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  779. 2021-04-09 09:28:29.550254 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  780. 2021-04-09 09:28:29.550254 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 23418887395
  781. 2021-04-09 09:28:29.550254 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 018887395
  782. 2021-04-09 09:28:29.550254 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887395" LIMIT 1
  783. 2021-04-09 09:28:29.550254 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Call direction : inbound
  784. 2021-04-09 09:28:29.550254 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("37.9.63.182") 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 "23418887395"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  785. 2021-04-09 09:28:29.550254 [INFO] switch_cpp.cpp:1443 [ASTPP] [Accountcode : 8090000000]
  786. 2021-04-09 09:28:29.550254 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "8090000000" AND deleted = 0 limit 1
  787. 2021-04-09 09:28:29.570257 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE number = "8090000000" AND deleted = 0 limit 1
  788. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="23418887395" AND A.accountid = '49' limit 1
  789. 2021-04-09 09:28:29.570257 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 23418887395
  790. 2021-04-09 09:28:29.570257 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  791. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  792. 2021-04-09 09:28:29.570257 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 23418887395
  793. 2021-04-09 09:28:29.570257 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 018887395
  794. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887395" LIMIT 1
  795. 2021-04-09 09:28:29.570257 [INFO] switch_cpp.cpp:1443 [ASTPP] [Dialplan] New Call direction : inbound
  796. 2021-04-09 09:28:29.570257 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  797. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  798. 2021-04-09 09:28:29.570257 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 23418887395
  799. 2021-04-09 09:28:29.570257 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 018887395
  800. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="018887395" AND (accountid = 0 OR status = 1) LIMIT 1
  801. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
  802. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :175
  803. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^23418887395.*' OR patterns = '^2341888739.*' OR patterns = '^234188873.*' OR patterns = '^23418887.*' OR patterns = '^2341888.*' OR patterns = '^234188.*' OR patterns = '^23418.*' OR patterns = '^2341.*' OR patterns = '^234.*' OR patterns = '^23.*' OR patterns = '^2.*' OR patterns ='--') AND accountid = 175 ORDER BY LENGTH(PKGPTR.patterns) DESC
  804. 2021-04-09 09:28:29.570257 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '23418887395' OR number = '2341888739' OR number = '234188873' OR number = '23418887' OR number = '2341888' OR number = '234188' OR number = '23418' OR number = '2341' OR number = '234' OR number = '23' OR number = '2' OR number ='--') OR (number = '23418887395*' OR number = '2341888739*' OR number = '234188873*' OR number = '23418887*' OR number = '2341888*' OR number = '234188*' OR number = '23418*' OR number = '2341*' OR number = '234*' OR number = '23*' OR number = '2*' OR number ='--') OR (number = '08163464460' OR number = '0816346446' OR number = '081634644' OR number = '08163464' OR number = '0816346' OR number = '081634' OR number = '08163' OR number = '0816' OR number = '081' OR number = '08' OR number = '0' OR number ='--') OR (number = '08163464460*' OR number = '0816346446*' OR number = '081634644*' OR number = '08163464*' OR number = '0816346*' OR number = '081634*' OR number = '08163*' OR number = '0816*' OR number = '081*' OR number = '08*' OR number = '0*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  805. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] Call Barring Status : -1
  806. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '23418887395' OR routing_prefix = '2341888739' OR routing_prefix = '234188873' OR routing_prefix = '23418887' OR routing_prefix = '2341888' OR routing_prefix = '234188' OR routing_prefix = '23418' OR routing_prefix = '2341' OR routing_prefix = '234' OR routing_prefix = '23' OR routing_prefix = '2' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  807. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Account Information ===================
  808. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] User id : 49
  809. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Account code : 8090000000
  810. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Balance : 10000
  811. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  812. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Ratecard id : 1
  813. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] CID Pool : 0
  814. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] ========================================================
  815. 2021-04-09 09:28:29.590246 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH1: 1
  816. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  817. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  818. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_RATES] call_direction :inbound
  819. 2021-04-09 09:28:29.590246 [NOTICE] switch_cpp.cpp:1443 [ASTPP] HARSH2: 1
  820. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate FROM localization WHERE id = 1 AND status=0 limit 1
  821. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="018887395" LIMIT 1
  822. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] call_direction:::::: inbound
  823. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] =============== Rates Information ===================
  824. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] ID : 98
  825. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Connectcost : 0.00000
  826. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Includedseconds : 0
  827. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Cost : 0.00000
  828. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] comment : 018887395
  829. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Country Id : 141
  830. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Accid : 49
  831. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] ================================================================
  832. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 49 !!!
  833. 2021-04-09 09:28:29.590246 [NOTICE] switch_cpp.cpp:1443 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  834. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] Call Max length duration : 100 minutes
  835. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] INB_FREE:TRUE
  836. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] free_inbound:1
  837. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 49 AND status=0 LIMIT 1
  838. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "175" AND deleted = 0 limit 1
  839. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [sandip] accountidSELECT * FROM accounts WHERE id = "175" AND deleted = 0 limit 1
  840. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Actual CustomerInfo XML:49
  841. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Userinfo XML:49
  842. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [userinfo] Actual CustomerInfo XML : 49
  843. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] maxlength::::::::: 100
  844. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 175 limit 1
  845. 2021-04-09 09:28:29.590246 [WARNING] switch_cpp.cpp:1443 [ASTPP] [user_domain] user_domainInbound Routes81.9mobile.nativetalk.com.ng
  846. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_10
  847. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] custom_function_name::::::::::1343:::::::::::::::98
  848. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [PBX] call_type : 10
  849. 2021-04-09 09:28:29.590246 [INFO] switch_cpp.cpp:1443 [ASTPP] [PBX] call_type : IVR
  850. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_IVR_INFO] Query :SELECT * from pbx_ivr_specification WHERE id = 19 and status=0 LIMIT 1
  851. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '0' LIMIT 1
  852. 2021-04-09 09:28:29.590246 [DEBUG] switch_cpp.cpp:1443 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  853. <document type="freeswitch/xml">
  854. <section name="dialplan" description="ASTPP Dialplan">
  855. <context name="default">
  856. <extension name="018887395">
  857. <condition field="destination_number" expression="23418887395">
  858. <action application="set" data="effective_destination_number=23418887395"/>
  859. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  860. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
  861. <action application="set" data="callstart=2021-04-09 08:28:25"/>
  862. <action application="set" data="hangup_after_bridge=true"/>
  863. <action application="set" data="continue_on_fail=TRUE"/>
  864. <action application="set" data="account_id=49"/>
  865. <action application="set" data="parent_id=0"/>
  866. <action application="set" data="entity_id=3"/>
  867. <action application="set" data="call_processed=internal"/>
  868. <action application="set" data="call_direction=inbound"/>
  869. <action application="set" data="accountname=VSM"/>
  870. <action application="set" data="origination_rates_did=ID:98|CODE:^018887395.*|DESTINATION:018887395|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175"/>
  871. <action application="set" data="origination_rates=0"/>
  872. <action application="set" data="original_caller_id_name=08163464460"/>
  873. <action application="set" data="original_caller_id_number=08163464460"/>
  874. <action application="set" data="effective_caller_id_name=08163464460"/>
  875. <action application="set" data="effective_caller_id_number=08163464460"/>
  876. <action application="set" data="receiver_accid=175"/>
  877. <action application="set" data="domain_name=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  878. <action application="set" data="sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  879. <action application="export" data="domain_name=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  880. <action application="export" data="sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng"/>
  881. <action application="export" data="presence_data=x|||9mobile(8090000000)|||^018887395.* // 018887395 // 0.00000||||||DID"/>
  882. <action application="export" data="call_type=10"/>
  883. <action application="set" data="did_calltype=10"/>
  884. <action application="set" data="did_extensions=19"/>
  885. <action application="set" data="ringback=0"/>
  886. <action application="set" data="module_name=pbx"/>
  887.     <action application="answer"/>
  888. <action application="ivr" data="multichoice IVR0"/>
  889. </condition>
  890. </extension>
  891. </context>
  892. </section>
  893. </document>
  894. Dialplan: sofia/default/08163464460@37.9.63.182:1024 parsing [default->018887395] continue=false
  895. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Regex (PASS) [018887395] destination_number(23418887395) =~ /23418887395/ break=on-false
  896. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(effective_destination_number=23418887395)
  897. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  898. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  899. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(callstart=2021-04-09 08:28:25)
  900. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(hangup_after_bridge=true)
  901. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(continue_on_fail=TRUE)
  902. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(account_id=49)
  903. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(parent_id=0)
  904. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(entity_id=3)
  905. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(call_processed=internal)
  906. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(call_direction=inbound)
  907. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(accountname=VSM)
  908. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(origination_rates_did=ID:98|CODE:^018887395.*|DESTINATION:018887395|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175)
  909. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(origination_rates=0)
  910. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(original_caller_id_name=08163464460)
  911. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(original_caller_id_number=08163464460)
  912. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(effective_caller_id_name=08163464460)
  913. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(effective_caller_id_number=08163464460)
  914. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(receiver_accid=175)
  915. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  916. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  917. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action export(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  918. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action export(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  919. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action export(presence_data=x|||9mobile(8090000000)|||^018887395.* // 018887395 // 0.00000||||||DID)
  920. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action export(call_type=10)
  921. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(did_calltype=10)
  922. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(did_extensions=19)
  923. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(ringback=0)
  924. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action set(module_name=pbx)
  925. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action answer()
  926. Dialplan: sofia/default/08163464460@37.9.63.182:1024 Action ivr(multichoice IVR0)
  927. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_state_machine.c:287 (sofia/default/08163464460@37.9.63.182:1024) State Change CS_ROUTING -> CS_EXECUTE
  928. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_state_machine.c:644 (sofia/default/08163464460@37.9.63.182:1024) State ROUTING going to sleep
  929. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_state_machine.c:585 (sofia/default/08163464460@37.9.63.182:1024) Running State Change CS_EXECUTE (Cur 2 Tot 234747)
  930. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_state_machine.c:651 (sofia/default/08163464460@37.9.63.182:1024) State EXECUTE
  931. 2021-04-09 09:28:29.590246 [DEBUG] mod_sofia.c:209 sofia/default/08163464460@37.9.63.182:1024 SOFIA EXECUTE
  932. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_state_machine.c:329 sofia/default/08163464460@37.9.63.182:1024 Standard EXECUTE
  933. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(effective_destination_number=23418887395)
  934. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [effective_destination_number]=[23418887395]
  935. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(bridge_pre_execute_bleg_app=sched_hangup)
  936. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [bridge_pre_execute_bleg_app]=[sched_hangup]
  937. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
  938. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
  939. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(callstart=2021-04-09 08:28:25)
  940. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [callstart]=[2021-04-09 08:28:25]
  941. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(hangup_after_bridge=true)
  942. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [hangup_after_bridge]=[true]
  943. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(continue_on_fail=TRUE)
  944. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [continue_on_fail]=[TRUE]
  945. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(account_id=49)
  946. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [account_id]=[49]
  947. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(parent_id=0)
  948. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [parent_id]=[0]
  949. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(entity_id=3)
  950. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [entity_id]=[3]
  951. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(call_processed=internal)
  952. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [call_processed]=[internal]
  953. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(call_direction=inbound)
  954. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [call_direction]=[inbound]
  955. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(accountname=VSM)
  956. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [accountname]=[VSM]
  957. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(origination_rates_did=ID:98|CODE:^018887395.*|DESTINATION:018887395|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175)
  958. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [origination_rates_did]=[ID:98|CODE:^018887395.*|DESTINATION:018887395|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:10|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:141|ACCID:175]
  959. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(origination_rates=0)
  960. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [origination_rates]=[0]
  961. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(original_caller_id_name=08163464460)
  962. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [original_caller_id_name]=[08163464460]
  963. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(original_caller_id_number=08163464460)
  964. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [original_caller_id_number]=[08163464460]
  965. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(effective_caller_id_name=08163464460)
  966. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [effective_caller_id_name]=[08163464460]
  967. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(effective_caller_id_number=08163464460)
  968. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [effective_caller_id_number]=[08163464460]
  969. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(receiver_accid=175)
  970. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [receiver_accid]=[175]
  971. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  972. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [domain_name]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  973. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  974. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [sip_to_host]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  975. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 export(domain_name=Inbound Routes81.9mobile.nativetalk.com.ng)
  976. 2021-04-09 09:28:29.590246 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  977. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 export(sip_to_host=Inbound Routes81.9mobile.nativetalk.com.ng)
  978. 2021-04-09 09:28:29.590246 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [sip_to_host]=[Inbound Routes81.9mobile.nativetalk.com.ng]
  979. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 export(presence_data=x|||9mobile(8090000000)|||^018887395.* // 018887395 // 0.00000||||||DID)
  980. 2021-04-09 09:28:29.590246 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||9mobile(8090000000)|||^018887395.* // 018887395 // 0.00000||||||DID]
  981. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 export(call_type=10)
  982. 2021-04-09 09:28:29.590246 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_type]=[10]
  983. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(did_calltype=10)
  984. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [did_calltype]=[10]
  985. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(did_extensions=19)
  986. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [did_extensions]=[19]
  987. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(ringback=0)
  988. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [ringback]=[0]
  989. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 set(module_name=pbx)
  990. 2021-04-09 09:28:29.590246 [DEBUG] mod_dptools.c:1672 SET sofia/default/08163464460@37.9.63.182:1024 [module_name]=[pbx]
  991. EXECUTE [depth=0] sofia/default/08163464460@37.9.63.182:1024 answer()
  992. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_media.c:8658 AUDIO RTP [sofia/default/08163464460@37.9.63.182:1024] 192.168.8.3 port 31294 -> 37.9.63.182 port 7138 codec: 0 ms: 20
  993. 2021-04-09 09:28:29.590246 [DEBUG] switch_rtp.c:4408 Starting timer [soft] 160 bytes per 20ms
  994. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_media.c:8972 sofia/default/08163464460@37.9.63.182:1024 Set 2833 dtmf send payload to 101
  995. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_media.c:8979 sofia/default/08163464460@37.9.63.182:1024 Set 2833 dtmf receive payload to 101
  996. 2021-04-09 09:28:29.590246 [DEBUG] switch_core_media.c:9002 sofia/default/08163464460@37.9.63.182:1024 Set rtp dtmf delay to 40
  997. 2021-04-09 09:28:29.590246 [DEBUG] mod_sofia.c:905 Local SDP sofia/default/08163464460@37.9.63.182:1024:
  998.