From Gruff Parakeet, 2 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: green; font-weight: bold;"> 2021-10-21 08:11:02.066665 [INFO] mod_dialplan_xml.c:637 Processing 442031501300 <442031501300>->00443453000101 in context default
  2. 2021-10-21 08:11:02.066665 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fd4240c7a70 Connected.
  3. 2021-10-21 08:11:02.066665 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  4. 2021-10-21 08:11:02.066665 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  5. 2021-10-21 08:11:02.066665 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
  6. 'Event-Name: REQUEST_PARAMS
  7. Core-UUID: 383771d0-00bf-11ec-97b1-7dfb9f6e00f0
  8. FreeSWITCH-Hostname: astpp1.local
  9. FreeSWITCH-Switchname: astpp1.local
  10. FreeSWITCH-IPv4: 89.46.223.193
  11. FreeSWITCH-IPv6: %3A%3A1
  12. Event-Date-Local: 2021-10-21%2008%3A11%3A02
  13. Event-Date-GMT: Thu,%2021%20Oct%202021%2012%3A11%3A02%20GMT
  14. Event-Date-Timestamp: 1634818262066665
  15. Event-Calling-File: mod_dialplan_xml.c
  16. Event-Calling-Function: dialplan_xml_locate
  17. Event-Calling-Line-Number: 608
  18. Event-Sequence: 20515436
  19. Channel-State: CS_ROUTING
  20. Channel-Call-State: RINGING
  21. Channel-State-Number: 2
  22. Channel-Name: sofia/default/442031501300%40212.199.161.206
  23. Unique-ID: f5563380-3267-11ec-8918-7dfb9f6e00f0
  24. Call-Direction: inbound
  25. Presence-Call-Direction: inbound
  26. Channel-HIT-Dialplan: true
  27. Channel-Presence-ID: 442031501300%40212.199.161.206
  28. Channel-Call-UUID: f5563380-3267-11ec-8918-7dfb9f6e00f0
  29. Answer-State: ringing
  30. Channel-Read-Codec-Name: PCMA
  31. Channel-Read-Codec-Rate: 8000
  32. Channel-Read-Codec-Bit-Rate: 64000
  33. Channel-Write-Codec-Name: PCMA
  34. Channel-Write-Codec-Rate: 8000
  35. Channel-Write-Codec-Bit-Rate: 64000
  36. Caller-Direction: inbound
  37. Caller-Logical-Direction: inbound
  38. Caller-Username: 442031501300
  39. Caller-Dialplan: XML
  40. Caller-Caller-ID-Name: 442031501300
  41. Caller-Caller-ID-Number: 442031501300
  42. Caller-Orig-Caller-ID-Name: 442031501300
  43. Caller-Orig-Caller-ID-Number: 442031501300
  44. Caller-Network-Addr: 136.243.196.198
  45. Caller-ANI: 442031501300
  46. Caller-Destination-Number: 00443453000101
  47. Caller-Unique-ID: f5563380-3267-11ec-8918-7dfb9f6e00f0
  48. Caller-Source: mod_sofia
  49. Caller-Context: default
  50. Caller-Channel-Name: sofia/default/442031501300%40212.199.161.206
  51. Caller-Profile-Index: 1
  52. Caller-Profile-Created-Time: 1634818262066665
  53. Caller-Channel-Created-Time: 1634818262066665
  54. Caller-Channel-Answered-Time: 0
  55. Caller-Channel-Progress-Time: 0
  56. Caller-Channel-Progress-Media-Time: 0
  57. Caller-Channel-Hangup-Time: 0
  58. Caller-Channel-Transfer-Time: 0
  59. Caller-Channel-Resurrect-Time: 0
  60. Caller-Channel-Bridged-Time: 0
  61. Caller-Channel-Last-Hold: 0
  62. Caller-Channel-Hold-Accum: 0
  63. Caller-Screen-Bit: true
  64. Caller-Privacy-Hide-Name: false
  65. Caller-Privacy-Hide-Number: false
  66. variable_direction: inbound
  67. variable_uuid: f5563380-3267-11ec-8918-7dfb9f6e00f0
  68. variable_session_id: 727135
  69. variable_sip_from_user: 442031501300
  70. variable_sip_from_uri: 442031501300%40212.199.161.206
  71. variable_sip_from_host: 212.199.161.206
  72. variable_video_media_flow: sendrecv
  73. variable_channel_name: sofia/default/442031501300%40212.199.161.206
  74. variable_sip_call_id: 3d2dc31b1eb18d8c7d9567db0a103cc5%40212.199.161.206%3A5060
  75. variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,mod_g729.G729%408000h%4020i%408000b
  76. variable_sip_local_network_addr: 89.46.223.193
  77. variable_sip_network_ip: 136.243.196.198
  78. variable_sip_network_port: 5060
  79. variable_sip_invite_stamp: 1634818262066665
  80. variable_sip_received_ip: 136.243.196.198
  81. variable_sip_received_port: 5060
  82. variable_sip_via_protocol: udp
  83. variable_sip_authorized: true
  84. variable_sip_acl_authed_by: default
  85. variable_sip_from_user_stripped: 442031501300
  86. variable_sip_from_tag: as7d0e3ac6
  87. variable_sofia_profile_name: default
  88. variable_recovery_profile_name: default
  89. variable_sip_invite_route_uri: %3Csip%3A136.243.196.198%3Blr%3Bdid%3De22.b2579746%3E
  90. variable_sip_invite_record_route: %3Csip%3A136.243.196.198%3Blr%3Bdid%3De22.b2579746%3E
  91. variable_sip_full_via: SIP/2.0/UDP%20136.243.196.198%3A5060%3Bbranch%3Dz9hG4bKdf0c.252d9292.0,SIP/2.0/UDP%20212.199.161.206%3A5060%3Breceived%3D212.199.161.206%3Bbranch%3Dz9hG4bK51c41089%3Brport%3D5060
  92. variable_sip_from_display: 442031501300
  93. variable_sip_full_from: %22442031501300%22%20%3Csip%3A442031501300%40212.199.161.206%3E%3Btag%3Das7d0e3ac6
  94. variable_sip_full_to: %3Csip%3A00443453000101%40136.243.196.198%3E
  95. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH
  96. variable_sip_req_user: 00443453000101
  97. variable_sip_req_uri: 00443453000101%40136.243.196.198
  98. variable_sip_req_host: 136.243.196.198
  99. variable_sip_to_user: 00443453000101
  100. variable_sip_to_uri: 00443453000101%40136.243.196.198
  101. variable_sip_to_host: 136.243.196.198
  102. variable_sip_contact_user: 442031501300
  103. variable_sip_contact_port: 5060
  104. variable_sip_contact_uri: 442031501300%40212.199.161.206%3A5060
  105. variable_sip_contact_host: 212.199.161.206
  106. variable_sip_user_agent: FPBX-2.9.0(1.8.15.0)
  107. variable_sip_via_host: 136.243.196.198
  108. variable_sip_via_port: 5060
  109. variable_max_forwards: 30
  110. variable_presence_id: 442031501300%40212.199.161.206
  111. variable_sip_h_X-AUTH-IP: 212.199.161.206
  112. variable_sip_h_P-Accountcode: 97237449191
  113. variable_sip_h_P-effective_caller_id_name: 442031501300
  114. variable_sip_h_P-effective_caller_id_number: 442031501300
  115. variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%20690547655%20690547655%20IN%20IP4%20212.199.161.206%0D%0As%3DAsterisk%20PBX%201.8.15.0%0D%0Ac%3DIN%20IP4%20212.199.161.206%0D%0At%3D0%200%0D%0Am%3Daudio%2010030%20RTP/AVP%208%200%2018%203%20112%205%2010%207%20110%2097%20111%209%20118%20117%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A3%20GSM/8000%0D%0Aa%3Drtpmap%3A112%20AAL2-G726-32/8000%0D%0Aa%3Drtpmap%3A5%20DVI4/8000%0D%0Aa%3Drtpmap%3A10%20L16/8000%0D%0Aa%3Drtpmap%3A7%20LPC/8000%0D%0Aa%3Drtpmap%3A110%20speex/8000%0D%0Aa%3Drtpmap%3A97%20iLBC/8000%0D%0Aa%3Dfmtp%3A97%20mode%3D30%0D%0Aa%3Drtpmap%3A111%20G726-32/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A118%20L16/16000%0D%0Aa%3Drtpmap%3A117%20speex/16000%0D%0Aa%3Dptime%3A20%0D%0A
  116. variable_rtp_use_codec_string: PCMA,PCMU,G729
  117. variable_audio_media_flow: sendrecv
  118. variable_remote_media_ip: 212.199.161.206
  119. variable_remote_media_port: 10030
  120. variable_rtp_audio_recv_pt: 8
  121. variable_rtp_use_codec_name: PCMA
  122. variable_rtp_use_codec_rate: 8000
  123. variable_rtp_use_codec_ptime: 20
  124. variable_rtp_use_codec_channels: 1
  125. variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
  126. variable_read_codec: PCMA
  127. variable_original_read_codec: PCMA
  128. variable_read_rate: 8000
  129. variable_original_read_rate: 8000
  130. variable_write_codec: PCMA
  131. variable_write_rate: 8000
  132. variable_dtmf_type: rfc2833
  133. variable_endpoint_disposition: RECEIVED
  134. variable_call_uuid: f5563380-3267-11ec-8918-7dfb9f6e00f0
  135. Hunt-Direction: inbound
  136. Hunt-Logical-Direction: inbound
  137. Hunt-Username: 442031501300
  138. Hunt-Dialplan: XML
  139. Hunt-Caller-ID-Name: 442031501300
  140. Hunt-Caller-ID-Number: 442031501300
  141. Hunt-Orig-Caller-ID-Name: 442031501300
  142. Hunt-Orig-Caller-ID-Number: 442031501300
  143. Hunt-Network-Addr: 136.243.196.198
  144. Hunt-ANI: 442031501300
  145. Hunt-Destination-Number: 00443453000101
  146. Hunt-Unique-ID: f5563380-3267-11ec-8918-7dfb9f6e00f0
  147. Hunt-Source: mod_sofia
  148. Hunt-Context: default
  149. Hunt-Channel-Name: sofia/default/442031501300%40212.199.161.206
  150. Hunt-Profile-Index: 1
  151. Hunt-Profile-Created-Time: 1634818262066665
  152. Hunt-Channel-Created-Time: 1634818262066665
  153. Hunt-Channel-Answered-Time: 0
  154. Hunt-Channel-Progress-Time: 0
  155. Hunt-Channel-Progress-Media-Time: 0
  156. Hunt-Channel-Hangup-Time: 0
  157. Hunt-Channel-Transfer-Time: 0
  158. Hunt-Channel-Resurrect-Time: 0
  159. Hunt-Channel-Bridged-Time: 0
  160. Hunt-Channel-Last-Hold: 0
  161. Hunt-Channel-Hold-Accum: 0
  162. Hunt-Screen-Bit: true
  163. Hunt-Privacy-Hide-Name: false
  164. Hunt-Privacy-Hide-Number: false
  165.  
  166. '
  167. 2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
  168. 2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
  169. 2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
  170. 2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
  171. 2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] blind_flag: 0
  172. 2021-10-21 08:11:02.066665 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 00443453000101
  173. 2021-10-21 08:11:02.066665 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '00443453000101' limit 1
  174. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number as number FROM accessnumber WHERE access_number = '00443453000101' AND status=0 limit 1
  175. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '212.199.161.206' limit 1
  176. 2021-10-21 08:11:02.086664 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FAXER] in custom Caller ID
  177. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 442031501300 / 442031501300
  178. 2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
  179. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
  180. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....00443453000101
  181. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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 ="00443453000101" LIMIT 1
  182. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="00443453000101"  AND domains.domain="136.243.196.198" limit 1
  183. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound
  184. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Accountcode1 : 97237449191]
  185. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "97237449191" OR number = "97237449191") AND deleted = 0 limit 1
  186. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^00443453000101.*' OR blocked_patterns = '^0044345300010.*' OR blocked_patterns = '^004434530001.*' OR blocked_patterns = '^00443453000.*' OR blocked_patterns = '^0044345300.*' OR blocked_patterns = '^004434530.*' OR blocked_patterns = '^00443453.*' OR blocked_patterns = '^0044345.*' OR blocked_patterns = '^004434.*' OR blocked_patterns = '^00443.*' OR blocked_patterns = '^0044.*' OR blocked_patterns = '^004.*' OR blocked_patterns = '^00.*' OR blocked_patterns = '^0.*' OR blocked_patterns ='--') AND accountid = 1179 limit 1
  187. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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 ="00443453000101" AND A.accountid = '1179' limit 1
  188. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 00443453000101
  189. 2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
  190. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
  191. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....00443453000101
  192. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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 ="00443453000101" LIMIT 1
  193. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="00443453000101"  AND domains.domain="136.243.196.198" limit 1
  194. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] New Call direction : outbound
  195. 2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
  196. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
  197. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....00443453000101
  198. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="00443453000101" AND (accountid = 0 OR status = 1) LIMIT 1
  199. 2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] Localization Id: 3
  200. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 3 AND status=0 limit 1
  201. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"00/*,+/*".....00443453000101
  202. 2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 00443453000101
  203. 2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 443453000101
  204. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"00/*,+/*".....443453000101
  205. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [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 = '^443453000101.*' OR patterns = '^44345300010.*' OR patterns = '^4434530001.*' OR patterns = '^443453000.*' OR patterns = '^44345300.*' OR patterns = '^4434530.*' OR patterns = '^443453.*' OR patterns = '^44345.*' OR patterns = '^4434.*' OR patterns = '^443.*' OR patterns = '^44.*' OR patterns = '^4.*' OR patterns ='--') AND accountid = 1179 ORDER BY LENGTH(PKGPTR.patterns) DESC
  206. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '443453000101' OR number = '44345300010' OR number = '4434530001' OR number = '443453000' OR number = '44345300' OR number = '4434530' OR number = '443453' OR number = '44345' OR number = '4434' OR number = '443' OR number = '44' OR number = '4' OR number ='--') OR (number = '443453000101*' OR number = '44345300010*' OR number = '4434530001*' OR number = '443453000*' OR number = '44345300*' OR number = '4434530*' OR number = '443453*' OR number = '44345*' OR number = '4434*' OR number = '443*' OR number = '44*' OR number = '4*' OR number ='--') OR (number = '442031501300' OR number = '44203150130' OR number = '4420315013' OR number = '442031501' OR number = '44203150' OR number = '4420315' OR number = '442031' OR number = '44203' OR number = '4420' OR number = '442' OR number = '44' OR number = '4' OR number ='--') OR (number = '442031501300*' OR number = '44203150130*' OR number = '4420315013*' OR number = '442031501*' OR number = '44203150*' OR number = '4420315*' OR number = '442031*' OR number = '44203*' OR number = '4420*' OR number = '442*' OR number = '44*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  207. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Call Barring Status : -1
  208. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '443453000101' OR routing_prefix = '44345300010' OR routing_prefix = '4434530001' OR routing_prefix = '443453000' OR routing_prefix = '44345300' OR routing_prefix = '4434530' OR routing_prefix = '443453' OR routing_prefix = '44345' OR routing_prefix = '4434' OR routing_prefix = '443' OR routing_prefix = '44' OR routing_prefix = '4' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  209. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
  210. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 1179
  211. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 97237449191
  212. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 110.07787
  213. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  214. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 75
  215. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] CID Pool : 0
  216. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
  217. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
  218. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] call_direction :outbound
  219. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
  220. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^443453000101.*' OR pattern = '^44345300010.*' OR pattern = '^4434530001.*' OR pattern = '^443453000.*' OR pattern = '^44345300.*' OR pattern = '^4434530.*' OR pattern = '^443453.*' OR pattern = '^44345.*' OR pattern = '^4434.*' OR pattern = '^443.*' OR pattern = '^44.*' OR pattern = '^4.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 75 OR accountid=1179)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
  221. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] call_direction:::::: outbound
  222. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
  223. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 1598243
  224. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
  225. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
  226. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.02500
  227. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : United Kingdom Wide Calls
  228. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Country Id : 0
  229. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 1179
  230. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Trunk ID: 0
  231. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Routing type:
  232. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
  233. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Your110.07787 balance Accountid 1179 !!!
  234. 2021-10-21 08:11:02.086664 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 180 mins!
  235. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 180 minutes
  236. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [DIALPLAN] User Rate ID : 1598243
  237. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id  :0
  238. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=75 ORDER by id asc
  239. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^443453000101.*' OR pattern = '^44345300010.*' OR pattern = '^4434530001.*' OR pattern = '^443453000.*' OR pattern = '^44345300.*' OR pattern = '^4434530.*' OR pattern = '^443453.*' OR pattern = '^44345.*' OR pattern = '^4434.*' OR pattern = '^443.*' OR pattern = '^44.*' OR pattern = '^4.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (43) ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence
  240. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [HYBRID_ROUTING]LCR with Cost in 2nd stage
  241. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Termination Rates Information ===================
  242. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 1527843
  243. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Code : ^443.*
  244. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Destination : United Kingdom Wide Calls
  245. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
  246. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Free Seconds : 0
  247. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Prefix : ^443.*
  248. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Strip : 0
  249. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Prepend : 0
  250. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Carrier id : 43
  251. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] carrier_name : BezeqINT2
  252. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] dialplan_variable :
  253. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Failover gateway : BezeqINT1
  254. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Vendor id : 1403
  255. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Max channels : 0
  256. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] Trunk Name : BezeqINT
  257. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================END OF TERMINATION RATES=======================
  258. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1179 AND status=0 LIMIT 1
  259. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] maxlength::::::::: 180
  260. 2021-10-21 08:11:02.086664 [INFO] switch_cpp.cpp:1365 [ASTPP] [PBX] call_direction : outbound
  261. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 75 AND status = 0
  262. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 1403) AND status=0 limit 1
  263. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :0/0.....443453000101
  264. 2021-10-21 08:11:02.086664 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:
  265. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  266. <document type="freeswitch/xml">
  267. <section name="dialplan" description="ASTPP Dialplan">
  268. <context name="default">
  269. <extension name="443453000101">
  270. <condition field="destination_number" expression="00443453000101">
  271. <action application="set" data="effective_destination_number=443453000101"/>
  272. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  273. <action application="set" data="bridge_pre_execute_bleg_data=+10800 normal_clearing"/>
  274. <action application="set" data="callstart=2021-10-21 12:11:02"/>
  275. <action application="set" data="hangup_after_bridge=true"/>
  276. <action application="set" data="continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602"/>
  277. <action application="set" data="account_id=1179"/>
  278. <action application="set" data="parent_id=0"/>
  279. <action application="set" data="entity_id=0"/>
  280. <action application="set" data="call_processed=internal"/>
  281. <action application="set" data="call_direction=outbound"/>
  282. <action application="set" data="accountname=default"/>
  283. <action application="set" data="filename1=astpp.pbx.lua"/>
  284. <action application="set" data="origination_rates=ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179"/>
  285. <action application="set" data="original_caller_id_name=442031501300"/>
  286. <action application="set" data="original_caller_id_number=442031501300"/>
  287.  
  288. <action application="export" data="is_recording=1"/>
  289. <action application="export" data="media_bug_answer_req=true"/>
  290. <action application="export" data="RECORD_STEREO=true"/>
  291. <action application="export" data="record_sample_rate=8000"/>
  292. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
  293. <action application="set" data="effective_caller_id_name=442031501300"/>
  294. <action application="set" data="effective_caller_id_number=442031501300"/>
  295. <action application="set" data="calltype=STANDARD"/>
  296. <action application="set" data="termination_rates=ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403"/>
  297. <action application="set" data="trunk_id=43"/>
  298. <action application="set" data="provider_id=1403"/>
  299. <action application="set" data="rate_flag=0"/>
  300. <action application="set" data="force_trunk_flag=1598243"/>
  301. <action application="export" data="presence_data=trunk_id=43"/>
  302. <action application="set" data="intcall=0"/>
  303. <action application="export" data="presence_data=x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206"/>
  304. <action application="bridge" data="[leg_timeout=0]sofia/gateway/BezeqINT2/443453000101;fs_path=sip:136.243.196.198:5060"/>
  305. <action application="bridge" data="[leg_timeout=0]sofia/gateway/BezeqINT1/443453000101;fs_path=sip:136.243.196.198:5060"/>
  306. </condition>
  307. </extension>
  308. </context>
  309. </section>
  310. </document>
  311. 2021-10-21 08:11:02.086664 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fd4240c7a70 released.
  312. Dialplan: sofia/default/442031501300@212.199.161.206 parsing [default->443453000101] continue=false
  313. Dialplan: sofia/default/442031501300@212.199.161.206 Regex (PASS) [443453000101] destination_number(00443453000101) =~ /00443453000101/ break=on-false
  314. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(effective_destination_number=443453000101)
  315. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  316. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
  317. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(callstart=2021-10-21 12:11:02)
  318. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(hangup_after_bridge=true)
  319. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
  320. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(account_id=1179)
  321. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(parent_id=0)
  322. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(entity_id=0)
  323. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(call_processed=internal)
  324. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(call_direction=outbound)
  325. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(accountname=default)
  326. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(filename1=astpp.pbx.lua)
  327. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(origination_rates=ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179)
  328. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(original_caller_id_name=442031501300)
  329. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(original_caller_id_number=442031501300)
  330. Dialplan: sofia/default/442031501300@212.199.161.206 Action export(is_recording=1)
  331. Dialplan: sofia/default/442031501300@212.199.161.206 Action export(media_bug_answer_req=true)
  332. Dialplan: sofia/default/442031501300@212.199.161.206 Action export(RECORD_STEREO=true)
  333. Dialplan: sofia/default/442031501300@212.199.161.206 Action export(record_sample_rate=8000)
  334. Dialplan: sofia/default/442031501300@212.199.161.206 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
  335. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(effective_caller_id_name=442031501300)
  336. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(effective_caller_id_number=442031501300)
  337. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(calltype=STANDARD)
  338. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(termination_rates=ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403)
  339. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(trunk_id=43)
  340. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(provider_id=1403)
  341. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(rate_flag=0)
  342. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(force_trunk_flag=1598243)
  343. Dialplan: sofia/default/442031501300@212.199.161.206 Action export(presence_data=trunk_id=43)
  344. Dialplan: sofia/default/442031501300@212.199.161.206 Action set(intcall=0)
  345. Dialplan: sofia/default/442031501300@212.199.161.206 Action export(presence_data=x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206)
  346. Dialplan: sofia/default/442031501300@212.199.161.206 Action bridge([leg_timeout=0]sofia/gateway/BezeqINT2/443453000101;fs_path=sip:136.243.196.198:5060)
  347. Dialplan: sofia/default/442031501300@212.199.161.206 Action bridge([leg_timeout=0]sofia/gateway/BezeqINT1/443453000101;fs_path=sip:136.243.196.198:5060)
  348. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:286 (sofia/default/442031501300@212.199.161.206) State Change CS_ROUTING -> CS_EXECUTE
  349. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:643 (sofia/default/442031501300@212.199.161.206) State ROUTING going to sleep
  350. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442031501300@212.199.161.206) Running State Change CS_EXECUTE (Cur 3 Tot 727135)
  351. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:650 (sofia/default/442031501300@212.199.161.206) State EXECUTE
  352. 2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:198 sofia/default/442031501300@212.199.161.206 SOFIA EXECUTE
  353. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:328 sofia/default/442031501300@212.199.161.206 Standard EXECUTE
  354. EXECUTE sofia/default/442031501300@212.199.161.206 set(effective_destination_number=443453000101)
  355. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [effective_destination_number]=[443453000101]
  356. EXECUTE sofia/default/442031501300@212.199.161.206 set(bridge_pre_execute_bleg_app=sched_hangup)
  357. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [bridge_pre_execute_bleg_app]=[sched_hangup]
  358. EXECUTE sofia/default/442031501300@212.199.161.206 set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
  359. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [bridge_pre_execute_bleg_data]=[+10800 normal_clearing]
  360. EXECUTE sofia/default/442031501300@212.199.161.206 set(callstart=2021-10-21 12:11:02)
  361. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [callstart]=[2021-10-21 12:11:02]
  362. EXECUTE sofia/default/442031501300@212.199.161.206 set(hangup_after_bridge=true)
  363. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [hangup_after_bridge]=[true]
  364. EXECUTE sofia/default/442031501300@212.199.161.206 set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
  365. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [continue_on_fail]=[1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602]
  366. EXECUTE sofia/default/442031501300@212.199.161.206 set(account_id=1179)
  367. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [account_id]=[1179]
  368. EXECUTE sofia/default/442031501300@212.199.161.206 set(parent_id=0)
  369. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [parent_id]=[0]
  370. EXECUTE sofia/default/442031501300@212.199.161.206 set(entity_id=0)
  371. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [entity_id]=[0]
  372. EXECUTE sofia/default/442031501300@212.199.161.206 set(call_processed=internal)
  373. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [call_processed]=[internal]
  374. EXECUTE sofia/default/442031501300@212.199.161.206 set(call_direction=outbound)
  375. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [call_direction]=[outbound]
  376. EXECUTE sofia/default/442031501300@212.199.161.206 set(accountname=default)
  377. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [accountname]=[default]
  378. EXECUTE sofia/default/442031501300@212.199.161.206 set(filename1=astpp.pbx.lua)
  379. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [filename1]=[astpp.pbx.lua]
  380. EXECUTE sofia/default/442031501300@212.199.161.206 set(origination_rates=ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179)
  381. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [origination_rates]=[ID:1598243|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.02500|INC:10|INITIALBLOCK:30|RATEGROUP:75|MARKUP:0|CI:0|ACCID:1179]
  382. EXECUTE sofia/default/442031501300@212.199.161.206 set(original_caller_id_name=442031501300)
  383. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [original_caller_id_name]=[442031501300]
  384. EXECUTE sofia/default/442031501300@212.199.161.206 set(original_caller_id_number=442031501300)
  385. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [original_caller_id_number]=[442031501300]
  386. EXECUTE sofia/default/442031501300@212.199.161.206 export(is_recording=1)
  387. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [is_recording]=[1]
  388. EXECUTE sofia/default/442031501300@212.199.161.206 export(media_bug_answer_req=true)
  389. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [media_bug_answer_req]=[true]
  390. EXECUTE sofia/default/442031501300@212.199.161.206 export(RECORD_STEREO=true)
  391. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RECORD_STEREO]=[true]
  392. EXECUTE sofia/default/442031501300@212.199.161.206 export(record_sample_rate=8000)
  393. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [record_sample_rate]=[8000]
  394. EXECUTE sofia/default/442031501300@212.199.161.206 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav)
  395. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav]
  396. EXECUTE sofia/default/442031501300@212.199.161.206 set(effective_caller_id_name=442031501300)
  397. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [effective_caller_id_name]=[442031501300]
  398. EXECUTE sofia/default/442031501300@212.199.161.206 set(effective_caller_id_number=442031501300)
  399. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [effective_caller_id_number]=[442031501300]
  400. EXECUTE sofia/default/442031501300@212.199.161.206 set(calltype=STANDARD)
  401. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [calltype]=[STANDARD]
  402. EXECUTE sofia/default/442031501300@212.199.161.206 set(termination_rates=ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403)
  403. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [termination_rates]=[ID:1527843|CODE:^443.*|DESTINATION:United Kingdom Wide Calls|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.01000|INC:1|INITIALBLOCK:1|TRUNK:43|PROVIDER:1403]
  404. EXECUTE sofia/default/442031501300@212.199.161.206 set(trunk_id=43)
  405. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [trunk_id]=[43]
  406. EXECUTE sofia/default/442031501300@212.199.161.206 set(provider_id=1403)
  407. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [provider_id]=[1403]
  408. EXECUTE sofia/default/442031501300@212.199.161.206 set(rate_flag=0)
  409. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [rate_flag]=[0]
  410. EXECUTE sofia/default/442031501300@212.199.161.206 set(force_trunk_flag=1598243)
  411. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [force_trunk_flag]=[1598243]
  412. EXECUTE sofia/default/442031501300@212.199.161.206 export(presence_data=trunk_id=43)
  413. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[trunk_id=43]
  414. EXECUTE sofia/default/442031501300@212.199.161.206 set(intcall=0)
  415. 2021-10-21 08:11:02.106679 [DEBUG] mod_dptools.c:1548 SET sofia/default/442031501300@212.199.161.206 [intcall]=[0]
  416. EXECUTE sofia/default/442031501300@212.199.161.206 export(presence_data=x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206)
  417. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206]
  418. EXECUTE sofia/default/442031501300@212.199.161.206 bridge([leg_timeout=0]sofia/gateway/BezeqINT2/443453000101;fs_path=sip:136.243.196.198:5060)
  419. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [is_recording]=[1] to event
  420. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
  421. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
  422. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
  423. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav] to event
  424. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [presence_data]=[x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206] to event
  425. 2021-10-21 08:11:02.106679 [DEBUG] switch_channel.c:1250 sofia/default/442031501300@212.199.161.206 EXPORTING[export_vars] [presence_data]=[x|||Asserson(97237449191)|||^443.* // United Kingdom Wide Calls // 0.02500 // trunk_id=0|||BezeqINT // ^443.* // United Kingdom Wide Calls // 0.01000 // trunk_id=43|||STD|||212.199.161.206] to event
  426. 2021-10-21 08:11:02.106679 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  427. 2021-10-21 08:11:02.106679 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  428. 2021-10-21 08:11:02.106679 [NOTICE] switch_channel.c:1104 New Channel sofia/default/443453000101 [f55c452c-3267-11ec-893e-7dfb9f6e00f0]
  429. 2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:4819 (sofia/default/443453000101) State Change CS_NEW -> CS_INIT
  430. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_INIT (Cur 4 Tot 727136)
  431. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:627 (sofia/default/443453000101) State INIT
  432. 2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:90 sofia/default/443453000101 SOFIA INIT
  433. 2021-10-21 08:11:02.106679 [DEBUG] sofia_glue.c:1264 sip:136.243.196.198:5060 Setting proxy route to sofia/default/443453000101
  434. 2021-10-21 08:11:02.106679 [DEBUG] sofia_glue.c:1295 sofia/default/443453000101 sending invite version: 1.6.20  64bit
  435. Local SDP:
  436. v=0
  437. o=FreeSWITCH 1634787550 1634787551 IN IP4 89.46.223.193
  438. s=FreeSWITCH
  439. c=IN IP4 89.46.223.193
  440. t=0 0
  441. m=audio 30712 RTP/AVP 8 101
  442. a=rtpmap:8 PCMA/8000
  443. a=rtpmap:101 telephone-event/8000
  444. a=fmtp:101 0-16
  445. a=ptime:20
  446. a=sendrecv
  447.  
  448. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:40 sofia/default/443453000101 Standard INIT
  449. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:48 (sofia/default/443453000101) State Change CS_INIT -> CS_ROUTING
  450. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:627 (sofia/default/443453000101) State INIT going to sleep
  451. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_ROUTING (Cur 4 Tot 727136)
  452. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:643 (sofia/default/443453000101) State ROUTING
  453. 2021-10-21 08:11:02.106679 [DEBUG] mod_sofia.c:143 sofia/default/443453000101 SOFIA ROUTING
  454. 2021-10-21 08:11:02.106679 [DEBUG] switch_ivr_originate.c:67 (sofia/default/443453000101) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  455. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:643 (sofia/default/443453000101) State ROUTING going to sleep
  456. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 727136)
  457. 2021-10-21 08:11:02.106679 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [calling][0]
  458. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:662 (sofia/default/443453000101) State CONSUME_MEDIA
  459. 2021-10-21 08:11:02.106679 [DEBUG] switch_core_state_machine.c:662 (sofia/default/443453000101) State CONSUME_MEDIA going to sleep
  460. 2021-10-21 08:11:06.546664 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [proceeding][180]
  461. 2021-10-21 08:11:06.546664 [DEBUG] sofia.c:7094 Remote SDP:
  462. v=0
  463. o=sbc-uk-bm-th14a 312340480 1634818263 IN IP4 212.179.237.23
  464. s=sip call
  465. c=IN IP4 212.179.237.23
  466. t=0 0
  467. m=audio 60138 RTP/AVP 8 101
  468. a=rtpmap:8 PCMA/8000
  469. a=rtpmap:101 telephone-event/8000
  470. a=fmtp:101 0-16
  471. a=ptime:20
  472.  
  473. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  474. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  475. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  476. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/443453000101 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  477. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_codec.c:111 sofia/default/443453000101 Original read codec set to PCMA:8
  478. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  479. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:4767 sofia/default/443453000101 Set 2833 dtmf send payload to 101 recv payload to 101
  480. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/443453000101] 89.46.223.193 port 30712 -> 212.179.237.23 port 60138 codec: 8 ms: 20
  481. 2021-10-21 08:11:06.546664 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  482. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:7180 sofia/default/443453000101 Set 2833 dtmf send payload to 101
  483. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:7187 sofia/default/443453000101 Set 2833 dtmf receive payload to 101
  484. 2021-10-21 08:11:06.546664 [DEBUG] switch_core_media.c:7210 sofia/default/443453000101 Set rtp dtmf delay to 40
  485. 2021-10-21 08:11:06.546664 [NOTICE] sofia_media.c:92 Pre-Answer sofia/default/443453000101!
  486. 2021-10-21 08:11:06.546664 [DEBUG] switch_channel.c:3474 (sofia/default/443453000101) Callstate Change DOWN -> EARLY
  487. 2021-10-21 08:11:06.566661 [INFO] switch_ivr_originate.c:3654 Sending early media
  488. 2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/442031501300@212.199.161.206] 89.46.223.193 port 26784 -> 212.199.161.206 port 10030 codec: 8 ms: 20
  489. 2021-10-21 08:11:06.566661 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  490. 2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:7180 sofia/default/442031501300@212.199.161.206 Set 2833 dtmf send payload to 101
  491. 2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:7187 sofia/default/442031501300@212.199.161.206 Set 2833 dtmf receive payload to 101
  492. 2021-10-21 08:11:06.566661 [DEBUG] switch_core_media.c:7210 sofia/default/442031501300@212.199.161.206 Set rtp dtmf delay to 40
  493. 2021-10-21 08:11:06.566661 [DEBUG] mod_sofia.c:2364 Ring SDP:
  494. v=0
  495. o=FreeSWITCH 1634791482 1634791483 IN IP4 89.46.223.193
  496. s=FreeSWITCH
  497. c=IN IP4 89.46.223.193
  498. t=0 0
  499. m=audio 26784 RTP/AVP 8 101
  500. a=rtpmap:8 PCMA/8000
  501. a=rtpmap:101 telephone-event/8000
  502. a=fmtp:101 0-16
  503. a=ptime:20
  504. a=sendrecv
  505.  
  506. 2021-10-21 08:11:06.566661 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/442031501300@212.199.161.206!
  507. 2021-10-21 08:11:06.566661 [DEBUG] switch_channel.c:3474 (sofia/default/442031501300@212.199.161.206) Callstate Change RINGING -> EARLY
  508. 2021-10-21 08:11:06.566661 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/443453000101]
  509. 2021-10-21 08:11:06.566661 [DEBUG] sofia.c:7084 Channel sofia/default/442031501300@212.199.161.206 entering state [early][183]
  510. 2021-10-21 08:11:06.566661 [DEBUG] switch_ivr_bridge.c:1614 (sofia/default/443453000101) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  511. 2021-10-21 08:11:06.566661 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_EXCHANGE_MEDIA (Cur 4 Tot 727136)
  512. 2021-10-21 08:11:06.566661 [DEBUG] switch_core_state_machine.c:653 (sofia/default/443453000101) State EXCHANGE_MEDIA
  513. 2021-10-21 08:11:06.566661 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  514. 2021-10-21 08:11:06.606664 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  515. 2021-10-21 08:11:06.666668 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [completing][200]
  516. 2021-10-21 08:11:06.666668 [DEBUG] sofia.c:7091 Duplicate SDP
  517. v=0
  518. o=sbc-uk-bm-th14a 312340480 1634818263 IN IP4 212.179.237.23
  519. s=sip call
  520. c=IN IP4 212.179.237.23
  521. t=0 0
  522. m=audio 60138 RTP/AVP 8 101
  523. a=rtpmap:8 PCMA/8000
  524. a=rtpmap:101 telephone-event/8000
  525. a=fmtp:101 0-16
  526. a=ptime:20
  527.  
  528. 2021-10-21 08:11:06.686664 [DEBUG] sofia.c:7084 Channel sofia/default/443453000101 entering state [ready][200]
  529. 2021-10-21 08:11:06.686664 [NOTICE] sofia.c:8159 Channel [sofia/default/443453000101] has been answered
  530. EXECUTE sofia/default/443453000101 record_session(/var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav)
  531. 2021-10-21 08:11:06.686664 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/442031501300@212.199.161.206.
  532. 2021-10-21 08:11:06.686664 [DEBUG] mod_sofia.c:850 Local SDP sofia/default/442031501300@212.199.161.206:
  533. v=0
  534. o=FreeSWITCH 1634791482 1634791484 IN IP4 89.46.223.193
  535. s=FreeSWITCH
  536. c=IN IP4 89.46.223.193
  537. t=0 0
  538. m=audio 26784 RTP/AVP 8 101
  539. a=rtpmap:8 PCMA/8000
  540. a=rtpmap:101 telephone-event/8000
  541. a=fmtp:101 0-16
  542. a=ptime:20
  543. a=sendrecv
  544.  
  545. 2021-10-21 08:11:06.686664 [NOTICE] switch_ivr_bridge.c:623 Channel [sofia/default/442031501300@212.199.161.206] has been answered
  546. 2021-10-21 08:11:06.686664 [DEBUG] sofia.c:7084 Channel sofia/default/442031501300@212.199.161.206 entering state [completed][200]
  547. EXECUTE sofia/default/442031501300@212.199.161.206 record_session(/var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav)
  548. 2021-10-21 08:11:06.686664 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/443453000101
  549. 2021-10-21 08:11:06.686664 [DEBUG] switch_channel.c:3773 (sofia/default/443453000101) Callstate Change EARLY -> ACTIVE
  550. 2021-10-21 08:11:06.686664 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/442031501300@212.199.161.206
  551. 2021-10-21 08:11:06.686664 [DEBUG] switch_channel.c:3773 (sofia/default/442031501300@212.199.161.206) Callstate Change EARLY -> ACTIVE
  552. 2021-10-21 08:11:06.706940 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech
  553. 2021-10-21 08:11:06.706940 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech
  554. 2021-10-21 08:11:06.706940 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  555. 2021-10-21 08:11:06.706940 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
  556. 2021-10-21 08:11:06.746667 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  557. 2021-10-21 08:11:06.746667 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
  558. 2021-10-21 08:11:06.746667 [DEBUG] switch_ivr_bridge.c:546 sofia/default/443453000101 Bridge execute app sched_hangup(+10800 normal_clearing)
  559. 2021-10-21 08:11:06.766668 [DEBUG] switch_ivr.c:623 sofia/default/443453000101 Command Execute sched_hangup(+10800 normal_clearing)
  560. EXECUTE sofia/default/443453000101 sched_hangup(+10800 normal_clearing)
  561. 2021-10-21 08:11:06.766668 [DEBUG] switch_scheduler.c:249 Added task 82642 switch_ivr_schedule_hangup (f55c452c-3267-11ec-893e-7dfb9f6e00f0) to run at 1634829066
  562. 2021-10-21 08:11:06.786727 [DEBUG] sofia.c:7084 Channel sofia/default/442031501300@212.199.161.206 entering state [ready][200]
  563. 2021-10-21 08:11:12.246662 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [6000@89.46.223.193] from ip 137.135.14.148
  564. 2021-10-21 08:11:12.466664 [WARNING] sofia_reg.c:2906 Can't find user [6000@89.46.223.193] from 137.135.14.148
  565. You must define a domain called '89.46.223.193' in your directory and add a user with the id="6000" attribute
  566. and you must configure your device to use the proper domain in it's authentication credentials.
  567. 2021-10-21 08:11:12.466664 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [6000@89.46.223.193] from ip 137.135.14.148
  568. 2021-10-21 08:11:22.686664 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [363363@89.46.223.193] from ip 137.135.14.148
  569. 2021-10-21 08:11:22.846665 [WARNING] sofia_reg.c:2906 Can't find user [363363@89.46.223.193] from 137.135.14.148
  570. You must define a domain called '89.46.223.193' in your directory and add a user with the id="363363" attribute
  571. and you must configure your device to use the proper domain in it's authentication credentials.
  572. 2021-10-21 08:11:22.846665 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [363363@89.46.223.193] from ip 137.135.14.148
  573. 2021-10-21 08:11:24.086671 [NOTICE] sofia.c:1012 Hangup sofia/default/41445083106@92.249.69.77 [CS_EXECUTE] [NORMAL_CLEARING]
  574. 2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/41445083106@92.249.69.77]
  575. 2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:706 sofia/default/41445083106@92.249.69.77 ending bridge by request from write function
  576. 2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/41796404058]
  577. 2021-10-21 08:11:24.086671 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/41796404058 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  578. 2021-10-21 08:11:24.086671 [DEBUG] switch_core_state_machine.c:653 (sofia/default/41796404058) State EXCHANGE_MEDIA going to sleep
  579. 2021-10-21 08:11:24.086671 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41796404058) Running State Change CS_HANGUP (Cur 4 Tot 727136)
  580. 2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/cba2b5b8-3267-11ec-8826-7dfb9f6e00f0.wav
  581. 2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/41796404058 skip receive message [UNBRIDGE] (channel is hungup already)
  582. 2021-10-21 08:11:24.086671 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/41445083106@92.249.69.77 skip receive message [UNBRIDGE] (channel is hungup already)
  583. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_session.c:2815 sofia/default/41445083106@92.249.69.77 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  584. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:650 (sofia/default/41445083106@92.249.69.77) State EXECUTE going to sleep
  585. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41445083106@92.249.69.77) Running State Change CS_HANGUP (Cur 4 Tot 727136)
  586. 2021-10-21 08:11:24.106754 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/cba2b5b8-3267-11ec-8826-7dfb9f6e00f0.wav
  587. 2021-10-21 08:11:24.106754 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
  588. 2021-10-21 08:11:24.106754 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
  589. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/41796404058
  590. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/41445083106@92.249.69.77
  591. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:850 (sofia/default/41796404058) Callstate Change ACTIVE -> HANGUP
  592. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41796404058) State HANGUP
  593. 2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:432 sofia/default/41796404058 Overriding SIP cause 480 with 200 from the other leg
  594. 2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:438 Channel sofia/default/41796404058 hanging up, cause: NORMAL_CLEARING
  595. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:850 (sofia/default/41445083106@92.249.69.77) Callstate Change ACTIVE -> HANGUP
  596. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41445083106@92.249.69.77) State HANGUP
  597. 2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:438 Channel sofia/default/41445083106@92.249.69.77 hanging up, cause: NORMAL_CLEARING
  598. 2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/41796404058
  599. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:60 sofia/default/41796404058 Standard HANGUP, cause: NORMAL_CLEARING
  600. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41796404058) State HANGUP going to sleep
  601. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:619 (sofia/default/41796404058) State Change CS_HANGUP -> CS_REPORTING
  602. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41796404058) Running State Change CS_REPORTING (Cur 4 Tot 727136)
  603. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41796404058) State REPORTING
  604. 2021-10-21 08:11:24.106754 [INFO] mod_json_cdr.c:271 Process [cba849ba-3267-11ec-884c-7dfb9f6e00f0.cdr.json]
  605. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:60 sofia/default/41445083106@92.249.69.77 Standard HANGUP, cause: NORMAL_CLEARING
  606. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:852 (sofia/default/41445083106@92.249.69.77) State HANGUP going to sleep
  607. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:619 (sofia/default/41445083106@92.249.69.77) State Change CS_HANGUP -> CS_REPORTING
  608. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:584 (sofia/default/41445083106@92.249.69.77) Running State Change CS_REPORTING (Cur 4 Tot 727136)
  609. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41445083106@92.249.69.77) State REPORTING
  610. 2021-10-21 08:11:24.106754 [INFO] mod_json_cdr.c:271 Process [cba2b5b8-3267-11ec-8826-7dfb9f6e00f0.cdr.json]
  611. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:174 sofia/default/41796404058 Standard REPORTING, cause: NORMAL_CLEARING
  612. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41796404058) State REPORTING going to sleep
  613. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:610 (sofia/default/41796404058) State Change CS_REPORTING -> CS_DESTROY
  614. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_session.c:1665 Session 727124 (sofia/default/41796404058) Locked, Waiting on external entities
  615. 2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1683 Session 727124 (sofia/default/41796404058) Ended
  616. 2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/41796404058 [CS_DESTROY]
  617. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:741 (sofia/default/41796404058) Running State Change CS_DESTROY (Cur 3 Tot 727136)
  618. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41796404058) State DESTROY
  619. 2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:343 sofia/default/41796404058 SOFIA DESTROY
  620. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:181 sofia/default/41796404058 Standard DESTROY
  621. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41796404058) State DESTROY going to sleep
  622. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:174 sofia/default/41445083106@92.249.69.77 Standard REPORTING, cause: NORMAL_CLEARING
  623. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:938 (sofia/default/41445083106@92.249.69.77) State REPORTING going to sleep
  624. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:610 (sofia/default/41445083106@92.249.69.77) State Change CS_REPORTING -> CS_DESTROY
  625. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_session.c:1665 Session 727123 (sofia/default/41445083106@92.249.69.77) Locked, Waiting on external entities
  626. 2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1683 Session 727123 (sofia/default/41445083106@92.249.69.77) Ended
  627. 2021-10-21 08:11:24.106754 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/41445083106@92.249.69.77 [CS_DESTROY]
  628. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:741 (sofia/default/41445083106@92.249.69.77) Running State Change CS_DESTROY (Cur 2 Tot 727136)
  629. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41445083106@92.249.69.77) State DESTROY
  630. 2021-10-21 08:11:24.106754 [DEBUG] mod_sofia.c:343 sofia/default/41445083106@92.249.69.77 SOFIA DESTROY
  631. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:181 sofia/default/41445083106@92.249.69.77 Standard DESTROY
  632. 2021-10-21 08:11:24.106754 [DEBUG] switch_core_state_machine.c:751 (sofia/default/41445083106@92.249.69.77) State DESTROY going to sleep
  633. 2021-10-21 08:11:24.266668 [DEBUG] switch_scheduler.c:144 Deleting task 82639 switch_ivr_schedule_hangup (cba849ba-3267-11ec-884c-7dfb9f6e00f0)
  634. 2021-10-21 08:11:48.226666 [NOTICE] switch_channel.c:1104 New Channel sofia/default/+447578992323@199.244.96.39 [10d93058-3268-11ec-894a-7dfb9f6e00f0]
  635. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_NEW (Cur 3 Tot 727137)
  636. 2021-10-21 08:11:48.226666 [DEBUG] sofia.c:9873 sofia/default/+447578992323@199.244.96.39 receiving invite from 136.243.196.198:5060 version: 1.6.20  64bit
  637. 2021-10-21 08:11:48.226666 [DEBUG] sofia.c:9989 IP 136.243.196.198 Approved by acl "default[]". Access Granted.
  638. 2021-10-21 08:11:48.226666 [DEBUG] sofia.c:7084 Channel sofia/default/+447578992323@199.244.96.39 entering state [received][100]
  639. 2021-10-21 08:11:48.226666 [DEBUG] sofia.c:7094 Remote SDP:
  640. v=0
  641. o=PortaSIP 396105275207201534 1 IN IP4 199.244.96.46
  642. s=sip call
  643. t=0 0
  644. m=audio 45912 RTP/AVP 8 18 0 101
  645. c=IN IP4 199.244.96.46
  646. a=rtpmap:8 PCMA/8000
  647. a=rtpmap:18 G729/8000
  648. a=fmtp:18 annexb=no
  649. a=rtpmap:0 PCMU/8000
  650. a=rtpmap:101 telephone-event/8000
  651. a=fmtp:101 0-15
  652. a=silenceSupp:off - - - -
  653. a=ptime:20
  654.  
  655. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  656. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  657. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  658. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  659. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  660. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  661. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  662. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  663. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  664. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  665. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  666. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  667. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  668. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/+447578992323@199.244.96.39 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  669. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_codec.c:111 sofia/default/+447578992323@199.244.96.39 Original read codec set to PCMA:8
  670. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  671. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_media.c:4767 sofia/default/+447578992323@199.244.96.39 Set 2833 dtmf send payload to 101 recv payload to 101
  672. 2021-10-21 08:11:48.226666 [DEBUG] sofia.c:7507 (sofia/default/+447578992323@199.244.96.39) State Change CS_NEW -> CS_INIT
  673. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:603 (sofia/default/+447578992323@199.244.96.39) State NEW
  674. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_INIT (Cur 3 Tot 727137)
  675. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:627 (sofia/default/+447578992323@199.244.96.39) State INIT
  676. 2021-10-21 08:11:48.226666 [DEBUG] mod_sofia.c:90 sofia/default/+447578992323@199.244.96.39 SOFIA INIT
  677. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:40 sofia/default/+447578992323@199.244.96.39 Standard INIT
  678. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:48 (sofia/default/+447578992323@199.244.96.39) State Change CS_INIT -> CS_ROUTING
  679. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:627 (sofia/default/+447578992323@199.244.96.39) State INIT going to sleep
  680. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_ROUTING (Cur 3 Tot 727137)
  681. 2021-10-21 08:11:48.226666 [DEBUG] switch_channel.c:2249 (sofia/default/+447578992323@199.244.96.39) Callstate Change DOWN -> RINGING
  682. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:643 (sofia/default/+447578992323@199.244.96.39) State ROUTING
  683. 2021-10-21 08:11:48.226666 [DEBUG] mod_sofia.c:143 sofia/default/+447578992323@199.244.96.39 SOFIA ROUTING
  684. 2021-10-21 08:11:48.226666 [DEBUG] switch_core_state_machine.c:236 sofia/default/+447578992323@199.244.96.39 Standard ROUTING
  685. 2021-10-21 08:11:48.226666 [INFO] mod_dialplan_xml.c:637 Processing +447578992323 <+447578992323>->442039123706 in context default
  686. 2021-10-21 08:11:48.226666 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fd4240c7a70 Connected.
  687. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
  688. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  689. 2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
  690. 'Event-Name: REQUEST_PARAMS
  691. Core-UUID: 383771d0-00bf-11ec-97b1-7dfb9f6e00f0
  692. FreeSWITCH-Hostname: astpp1.local
  693. FreeSWITCH-Switchname: astpp1.local
  694. FreeSWITCH-IPv4: 89.46.223.193
  695. FreeSWITCH-IPv6: %3A%3A1
  696. Event-Date-Local: 2021-10-21%2008%3A11%3A48
  697. Event-Date-GMT: Thu,%2021%20Oct%202021%2012%3A11%3A48%20GMT
  698. Event-Date-Timestamp: 1634818308226666
  699. Event-Calling-File: mod_dialplan_xml.c
  700. Event-Calling-Function: dialplan_xml_locate
  701. Event-Calling-Line-Number: 608
  702. Event-Sequence: 20515580
  703. Channel-State: CS_ROUTING
  704. Channel-Call-State: RINGING
  705. Channel-State-Number: 2
  706. Channel-Name: sofia/default/%2B447578992323%40199.244.96.39
  707. Unique-ID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
  708. Call-Direction: inbound
  709. Presence-Call-Direction: inbound
  710. Channel-HIT-Dialplan: true
  711. Channel-Presence-ID: %2B447578992323%40199.244.96.39
  712. Channel-Call-UUID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
  713. Answer-State: ringing
  714. Channel-Read-Codec-Name: PCMA
  715. Channel-Read-Codec-Rate: 8000
  716. Channel-Read-Codec-Bit-Rate: 64000
  717. Channel-Write-Codec-Name: PCMA
  718. Channel-Write-Codec-Rate: 8000
  719. Channel-Write-Codec-Bit-Rate: 64000
  720. Caller-Direction: inbound
  721. Caller-Logical-Direction: inbound
  722. Caller-Username: %2B447578992323
  723. Caller-Dialplan: XML
  724. Caller-Caller-ID-Name: %2B447578992323
  725. Caller-Caller-ID-Number: %2B447578992323
  726. Caller-Orig-Caller-ID-Name: %2B447578992323
  727. Caller-Orig-Caller-ID-Number: %2B447578992323
  728. Caller-Network-Addr: 136.243.196.198
  729. Caller-ANI: %2B447578992323
  730. Caller-Destination-Number: 442039123706
  731. Caller-Unique-ID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
  732. Caller-Source: mod_sofia
  733. Caller-Context: default
  734. Caller-Channel-Name: sofia/default/%2B447578992323%40199.244.96.39
  735. Caller-Profile-Index: 1
  736. Caller-Profile-Created-Time: 1634818308226666
  737. Caller-Channel-Created-Time: 1634818308226666
  738. Caller-Channel-Answered-Time: 0
  739. Caller-Channel-Progress-Time: 0
  740. Caller-Channel-Progress-Media-Time: 0
  741. Caller-Channel-Hangup-Time: 0
  742. Caller-Channel-Transfer-Time: 0
  743. Caller-Channel-Resurrect-Time: 0
  744. Caller-Channel-Bridged-Time: 0
  745. Caller-Channel-Last-Hold: 0
  746. Caller-Channel-Hold-Accum: 0
  747. Caller-Screen-Bit: true
  748. Caller-Privacy-Hide-Name: false
  749. Caller-Privacy-Hide-Number: false
  750. variable_direction: inbound
  751. variable_uuid: 10d93058-3268-11ec-894a-7dfb9f6e00f0
  752. variable_session_id: 727137
  753. variable_sip_from_user: %2B447578992323
  754. variable_sip_from_uri: %2B447578992323%40199.244.96.39
  755. variable_sip_from_host: 199.244.96.39
  756. variable_video_media_flow: sendrecv
  757. variable_channel_name: sofia/default/%2B447578992323%40199.244.96.39
  758. variable_sip_call_id: 24548822-3843807107-1184227746%40MSX17.gammatelecom.com~2o
  759. variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_g729.G729%408000h%4020i%408000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  760. variable_sip_local_network_addr: 89.46.223.193
  761. variable_sip_network_ip: 136.243.196.198
  762. variable_sip_network_port: 5060
  763. variable_sip_invite_stamp: 1634818308226666
  764. variable_sip_received_ip: 136.243.196.198
  765. variable_sip_received_port: 5060
  766. variable_sip_via_protocol: udp
  767. variable_sip_authorized: true
  768. variable_sip_acl_authed_by: default
  769. variable_sip_from_user_stripped: 447578992323
  770. variable_sip_from_tag: yjp5nyxvdrst26nt.o
  771. variable_sofia_profile_name: default
  772. variable_recovery_profile_name: default
  773. variable_sip_Remote-Party-ID: %3Csip%3A%2B447578992323%40199.244.96.39%3E%3Bparty%3Dcalling
  774. variable_sip_P-Asserted-Identity: %2B447578992323
  775. variable_sip_cid_type: pid
  776. variable_sip_invite_route_uri: %3Csip%3A136.243.196.198%3Blr%3Bdid%3D428.ac4ae483%3E,%3Csip%3A199.244.96.39%3Blr%3Bep%3E
  777. variable_sip_invite_record_route: %3Csip%3A199.244.96.39%3Blr%3Bep%3E,%3Csip%3A136.243.196.198%3Blr%3Bdid%3D428.ac4ae483%3E
  778. variable_sip_full_via: SIP/2.0/UDP%20136.243.196.198%3A5060%3Bbranch%3Dz9hG4bK8d7f.dc289622.0,SIP/2.0/UDP%20199.244.96.39%3A5060%3Breceived%3D199.244.96.39%3Bbranch%3Dz9hG4bK-524287-1---fd6c70cccdef37b3f58cd7e403893cc0%3Brport%3D5060,SIP/2.0/UDP%20199.244.96.46%3A5071%3Brport%3D5071%3Bbranch%3Dz9hG4bK-sixdqbr24druxrju
  779. variable_sip_full_from: %3Csip%3A%2B447578992323%40199.244.96.39%3E%3Btag%3Dyjp5nyxvdrst26nt.o
  780. variable_sip_full_to: %3Csip%3A442039123706%40newsip.israelnumber.com%3E
  781. variable_sip_allow: INVITE,%20ACK,%20BYE,%20CANCEL,%20INFO,%20SUBSCRIBE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20UPDATE
  782. variable_sip_req_user: 442039123706
  783. variable_sip_req_uri: 442039123706%40newsip.israelnumber.com
  784. variable_sip_req_host: newsip.israelnumber.com
  785. variable_sip_to_user: 442039123706
  786. variable_sip_to_uri: 442039123706%40newsip.israelnumber.com
  787. variable_sip_to_host: newsip.israelnumber.com
  788. variable_sip_contact_user: %2B447578992323
  789. variable_sip_contact_port: 5071
  790. variable_sip_contact_uri: %2B447578992323%40199.244.96.46%3A5071
  791. variable_sip_contact_host: 199.244.96.46
  792. variable_sip_user_agent: PortaSIP
  793. variable_sip_via_host: 136.243.196.198
  794. variable_sip_via_port: 5060
  795. variable_max_forwards: 30
  796. variable_presence_id: %2B447578992323%40199.244.96.39
  797. variable_sip_h_X-AUTH-IP: 199.244.96.39
  798. variable_sip_h_P-Accountcode: 594905904039
  799. variable_sip_h_P-effective_caller_id_name: %2B447578992323
  800. variable_sip_h_P-effective_caller_id_number: %2B447578992323
  801. variable_switch_r_sdp: v%3D0%0D%0Ao%3DPortaSIP%20396105275207201534%201%20IN%20IP4%20199.244.96.46%0D%0As%3Dsip%20call%0D%0At%3D0%200%0D%0Am%3Daudio%2045912%20RTP/AVP%208%2018%200%20101%0D%0Ac%3DIN%20IP4%20199.244.96.46%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0Aa%3DsilenceSupp%3Aoff%20-%20-%20-%20-%0D%0Aa%3Dptime%3A20%0D%0A
  802. variable_rtp_use_codec_string: PCMA,PCMU,G729
  803. variable_audio_media_flow: sendrecv
  804. variable_remote_media_ip: 199.244.96.46
  805. variable_remote_media_port: 45912
  806. variable_rtp_audio_recv_pt: 8
  807. variable_rtp_use_codec_name: PCMA
  808. variable_rtp_use_codec_rate: 8000
  809. variable_rtp_use_codec_ptime: 20
  810. variable_rtp_use_codec_channels: 1
  811. variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
  812. variable_read_codec: PCMA
  813. variable_original_read_codec: PCMA
  814. variable_read_rate: 8000
  815. variable_original_read_rate: 8000
  816. variable_write_codec: PCMA
  817. variable_write_rate: 8000
  818. variable_dtmf_type: rfc2833
  819. variable_endpoint_disposition: RECEIVED
  820. variable_call_uuid: 10d93058-3268-11ec-894a-7dfb9f6e00f0
  821. Hunt-Direction: inbound
  822. Hunt-Logical-Direction: inbound
  823. Hunt-Username: %2B447578992323
  824. Hunt-Dialplan: XML
  825. Hunt-Caller-ID-Name: %2B447578992323
  826. Hunt-Caller-ID-Number: %2B447578992323
  827. Hunt-Orig-Caller-ID-Name: %2B447578992323
  828. Hunt-Orig-Caller-ID-Number: %2B447578992323
  829. Hunt-Network-Addr: 136.243.196.198
  830. Hunt-ANI: %2B447578992323
  831. Hunt-Destination-Number: 442039123706
  832. Hunt-Unique-ID: 10d93058-3268-11ec-894a-7dfb9f6e00f0
  833. Hunt-Source: mod_sofia
  834. Hunt-Context: default
  835. Hunt-Channel-Name: sofia/default/%2B447578992323%40199.244.96.39
  836. Hunt-Profile-Index: 1
  837. Hunt-Profile-Created-Time: 1634818308226666
  838. Hunt-Channel-Created-Time: 1634818308226666
  839. Hunt-Channel-Answered-Time: 0
  840. Hunt-Channel-Progress-Time: 0
  841. Hunt-Channel-Progress-Media-Time: 0
  842. Hunt-Channel-Hangup-Time: 0
  843. Hunt-Channel-Transfer-Time: 0
  844. Hunt-Channel-Resurrect-Time: 0
  845. Hunt-Channel-Bridged-Time: 0
  846. Hunt-Channel-Last-Hold: 0
  847. Hunt-Channel-Hold-Accum: 0
  848. Hunt-Screen-Bit: true
  849. Hunt-Privacy-Hide-Name: false
  850. Hunt-Privacy-Hide-Number: false
  851.  
  852. '
  853. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
  854. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
  855. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
  856. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
  857. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] blind_flag: 0
  858. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 442039123706
  859. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '442039123706' limit 1
  860. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number as number FROM accessnumber WHERE access_number = '442039123706' AND status=0 limit 1
  861. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE domain = '199.244.96.39' limit 1
  862. 2021-10-21 08:11:48.226666 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FAXER] in custom Caller ID
  863. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : +447578992323 / +447578992323
  864. 2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
  865. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
  866. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
  867. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [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 ="442039123706" LIMIT 1
  868. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : inbound
  869. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Accountcode1 : 594905904039]
  870. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "594905904039" OR number = "594905904039") AND deleted = 0 limit 1
  871. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [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 ="442039123706" AND A.accountid = '855' limit 1
  872. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 442039123706
  873. 2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
  874. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
  875. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
  876. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [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 ="442039123706" LIMIT 1
  877. 2021-10-21 08:11:48.226666 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] New Call direction : inbound
  878. 2021-10-21 08:11:48.226666 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
  879. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
  880. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
  881. 2021-10-21 08:11:48.226666 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="442039123706" AND (accountid = 0 OR status = 1) LIMIT 1
  882. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] call_direction :inbound
  883. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] DID_ACCOUNTID :851
  884. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [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 = '^442039123706.*' OR patterns = '^44203912370.*' OR patterns = '^4420391237.*' OR patterns = '^442039123.*' OR patterns = '^44203912.*' OR patterns = '^4420391.*' OR patterns = '^442039.*' OR patterns = '^44203.*' OR patterns = '^4420.*' OR patterns = '^442.*' OR patterns = '^44.*' OR patterns = '^4.*' OR patterns ='--') AND accountid = 851 ORDER BY LENGTH(PKGPTR.patterns) DESC
  885. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '442039123706' OR number = '44203912370' OR number = '4420391237' OR number = '442039123' OR number = '44203912' OR number = '4420391' OR number = '442039' OR number = '44203' OR number = '4420' OR number = '442' OR number = '44' OR number = '4' OR number ='--') OR (number = '442039123706*' OR number = '44203912370*' OR number = '4420391237*' OR number = '442039123*' OR number = '44203912*' OR number = '4420391*' OR number = '442039*' OR number = '44203*' OR number = '4420*' OR number = '442*' OR number = '44*' OR number = '4*' OR number ='--') OR (number = '+447578992323' OR number = '+44757899232' OR number = '+4475789923' OR number = '+447578992' OR number = '+44757899' OR number = '+4475789' OR number = '+447578' OR number = '+44757' OR number = '+4475' OR number = '+447' OR number = '+44' OR number = '+4' OR number = '+' OR number ='--') OR (number = '+447578992323*' OR number = '+44757899232*' OR number = '+4475789923*' OR number = '+447578992*' OR number = '+44757899*' OR number = '+4475789*' OR number = '+447578*' OR number = '+44757*' OR number = '+4475*' OR number = '+447*' OR number = '+44*' OR number = '+4*' OR number = '+*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
  886. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Call Barring Status : -1
  887. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '442039123706' OR routing_prefix = '44203912370' OR routing_prefix = '4420391237' OR routing_prefix = '442039123' OR routing_prefix = '44203912' OR routing_prefix = '4420391' OR routing_prefix = '442039' OR routing_prefix = '44203' OR routing_prefix = '4420' OR routing_prefix = '442' OR routing_prefix = '44' OR routing_prefix = '4' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
  888. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
  889. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 855
  890. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 594905904039
  891. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 10000
  892. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  893. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 1
  894. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] CID Pool : 0
  895. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
  896. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 1 AND status = 0
  897. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] call_direction :inbound
  898. 2021-10-21 08:11:48.246675 [NOTICE] switch_cpp.cpp:1365 [ASTPP] DID global translation id: 13
  899. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 13 AND status=0 limit 1
  900. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_LOCALIZATION] Query :"+/*".....442039123706
  901. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [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 ="442039123706" LIMIT 1
  902. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] call_direction:::::: inbound
  903. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
  904. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 12355
  905. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
  906. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
  907. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.01300
  908. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : London Landline
  909. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Country Id : 200
  910. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 855
  911. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
  912. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 855 !!!
  913. 2021-10-21 08:11:48.246675 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 180 mins!
  914. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 180 minutes
  915. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] INB_FREE:TRUE
  916. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] free_inbound:1
  917. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 855 AND status=0 LIMIT 1
  918. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (id = "851" OR number = "851") AND deleted = 0 limit 1
  919. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML:855
  920. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Userinfo XML:855
  921. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] [userinfo] Actual CustomerInfo XML : 855
  922. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 851 limit 1
  923. 2021-10-21 08:11:48.246675 [WARNING] switch_cpp.cpp:1365 [ASTPP] [user_domain] user_domainVoicespin802.newsip.israelnumber.com
  924. 2021-10-21 08:11:48.246675 [INFO] switch_cpp.cpp:1365 [ASTPP] maxlength::::::::: 180
  925. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET domains] Query :SELECT * FROM domains WHERE accountid = 851 limit 1
  926. 2021-10-21 08:11:48.246675 [WARNING] switch_cpp.cpp:1365 [ASTPP] [user_domain] user_domainVoicespin802.newsip.israelnumber.com
  927. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] custom_function_name:::::::::::::::::::::::::custom_inbound_2
  928. 2021-10-21 08:11:48.246675 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  929. <document type="freeswitch/xml">
  930. <section name="dialplan" description="ASTPP Dialplan">
  931. <context name="default">
  932. <extension name="442039123706">
  933. <condition field="destination_number" expression="442039123706">
  934. <action application="set" data="effective_destination_number=442039123706"/>
  935. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
  936. <action application="set" data="bridge_pre_execute_bleg_data=+10800 normal_clearing"/>
  937. <action application="set" data="callstart=2021-10-21 12:11:48"/>
  938. <action application="set" data="hangup_after_bridge=true"/>
  939. <action application="set" data="continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602"/>
  940. <action application="set" data="account_id=855"/>
  941. <action application="set" data="parent_id=0"/>
  942. <action application="set" data="entity_id=3"/>
  943. <action application="set" data="call_processed=internal"/>
  944. <action application="set" data="call_direction=inbound"/>
  945. <action application="set" data="accountname=default"/>
  946. <action application="set" data="filename1=astpp.pbx.lua"/>
  947. <action application="set" data="origination_rates_did=ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851"/>
  948. <action application="set" data="origination_rates=0"/>
  949. <action application="set" data="original_caller_id_name=+447578992323"/>
  950. <action application="set" data="original_caller_id_number=+447578992323"/>
  951. <action application="set" data="effective_caller_id_name=+447578992323"/>
  952. <action application="set" data="effective_caller_id_number=+447578992323"/>
  953. <action application="set" data="receiver_accid=851"/>
  954. <action application="export" data="domain_name=Voicespin802.newsip.israelnumber.com"/>
  955. <action application="export" data="sip_to_host=Voicespin802.newsip.israelnumber.com"/>
  956. <action application="limit" data="db 442039123706 did_442039123706 2 !SWITCH_CONGESTION"/>
  957. <action application="export" data="presence_data=x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39"/>
  958. <action application="export" data="call_type=2"/>
  959. <action application="set" data="calltype=DIRECT-IP"/>
  960. <action application="bridge" data="{sip_invite_params=user=DIRECT-IP}[leg_timeout=30]sofia/${sofia_profile_name}/442039123706@35.205.116.118"/>
  961. </condition>
  962. </extension>
  963. </context>
  964. </section>
  965. </document>
  966. 2021-10-21 08:11:48.246675 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fd4240c7a70 released.
  967. Dialplan: sofia/default/+447578992323@199.244.96.39 parsing [default->442039123706] continue=false
  968. Dialplan: sofia/default/+447578992323@199.244.96.39 Regex (PASS) [442039123706] destination_number(442039123706) =~ /442039123706/ break=on-false
  969. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(effective_destination_number=442039123706)
  970. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(bridge_pre_execute_bleg_app=sched_hangup)
  971. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
  972. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(callstart=2021-10-21 12:11:48)
  973. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(hangup_after_bridge=true)
  974. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
  975. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(account_id=855)
  976. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(parent_id=0)
  977. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(entity_id=3)
  978. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(call_processed=internal)
  979. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(call_direction=inbound)
  980. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(accountname=default)
  981. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(filename1=astpp.pbx.lua)
  982. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(origination_rates_did=ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851)
  983. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(origination_rates=0)
  984. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(original_caller_id_name=+447578992323)
  985. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(original_caller_id_number=+447578992323)
  986. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(effective_caller_id_name=+447578992323)
  987. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(effective_caller_id_number=+447578992323)
  988. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(receiver_accid=851)
  989. Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(domain_name=Voicespin802.newsip.israelnumber.com)
  990. Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(sip_to_host=Voicespin802.newsip.israelnumber.com)
  991. Dialplan: sofia/default/+447578992323@199.244.96.39 Action limit(db 442039123706 did_442039123706 2 !SWITCH_CONGESTION)
  992. Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(presence_data=x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39)
  993. Dialplan: sofia/default/+447578992323@199.244.96.39 Action export(call_type=2)
  994. Dialplan: sofia/default/+447578992323@199.244.96.39 Action set(calltype=DIRECT-IP)
  995. Dialplan: sofia/default/+447578992323@199.244.96.39 Action bridge({sip_invite_params=user=DIRECT-IP}[leg_timeout=30]sofia/${sofia_profile_name}/442039123706@35.205.116.118)
  996. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:286 (sofia/default/+447578992323@199.244.96.39) State Change CS_ROUTING -> CS_EXECUTE
  997. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:643 (sofia/default/+447578992323@199.244.96.39) State ROUTING going to sleep
  998. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_EXECUTE (Cur 3 Tot 727137)
  999. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:650 (sofia/default/+447578992323@199.244.96.39) State EXECUTE
  1000. 2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:198 sofia/default/+447578992323@199.244.96.39 SOFIA EXECUTE
  1001. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:328 sofia/default/+447578992323@199.244.96.39 Standard EXECUTE
  1002. EXECUTE sofia/default/+447578992323@199.244.96.39 set(effective_destination_number=442039123706)
  1003. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [effective_destination_number]=[442039123706]
  1004. EXECUTE sofia/default/+447578992323@199.244.96.39 set(bridge_pre_execute_bleg_app=sched_hangup)
  1005. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [bridge_pre_execute_bleg_app]=[sched_hangup]
  1006. EXECUTE sofia/default/+447578992323@199.244.96.39 set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
  1007. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [bridge_pre_execute_bleg_data]=[+10800 normal_clearing]
  1008. EXECUTE sofia/default/+447578992323@199.244.96.39 set(callstart=2021-10-21 12:11:48)
  1009. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [callstart]=[2021-10-21 12:11:48]
  1010. EXECUTE sofia/default/+447578992323@199.244.96.39 set(hangup_after_bridge=true)
  1011. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [hangup_after_bridge]=[true]
  1012. EXECUTE sofia/default/+447578992323@199.244.96.39 set(continue_on_fail=1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602)
  1013. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [continue_on_fail]=[1,19,20,21,27,34,38,41,42,44,52,58,69,79,102,602]
  1014. EXECUTE sofia/default/+447578992323@199.244.96.39 set(account_id=855)
  1015. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [account_id]=[855]
  1016. EXECUTE sofia/default/+447578992323@199.244.96.39 set(parent_id=0)
  1017. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [parent_id]=[0]
  1018. EXECUTE sofia/default/+447578992323@199.244.96.39 set(entity_id=3)
  1019. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [entity_id]=[3]
  1020. EXECUTE sofia/default/+447578992323@199.244.96.39 set(call_processed=internal)
  1021. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [call_processed]=[internal]
  1022. EXECUTE sofia/default/+447578992323@199.244.96.39 set(call_direction=inbound)
  1023. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [call_direction]=[inbound]
  1024. EXECUTE sofia/default/+447578992323@199.244.96.39 set(accountname=default)
  1025. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [accountname]=[default]
  1026. EXECUTE sofia/default/+447578992323@199.244.96.39 set(filename1=astpp.pbx.lua)
  1027. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [filename1]=[astpp.pbx.lua]
  1028. EXECUTE sofia/default/+447578992323@199.244.96.39 set(origination_rates_did=ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851)
  1029. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [origination_rates_did]=[ID:12355|CODE:^442039123706.*|DESTINATION:London Landline|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:2|COST:0.01300|INC:60|INITIALBLOCK:60|RATEGROUP:0|MARKUP:0|CI:200|ACCID:851]
  1030. EXECUTE sofia/default/+447578992323@199.244.96.39 set(origination_rates=0)
  1031. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [origination_rates]=[0]
  1032. EXECUTE sofia/default/+447578992323@199.244.96.39 set(original_caller_id_name=+447578992323)
  1033. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [original_caller_id_name]=[+447578992323]
  1034. EXECUTE sofia/default/+447578992323@199.244.96.39 set(original_caller_id_number=+447578992323)
  1035. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [original_caller_id_number]=[+447578992323]
  1036. EXECUTE sofia/default/+447578992323@199.244.96.39 set(effective_caller_id_name=+447578992323)
  1037. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [effective_caller_id_name]=[+447578992323]
  1038. EXECUTE sofia/default/+447578992323@199.244.96.39 set(effective_caller_id_number=+447578992323)
  1039. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [effective_caller_id_number]=[+447578992323]
  1040. EXECUTE sofia/default/+447578992323@199.244.96.39 set(receiver_accid=851)
  1041. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [receiver_accid]=[851]
  1042. EXECUTE sofia/default/+447578992323@199.244.96.39 export(domain_name=Voicespin802.newsip.israelnumber.com)
  1043. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[Voicespin802.newsip.israelnumber.com]
  1044. EXECUTE sofia/default/+447578992323@199.244.96.39 export(sip_to_host=Voicespin802.newsip.israelnumber.com)
  1045. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_to_host]=[Voicespin802.newsip.israelnumber.com]
  1046. EXECUTE sofia/default/+447578992323@199.244.96.39 limit(db 442039123706 did_442039123706 2 !SWITCH_CONGESTION)
  1047. 2021-10-21 08:11:48.246675 [DEBUG] switch_limit.c:126 incr called: 442039123706_did_442039123706 max:2, interval:0
  1048. 2021-10-21 08:11:48.246675 [DEBUG] mod_db.c:194 Usage for 442039123706_did_442039123706 is now 1/2
  1049. EXECUTE sofia/default/+447578992323@199.244.96.39 export(presence_data=x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39)
  1050. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [presence_data]=[x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39]
  1051. EXECUTE sofia/default/+447578992323@199.244.96.39 export(call_type=2)
  1052. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_type]=[2]
  1053. EXECUTE sofia/default/+447578992323@199.244.96.39 set(calltype=DIRECT-IP)
  1054. 2021-10-21 08:11:48.246675 [DEBUG] mod_dptools.c:1548 SET sofia/default/+447578992323@199.244.96.39 [calltype]=[DIRECT-IP]
  1055. EXECUTE sofia/default/+447578992323@199.244.96.39 bridge({sip_invite_params=user=DIRECT-IP}[leg_timeout=30]sofia/default/442039123706@35.205.116.118)
  1056. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [domain_name]=[Voicespin802.newsip.israelnumber.com] to event
  1057. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [sip_to_host]=[Voicespin802.newsip.israelnumber.com] to event
  1058. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [presence_data]=[x|||AvoxiIn1(594905904039)|||^442039123706.* // London Landline // 0.01300||||||DID|||199.244.96.39] to event
  1059. 2021-10-21 08:11:48.246675 [DEBUG] switch_channel.c:1250 sofia/default/+447578992323@199.244.96.39 EXPORTING[export_vars] [call_type]=[2] to event
  1060. 2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1061. 2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  1062. 2021-10-21 08:11:48.246675 [NOTICE] switch_channel.c:1104 New Channel sofia/default/442039123706@35.205.116.118 [10de8f26-3268-11ec-896a-7dfb9f6e00f0]
  1063. 2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:4819 (sofia/default/442039123706@35.205.116.118) State Change CS_NEW -> CS_INIT
  1064. 2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:2963 sofia/default/442039123706@35.205.116.118 Setting leg timeout to 30
  1065. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_INIT (Cur 4 Tot 727138)
  1066. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:627 (sofia/default/442039123706@35.205.116.118) State INIT
  1067. 2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:90 sofia/default/442039123706@35.205.116.118 SOFIA INIT
  1068. 2021-10-21 08:11:48.246675 [DEBUG] sofia_glue.c:1295 sofia/default/442039123706@35.205.116.118 sending invite version: 1.6.20  64bit
  1069. Local SDP:
  1070. v=0
  1071. o=FreeSWITCH 1634791738 1634791739 IN IP4 89.46.223.193
  1072. s=FreeSWITCH
  1073. c=IN IP4 89.46.223.193
  1074. t=0 0
  1075. m=audio 26570 RTP/AVP 8 101
  1076. a=rtpmap:8 PCMA/8000
  1077. a=rtpmap:101 telephone-event/8000
  1078. a=fmtp:101 0-16
  1079. a=ptime:20
  1080. a=sendrecv
  1081.  
  1082. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:40 sofia/default/442039123706@35.205.116.118 Standard INIT
  1083. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:48 (sofia/default/442039123706@35.205.116.118) State Change CS_INIT -> CS_ROUTING
  1084. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:627 (sofia/default/442039123706@35.205.116.118) State INIT going to sleep
  1085. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_ROUTING (Cur 4 Tot 727138)
  1086. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:643 (sofia/default/442039123706@35.205.116.118) State ROUTING
  1087. 2021-10-21 08:11:48.246675 [DEBUG] mod_sofia.c:143 sofia/default/442039123706@35.205.116.118 SOFIA ROUTING
  1088. 2021-10-21 08:11:48.246675 [DEBUG] switch_ivr_originate.c:67 (sofia/default/442039123706@35.205.116.118) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1089. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:643 (sofia/default/442039123706@35.205.116.118) State ROUTING going to sleep
  1090. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 727138)
  1091. 2021-10-21 08:11:48.246675 [DEBUG] sofia.c:7084 Channel sofia/default/442039123706@35.205.116.118 entering state [calling][0]
  1092. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:662 (sofia/default/442039123706@35.205.116.118) State CONSUME_MEDIA
  1093. 2021-10-21 08:11:48.246675 [DEBUG] switch_core_state_machine.c:662 (sofia/default/442039123706@35.205.116.118) State CONSUME_MEDIA going to sleep
  1094. 2021-10-21 08:11:48.526661 [DEBUG] sofia.c:7084 Channel sofia/default/442039123706@35.205.116.118 entering state [completing][200]
  1095. 2021-10-21 08:11:48.526661 [DEBUG] sofia.c:7094 Remote SDP:
  1096. v=0
  1097. o=root 894262298 894262298 IN IP4 104.155.54.20
  1098. s=Asterisk PBX 13.29.2
  1099. c=IN IP4 104.155.54.20
  1100. t=0 0
  1101. m=audio 15608 RTP/AVP 8 101
  1102. a=rtpmap:8 PCMA/8000
  1103. a=rtpmap:101 telephone-event/8000
  1104. a=fmtp:101 0-16
  1105. a=ptime:20
  1106. a=maxptime:150
  1107.  
  1108. 2021-10-21 08:11:48.526661 [DEBUG] sofia.c:7084 Channel sofia/default/442039123706@35.205.116.118 entering state [ready][200]
  1109. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1110. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1111. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  1112. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/442039123706@35.205.116.118 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1113. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_codec.c:111 sofia/default/442039123706@35.205.116.118 Original read codec set to PCMA:8
  1114. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  1115. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:4767 sofia/default/442039123706@35.205.116.118 Set 2833 dtmf send payload to 101 recv payload to 101
  1116. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/442039123706@35.205.116.118] 89.46.223.193 port 26570 -> 104.155.54.20 port 15608 codec: 8 ms: 20
  1117. 2021-10-21 08:11:48.526661 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  1118. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:7180 sofia/default/442039123706@35.205.116.118 Set 2833 dtmf send payload to 101
  1119. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:7187 sofia/default/442039123706@35.205.116.118 Set 2833 dtmf receive payload to 101
  1120. 2021-10-21 08:11:48.526661 [DEBUG] switch_core_media.c:7210 sofia/default/442039123706@35.205.116.118 Set rtp dtmf delay to 40
  1121. 2021-10-21 08:11:48.526661 [NOTICE] sofia.c:8218 Channel [sofia/default/442039123706@35.205.116.118] has been answered
  1122. 2021-10-21 08:11:48.526661 [DEBUG] switch_channel.c:3773 (sofia/default/442039123706@35.205.116.118) Callstate Change DOWN -> ACTIVE
  1123. 2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/+447578992323@199.244.96.39] 89.46.223.193 port 21052 -> 199.244.96.46 port 45912 codec: 8 ms: 20
  1124. 2021-10-21 08:11:48.546661 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  1125. 2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:7180 sofia/default/+447578992323@199.244.96.39 Set 2833 dtmf send payload to 101
  1126. 2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:7187 sofia/default/+447578992323@199.244.96.39 Set 2833 dtmf receive payload to 101
  1127. 2021-10-21 08:11:48.546661 [DEBUG] switch_core_media.c:7210 sofia/default/+447578992323@199.244.96.39 Set rtp dtmf delay to 40
  1128. 2021-10-21 08:11:48.546661 [DEBUG] mod_sofia.c:850 Local SDP sofia/default/+447578992323@199.244.96.39:
  1129. v=0
  1130. o=FreeSWITCH 1634797256 1634797257 IN IP4 89.46.223.193
  1131. s=FreeSWITCH
  1132. c=IN IP4 89.46.223.193
  1133. t=0 0
  1134. m=audio 21052 RTP/AVP 8 101
  1135. a=rtpmap:8 PCMA/8000
  1136. a=rtpmap:101 telephone-event/8000
  1137. a=fmtp:101 0-16
  1138. a=ptime:20
  1139. a=sendrecv
  1140.  
  1141. 2021-10-21 08:11:48.546661 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/default/+447578992323@199.244.96.39] has been answered
  1142. 2021-10-21 08:11:48.546661 [DEBUG] switch_channel.c:3773 (sofia/default/+447578992323@199.244.96.39) Callstate Change RINGING -> ACTIVE
  1143. 2021-10-21 08:11:48.546661 [DEBUG] sofia.c:7084 Channel sofia/default/+447578992323@199.244.96.39 entering state [completed][200]
  1144. 2021-10-21 08:11:48.546661 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/442039123706@35.205.116.118]
  1145. 2021-10-21 08:11:48.546661 [DEBUG] switch_ivr_bridge.c:1614 (sofia/default/442039123706@35.205.116.118) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1146. 2021-10-21 08:11:48.546661 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_EXCHANGE_MEDIA (Cur 4 Tot 727138)
  1147. 2021-10-21 08:11:48.546661 [DEBUG] switch_core_state_machine.c:653 (sofia/default/442039123706@35.205.116.118) State EXCHANGE_MEDIA
  1148. 2021-10-21 08:11:48.546661 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  1149. 2021-10-21 08:11:48.706665 [DEBUG] sofia.c:7084 Channel sofia/default/+447578992323@199.244.96.39 entering state [ready][200]
  1150. 2021-10-21 08:11:48.726735 [DEBUG] switch_ivr_bridge.c:546 sofia/default/442039123706@35.205.116.118 Bridge execute app sched_hangup(+10800 normal_clearing)
  1151. 2021-10-21 08:11:48.746667 [DEBUG] switch_ivr.c:623 sofia/default/442039123706@35.205.116.118 Command Execute sched_hangup(+10800 normal_clearing)
  1152. EXECUTE sofia/default/442039123706@35.205.116.118 sched_hangup(+10800 normal_clearing)
  1153. 2021-10-21 08:11:48.746667 [DEBUG] switch_scheduler.c:249 Added task 82643 switch_ivr_schedule_hangup (10de8f26-3268-11ec-896a-7dfb9f6e00f0) to run at 1634829108
  1154. 2021-10-21 08:11:48.906674 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  1155. 2021-10-21 08:11:50.986672 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  1156. 2021-10-21 08:11:56.346678 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [775@89.46.223.193] from ip 185.166.84.32
  1157. 2021-10-21 08:11:56.406708 [WARNING] sofia_reg.c:2906 Can't find user [775@89.46.223.193] from 185.166.84.32
  1158. You must define a domain called '89.46.223.193' in your directory and add a user with the id="775" attribute
  1159. and you must configure your device to use the proper domain in it's authentication credentials.
  1160. 2021-10-21 08:11:56.406708 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [775@89.46.223.193] from ip 185.166.84.32
  1161. 2021-10-21 08:12:00.946725 [NOTICE] sofia.c:1012 Hangup sofia/default/442031501300@212.199.161.206 [CS_EXECUTE] [NORMAL_CLEARING]
  1162. 2021-10-21 08:12:00.946725 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/442031501300@212.199.161.206]
  1163. 2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:706 sofia/default/442031501300@212.199.161.206 ending bridge by request from write function
  1164. 2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/443453000101]
  1165. 2021-10-21 08:12:00.966668 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/443453000101 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1166. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:653 (sofia/default/443453000101) State EXCHANGE_MEDIA going to sleep
  1167. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_HANGUP (Cur 4 Tot 727138)
  1168. 2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav
  1169. 2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/443453000101 skip receive message [UNBRIDGE] (channel is hungup already)
  1170. 2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/442031501300@212.199.161.206 skip receive message [UNBRIDGE] (channel is hungup already)
  1171. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_session.c:2815 sofia/default/442031501300@212.199.161.206 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1172. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:650 (sofia/default/442031501300@212.199.161.206) State EXECUTE going to sleep
  1173. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442031501300@212.199.161.206) Running State Change CS_HANGUP (Cur 4 Tot 727138)
  1174. 2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_async.c:1316 Stop recording file /var/lib/freeswitch/recordings/f5563380-3267-11ec-8918-7dfb9f6e00f0.wav
  1175. 2021-10-21 08:12:00.966668 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
  1176. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/442031501300@212.199.161.206
  1177. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:850 (sofia/default/442031501300@212.199.161.206) Callstate Change ACTIVE -> HANGUP
  1178. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442031501300@212.199.161.206) State HANGUP
  1179. 2021-10-21 08:12:00.966668 [DEBUG] mod_sofia.c:438 Channel sofia/default/442031501300@212.199.161.206 hanging up, cause: NORMAL_CLEARING
  1180. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:60 sofia/default/442031501300@212.199.161.206 Standard HANGUP, cause: NORMAL_CLEARING
  1181. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442031501300@212.199.161.206) State HANGUP going to sleep
  1182. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:619 (sofia/default/442031501300@212.199.161.206) State Change CS_HANGUP -> CS_REPORTING
  1183. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442031501300@212.199.161.206) Running State Change CS_REPORTING (Cur 4 Tot 727138)
  1184. 2021-10-21 08:12:00.966668 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442031501300@212.199.161.206) State REPORTING
  1185. 2021-10-21 08:12:00.966668 [INFO] mod_json_cdr.c:271 Process [f5563380-3267-11ec-8918-7dfb9f6e00f0.cdr.json]
  1186. 2021-10-21 08:12:00.986672 [DEBUG] switch_ivr_async.c:1380 Channel is hung up
  1187. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/443453000101
  1188. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:850 (sofia/default/443453000101) Callstate Change ACTIVE -> HANGUP
  1189. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:852 (sofia/default/443453000101) State HANGUP
  1190. 2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:432 sofia/default/443453000101 Overriding SIP cause 480 with 200 from the other leg
  1191. 2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:438 Channel sofia/default/443453000101 hanging up, cause: NORMAL_CLEARING
  1192. 2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/443453000101
  1193. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:60 sofia/default/443453000101 Standard HANGUP, cause: NORMAL_CLEARING
  1194. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:852 (sofia/default/443453000101) State HANGUP going to sleep
  1195. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:619 (sofia/default/443453000101) State Change CS_HANGUP -> CS_REPORTING
  1196. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:584 (sofia/default/443453000101) Running State Change CS_REPORTING (Cur 4 Tot 727138)
  1197. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:938 (sofia/default/443453000101) State REPORTING
  1198. 2021-10-21 08:12:00.986672 [INFO] mod_json_cdr.c:271 Process [f55c452c-3267-11ec-893e-7dfb9f6e00f0.cdr.json]
  1199. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:174 sofia/default/443453000101 Standard REPORTING, cause: NORMAL_CLEARING
  1200. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:938 (sofia/default/443453000101) State REPORTING going to sleep
  1201. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:610 (sofia/default/443453000101) State Change CS_REPORTING -> CS_DESTROY
  1202. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_session.c:1665 Session 727136 (sofia/default/443453000101) Locked, Waiting on external entities
  1203. 2021-10-21 08:12:00.986672 [NOTICE] switch_core_session.c:1683 Session 727136 (sofia/default/443453000101) Ended
  1204. 2021-10-21 08:12:00.986672 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/443453000101 [CS_DESTROY]
  1205. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:741 (sofia/default/443453000101) Running State Change CS_DESTROY (Cur 3 Tot 727138)
  1206. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:751 (sofia/default/443453000101) State DESTROY
  1207. 2021-10-21 08:12:00.986672 [DEBUG] mod_sofia.c:343 sofia/default/443453000101 SOFIA DESTROY
  1208. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:181 sofia/default/443453000101 Standard DESTROY
  1209. 2021-10-21 08:12:00.986672 [DEBUG] switch_core_state_machine.c:751 (sofia/default/443453000101) State DESTROY going to sleep
  1210. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:174 sofia/default/442031501300@212.199.161.206 Standard REPORTING, cause: NORMAL_CLEARING
  1211. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442031501300@212.199.161.206) State REPORTING going to sleep
  1212. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:610 (sofia/default/442031501300@212.199.161.206) State Change CS_REPORTING -> CS_DESTROY
  1213. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_session.c:1665 Session 727135 (sofia/default/442031501300@212.199.161.206) Locked, Waiting on external entities
  1214. 2021-10-21 08:12:01.046729 [NOTICE] switch_core_session.c:1683 Session 727135 (sofia/default/442031501300@212.199.161.206) Ended
  1215. 2021-10-21 08:12:01.046729 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/442031501300@212.199.161.206 [CS_DESTROY]
  1216. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:741 (sofia/default/442031501300@212.199.161.206) Running State Change CS_DESTROY (Cur 2 Tot 727138)
  1217. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442031501300@212.199.161.206) State DESTROY
  1218. 2021-10-21 08:12:01.046729 [DEBUG] mod_sofia.c:343 sofia/default/442031501300@212.199.161.206 SOFIA DESTROY
  1219. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:181 sofia/default/442031501300@212.199.161.206 Standard DESTROY
  1220. 2021-10-21 08:12:01.046729 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442031501300@212.199.161.206) State DESTROY going to sleep
  1221. 2021-10-21 08:12:01.246672 [DEBUG] switch_scheduler.c:144 Deleting task 82642 switch_ivr_schedule_hangup (f55c452c-3267-11ec-893e-7dfb9f6e00f0)
  1222. 2021-10-21 08:12:03.766665 [NOTICE] sofia.c:1012 Hangup sofia/default/+447578992323@199.244.96.39 [CS_EXECUTE] [NORMAL_CLEARING]
  1223. 2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/+447578992323@199.244.96.39]
  1224. 2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:706 sofia/default/+447578992323@199.244.96.39 ending bridge by request from write function
  1225. 2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/442039123706@35.205.116.118]
  1226. 2021-10-21 08:12:03.766665 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/442039123706@35.205.116.118 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1227. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:653 (sofia/default/442039123706@35.205.116.118) State EXCHANGE_MEDIA going to sleep
  1228. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_HANGUP (Cur 2 Tot 727138)
  1229. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:850 (sofia/default/442039123706@35.205.116.118) Callstate Change ACTIVE -> HANGUP
  1230. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442039123706@35.205.116.118) State HANGUP
  1231. 2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:432 sofia/default/442039123706@35.205.116.118 Overriding SIP cause 480 with 200 from the other leg
  1232. 2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:438 Channel sofia/default/442039123706@35.205.116.118 hanging up, cause: NORMAL_CLEARING
  1233. 2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/442039123706@35.205.116.118
  1234. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:60 sofia/default/442039123706@35.205.116.118 Standard HANGUP, cause: NORMAL_CLEARING
  1235. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/442039123706@35.205.116.118) State HANGUP going to sleep
  1236. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:619 (sofia/default/442039123706@35.205.116.118) State Change CS_HANGUP -> CS_REPORTING
  1237. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/442039123706@35.205.116.118) Running State Change CS_REPORTING (Cur 2 Tot 727138)
  1238. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442039123706@35.205.116.118) State REPORTING
  1239. 2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/442039123706@35.205.116.118 skip receive message [UNBRIDGE] (channel is hungup already)
  1240. 2021-10-21 08:12:03.766665 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/+447578992323@199.244.96.39 skip receive message [UNBRIDGE] (channel is hungup already)
  1241. 2021-10-21 08:12:03.766665 [INFO] mod_json_cdr.c:271 Process [10de8f26-3268-11ec-896a-7dfb9f6e00f0.cdr.json]
  1242. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_session.c:2815 sofia/default/+447578992323@199.244.96.39 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1243. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:650 (sofia/default/+447578992323@199.244.96.39) State EXECUTE going to sleep
  1244. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_HANGUP (Cur 2 Tot 727138)
  1245. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:850 (sofia/default/+447578992323@199.244.96.39) Callstate Change ACTIVE -> HANGUP
  1246. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/+447578992323@199.244.96.39) State HANGUP
  1247. 2021-10-21 08:12:03.766665 [DEBUG] mod_sofia.c:438 Channel sofia/default/+447578992323@199.244.96.39 hanging up, cause: NORMAL_CLEARING
  1248. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:60 sofia/default/+447578992323@199.244.96.39 Standard HANGUP, cause: NORMAL_CLEARING
  1249. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:852 (sofia/default/+447578992323@199.244.96.39) State HANGUP going to sleep
  1250. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:619 (sofia/default/+447578992323@199.244.96.39) State Change CS_HANGUP -> CS_REPORTING
  1251. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:584 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_REPORTING (Cur 2 Tot 727138)
  1252. 2021-10-21 08:12:03.766665 [DEBUG] switch_core_state_machine.c:938 (sofia/default/+447578992323@199.244.96.39) State REPORTING
  1253. 2021-10-21 08:12:03.766665 [INFO] mod_json_cdr.c:271 Process [10d93058-3268-11ec-894a-7dfb9f6e00f0.cdr.json]
  1254. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:174 sofia/default/442039123706@35.205.116.118 Standard REPORTING, cause: NORMAL_CLEARING
  1255. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:938 (sofia/default/442039123706@35.205.116.118) State REPORTING going to sleep
  1256. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:610 (sofia/default/442039123706@35.205.116.118) State Change CS_REPORTING -> CS_DESTROY
  1257. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_session.c:1665 Session 727138 (sofia/default/442039123706@35.205.116.118) Locked, Waiting on external entities
  1258. 2021-10-21 08:12:03.786666 [NOTICE] switch_core_session.c:1683 Session 727138 (sofia/default/442039123706@35.205.116.118) Ended
  1259. 2021-10-21 08:12:03.786666 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/442039123706@35.205.116.118 [CS_DESTROY]
  1260. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:741 (sofia/default/442039123706@35.205.116.118) Running State Change CS_DESTROY (Cur 1 Tot 727138)
  1261. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442039123706@35.205.116.118) State DESTROY
  1262. 2021-10-21 08:12:03.786666 [DEBUG] mod_sofia.c:343 sofia/default/442039123706@35.205.116.118 SOFIA DESTROY
  1263. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:181 sofia/default/442039123706@35.205.116.118 Standard DESTROY
  1264. 2021-10-21 08:12:03.786666 [DEBUG] switch_core_state_machine.c:751 (sofia/default/442039123706@35.205.116.118) State DESTROY going to sleep
  1265. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:174 sofia/default/+447578992323@199.244.96.39 Standard REPORTING, cause: NORMAL_CLEARING
  1266. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:938 (sofia/default/+447578992323@199.244.96.39) State REPORTING going to sleep
  1267. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:610 (sofia/default/+447578992323@199.244.96.39) State Change CS_REPORTING -> CS_DESTROY
  1268. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_session.c:1665 Session 727137 (sofia/default/+447578992323@199.244.96.39) Locked, Waiting on external entities
  1269. 2021-10-21 08:12:03.826669 [NOTICE] switch_core_session.c:1683 Session 727137 (sofia/default/+447578992323@199.244.96.39) Ended
  1270. 2021-10-21 08:12:03.826669 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/+447578992323@199.244.96.39 [CS_DESTROY]
  1271. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:741 (sofia/default/+447578992323@199.244.96.39) Running State Change CS_DESTROY (Cur 0 Tot 727138)
  1272. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:751 (sofia/default/+447578992323@199.244.96.39) State DESTROY
  1273. 2021-10-21 08:12:03.826669 [DEBUG] mod_sofia.c:343 sofia/default/+447578992323@199.244.96.39 SOFIA DESTROY
  1274. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:181 sofia/default/+447578992323@199.244.96.39 Standard DESTROY
  1275. 2021-10-21 08:12:03.826669 [DEBUG] switch_core_state_machine.c:751 (sofia/default/+447578992323@199.244.96.39) State DESTROY going to sleep
  1276. freeswitch@astpp1.local> /exit
  1277.  
  1278. [root@astpp1 inextrix1]#
  1279.