From Ample Hummingbird, 5 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: green; font-weight: bold;"> 2018-11-13 13:32:30.885643 [INFO] switch_cpp.cpp:1376 Destination_number: 27101409382<-----------
  2.  
  3. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 Account_code: 3501022386
  4. 2018-11-13 13:32:30.885643 [NOTICE] switch_cpp.cpp:1376 SELECT mc.id, mc.name, mc.account_type, mc.account_code, b.balance_uuid, b.value as balance, t.name as tenant,
  5.     mc.origination, mc.orig_credit_limit, mc.max_per_minute_rate as max_rate, mc.max_daily_spend, mc.play_audio_messages as play_msgs,
  6.     mc.orig_max_channels as max_channels, mc.orig_max_cps as max_cps, tc.max_call_length as max_duration, mc.enable_calls, mc.enabled
  7.     FROM management_client mc
  8.     INNER JOIN management_tenant t ON t.id = mc.tenant_id
  9.     INNER JOIN management_tenantconfiguration tc ON ( t.id = tc.tenant_id )
  10.     INNER JOIN management_clientbalances b ON mc.id = b.client_id
  11.     WHERE mc.account_code = '3501022386' AND b.balance_type = '*monetary' AND weight = '10'
  12. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 <------- Getting Account Info for Client: Pivotal Data with account_code: 3501022386 ------->
  13.  
  14. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 -------------> Geting SIP endpoint details ------------->
  15.  
  16. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 No sip_h_X-username in header, selecting the first available SIP Endpoint
  17. 2018-11-13 13:32:30.885643 [NOTICE] switch_cpp.cpp:1376 SELECT mc.account_code, s.username, s.password, s.ip_address, s.caller_id_name as cid_name, s.caller_id_number as cid_number,
  18.     s.bypass_media as bymedia, s.pass_thru_caller_id, s.custom_sip_headers, c1.description as c1, c2.description as c2,
  19.     c3.description as c3, c4.description as c4, s.enabled
  20.     FROM management_sipaccount s
  21.     INNER JOIN management_client mc ON s.client_id = mc.id
  22.     LEFT OUTER JOIN configuration_codecpreference c1 ON s.codec1_id = c1.id
  23.     LEFT OUTER JOIN configuration_codecpreference c2 ON s.codec2_id = c2.id
  24.     LEFT OUTER JOIN configuration_codecpreference c3 ON s.codec3_id = c3.id
  25.     LEFT OUTER JOIN configuration_codecpreference c4 ON s.codec4_id = c4.id
  26.     WHERE  mc.id = '4695154687031079' AND s.enabled = 't'
  27. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 elapsed time: 0.001264
  28.  
  29. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('sip_h_X-username', 'pivotaloffice')
  30. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 ------->61c3f84a-bdf7-4a85-90bc-6be063b5812e - WS CALL Set value :.....: sip_h_X-username pivotaloffice<-------------
  31. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 -------------> Geting Client Tariff Plan info ------------->
  32.  
  33. 2018-11-13 13:32:30.885643 [NOTICE] switch_cpp.cpp:1376 SELECT mt.tech_prefix, mt.tpb_id, t.name as tpb_name, mt.routing_plan_id, r.routing_type, r.allow_negative_profit
  34.       FROM management_tpballocation mt
  35.       INNER JOIN management_client mc ON mt.client_id = mc.id
  36.       INNER JOIN routing_routingplan r ON mt.routing_plan_id = r.id
  37.       INNER JOIN tariff_plans_tpb t ON t.id = mt.tpb_id
  38.       WHERE mc.account_code = '3501022386'
  39. 2018-11-13 13:32:30.885643 [INFO] switch_cpp.cpp:1376 <----------------------------------------------------->
  40. 2018-11-13 13:32:30.885643 [INFO] switch_cpp.cpp:1376 Trying without tech prefix
  41. 2018-11-13 13:32:30.885643 [INFO] switch_cpp.cpp:1376 Dialed number : 27101409382
  42. 2018-11-13 13:32:30.885643 [INFO] switch_cpp.cpp:1376 Tariff Plan Name : COST_PRICE_CUSTOME
  43. 2018-11-13 13:32:30.885643 [INFO] switch_cpp.cpp:1376 <----------------------------------------------------->
  44.  
  45. 2018-11-13 13:32:30.885643 [WARNING] switch_cpp.cpp:1376 ------------  Getting Call cost for client  ------------
  46.  
  47. 2018-11-13 13:32:30.885643 [NOTICE] switch_cpp.cpp:1376 SELECT tp.code, tp.destination_name, tp.connect_fee, tp.rate, tp.rate_minimum, tp.rate_increment, tp.effective_date, tp.expiry_date, tp.enabled
  48.     FROM tariff_plans_rates tp
  49.     WHERE code @> '27101409382' AND tpb_id = '4695159467358691' AND tp.effective_date <= tp.expiry_date AND tp.expiry_date >= now()
  50.     ORDER BY CODE ASC LIMIT 1
  51. 2018-11-13 13:32:30.885643 [NOTICE] switch_cpp.cpp:1376 Code: 2710
  52. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(execute_on_answer=nibblebill adjust -0)
  53. 2018-11-13 13:32:30.885643 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [execute_on_answer]=[nibblebill adjust -0]
  54. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('connect_fee', '0')
  55. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('nibble_rate', '0.2')
  56. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('nibble_minimum', '0.0033333333333333')
  57. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('nibble_increment', '1')
  58. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('nibble_account', 'FCA8B96A-9A62-402A-A6C6-2D5709B2A93A')
  59. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_account', '3501022386')
  60. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('client_id', '4695154687031079')
  61. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_tenant', 'sbc.pivotaldata.co.za')
  62. 2018-11-13 13:32:30.885643 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('nobal_amt', '-5000')
  63. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za nibblebill(heartbeat 60)
  64. 2018-11-13 13:32:30.905645 [WARNING] switch_core_session.c:1653 sofia/internal/user@sbc.pivotaldata.co.za using scheduler due to bypass media or media is not established.
  65. 2018-11-13 13:32:30.905645 [DEBUG] switch_scheduler.c:249 Added task 62974 switch_core_session_sched_heartbeat (61c3f84a-bdf7-4a85-90bc-6be063b5812e) to run at 1542108810
  66. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_dept', ' ')
  67. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_site', ' ')
  68. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_extension', '2027')
  69. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_custom', ' ')
  70. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_subject', ' ')
  71. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_supplier', ' ')
  72. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_reqtype', 'POSTPAID')
  73. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('o_category', 'COST_PRICE_CUSTOME')
  74. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('prefix', '2710')
  75. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('destination_name', 'SA National_2710')
  76. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('destination_number', '27101409382')
  77. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('tor', '*voice')
  78. 2018-11-13 13:32:30.905645 [WARNING] switch_cpp.cpp:1376 -------------> Removing unwanted SIP Headers ------------->
  79.  
  80. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za unset(sip_h_X-accountcode)
  81. 2018-11-13 13:32:30.905645 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-accountcode]
  82. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za unset(sip_h_X-username)
  83. 2018-11-13 13:32:30.905645 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-username]
  84. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za unset(sip_h_X-extension)
  85. 2018-11-13 13:32:30.905645 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-extension]
  86. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za unset(sip_h_X-dept)
  87. 2018-11-13 13:32:30.905645 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-dept]
  88. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za unset(sip_h_X-site)
  89. 2018-11-13 13:32:30.905645 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-site]
  90. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za unset(sip_h_X-custom)
  91. 2018-11-13 13:32:30.905645 [DEBUG] mod_dptools.c:1743 UNSET [sip_h_X-custom]
  92. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 Checking if 27101409382 is a DID <-------------
  93. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 SELECT status from management_did where did_number = '27101409382' and status != 'ported_out'
  94. 2018-11-13 13:32:30.905645 [WARNING] switch_cpp.cpp:1376 ------------  Getting LCR info  ------------
  95.  
  96. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 SELECT *
  97.     FROM (SELECT DISTINCT ON (t.name) t.name as tariff_plan, rt.id AS rt_id, r.code, r.destination_name, r.connect_fee, r.rate, r.rate_minimum, r.rate_increment,
  98.     t.prefix, t.suffix, t.quality, t.reliability, t.client_id AS carrier_id, r.effective_date, r.expiry_date, t.gw1_id, t.gw2_id, t.gw3_id, r.enabled
  99.     FROM tariff_plans_rates r
  100.     INNER JOIN tariff_plans_tpb t ON r.tpb_id = t.id
  101.     INNER JOIN routing_routingplantariff rt ON rt.tariff_plan_id = t.id
  102.     WHERE rt.routing_plan_id = '4695162600715466' AND code @> '27101409382' AND enabled = 't' AND (CURRENT_TIMESTAMP BETWEEN r.effective_date AND r.expiry_date)
  103.     ORDER BY t.name, code ASC, "effective_date" DESC) n
  104.     ORDER BY rate ASC, quality DESC, reliability DESC
  105. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ------------------  No of routes found: 2  ------------------
  106. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ------->                   lcr_profile: lc
  107. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ------->     lcr_allow_negative_profit: 1.00
  108.  
  109. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 SELECT lead_strip, tail_strip, add_prefix, add_suffix, terminator_id
  110.         FROM routing_providernumbertranslation
  111.         WHERE routing_providernumbertranslation.terminator_id = '4694781441722884' AND substring('27101409382',1,length(lead_strip)) = lead_strip
  112. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 -----------------------  Route [1 of 2 ] -----------------------
  113. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->             lcr_code[1]: 2710
  114. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 -------> lcr_destination_name[1]: SA National_2710
  115. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->      lcr_connect_fee[1]: 0.00000
  116. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->             lcr_rate[1]: 0.15000
  117. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->     lcr_rate_minimum[1]: 1
  118. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->   lcr_rate_increment[1]: 1
  119. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->           lcr_prefix[1]:
  120. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->      lcr_tariff_plan[1]: BITCO_NAT
  121. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->       lcr_carrier_id[1]: 4694781441722884
  122. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->   lcr_effective_date[1]: 2017-09-24 21:50:39.307041
  123. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->      lcr_expiry_date[1]: 2027-09-24 21:50:40.264676
  124. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->          lcr_enabled[1]: 1
  125. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ------------------------------------------------------------------
  126.  
  127. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 SELECT lead_strip, tail_strip, add_prefix, add_suffix, terminator_id
  128.         FROM routing_providernumbertranslation
  129.         WHERE routing_providernumbertranslation.terminator_id = '4694781441722884' AND substring('27101409382',1,length(lead_strip)) = lead_strip
  130. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 -----------------------  Route [2 of 2 ] -----------------------
  131. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->             lcr_code[2]: 2710140
  132. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 -------> lcr_destination_name[2]: SOUTH AFRICA - SAICOM - GEOGRAPHIC
  133. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->      lcr_connect_fee[2]: 0.00000
  134. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->             lcr_rate[2]: 0.16000
  135. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->     lcr_rate_minimum[2]: 1
  136. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->   lcr_rate_increment[2]: 1
  137. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->           lcr_prefix[2]:
  138. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->      lcr_tariff_plan[2]: LIQUID_NAT
  139. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->       lcr_carrier_id[2]: 4694781441722884
  140. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->   lcr_effective_date[2]: 2017-09-24 21:50:39.307041
  141. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->      lcr_expiry_date[2]: 2027-09-24 21:50:40.264676
  142. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 ------->          lcr_enabled[2]: 1
  143. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ------------------------------------------------------------------
  144.  
  145. 2018-11-13 13:32:30.905645 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('sip_h_X-cid', 'a6210672-61da-1237-319a-0050560101ff')
  146. 2018-11-13 13:32:30.905645 [WARNING] switch_cpp.cpp:1376 ------->61c3f84a-bdf7-4a85-90bc-6be063b5812e - WS CALL Set value :.....: sip_h_X-cid a6210672-61da-1237-319a-0050560101ff<-------------
  147. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ----------> Call progressing through the diaplan <----------
  148.  
  149. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 --------------------- Customer rate: 0.2
  150. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ----------- Negative profit allowed: 1.00%
  151. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 ---- Maximum provider rate for call: 0.202
  152.  
  153. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za limit(hash sbc.pivotaldata.co.za pivotaloffice 15 5/1 !CLIENT_LIMIT_EXCEEDED)
  154. 2018-11-13 13:32:30.905645 [DEBUG] switch_limit.c:126 incr called: sbc.pivotaldata.co.za_pivotaloffice max:15, interval:0
  155. 2018-11-13 13:32:30.905645 [DEBUG] mod_hash.c:196 Usage for sbc.pivotaldata.co.za_pivotaloffice is now 1/15
  156. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 Limit for this client: pivotaloffice is 15 Concurrent and 5 CPS <-----------
  157.  
  158. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 -------------> Provider_1 rate: 0.15000 <-----------
  159. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 <-------------------------------------------------->
  160. 2018-11-13 13:32:30.905645 [NOTICE] switch_cpp.cpp:1376 SELECT mc.account_code, mc.account_type, mc.orig_credit_limit, mb.balance_uuid
  161.             FROM management_client mc
  162.             INNER JOIN management_clientbalances mb ON mc.id = mb.client_id
  163.             WHERE mb.client_id = '4694781441722884' AND mb.balance_type = '*monetary' AND mb.weight=10 AND mb.enabled = 't'
  164.  
  165. 2018-11-13 13:32:30.905645 [INFO] switch_cpp.cpp:1376 -----------------> Trying LCR Route = 1 of 2 <-----------------
  166.  
  167. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(execute_on_answer=nibblebill adjust -0)
  168. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [execute_on_answer]=[nibblebill adjust -0]
  169. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:connect_fee=0.00000)
  170. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [connect_fee]=[0.00000]
  171. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:nibble_rate=0.15000)
  172. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [nibble_rate]=[0.15000]
  173. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:nibble_minimum=0.0025)
  174. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [nibble_minimum]=[0.0025]
  175. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:nibble_increment=1)
  176. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [nibble_increment]=[1]
  177. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:nibble_account=C43C0362-7301-4B03-9F65-AF2655E2C67F)
  178. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [nibble_account]=[C43C0362-7301-4B03-9F65-AF2655E2C67F]
  179. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_account=6833163331)
  180. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_account]=[6833163331]
  181. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_tenant=sbc.pivotaldata.co.za)
  182. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_tenant]=[sbc.pivotaldata.co.za]
  183. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_dept= )
  184. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_dept]=[ ]
  185. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_site= )
  186. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_site]=[ ]
  187. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_extension= )
  188. 2018-11-13 13:32:30.905645 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_extension]=[ ]
  189. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_custom= )
  190. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_custom]=[ ]
  191. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_subject= )
  192. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_subject]=[ ]
  193. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_supplier= )
  194. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_supplier]=[ ]
  195. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_reqtype=POSTPAID)
  196. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_reqtype]=[POSTPAID]
  197. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:o_category=BITCO_NAT)
  198. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [o_category]=[BITCO_NAT]
  199. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:prefix=2710)
  200. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [prefix]=[2710]
  201. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:destination_name=SA National_2710)
  202. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [destination_name]=[SA National_2710]
  203. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:destination_number=27101409382)
  204. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [destination_number]=[27101409382]
  205. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:tor=*voice)
  206. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [tor]=[*voice]
  207. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za export(nolocal:nobal_amt=-50000)
  208. 2018-11-13 13:32:30.925646 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [nobal_amt]=[-50000]
  209. EXECUTE sofia/internal/user@sbc.pivotaldata.co.za nibblebill(heartbeat 60)
  210. 2018-11-13 13:32:30.925646 [WARNING] switch_core_session.c:1653 sofia/internal/user@sbc.pivotaldata.co.za using scheduler due to bypass media or media is not established.
  211. 2018-11-13 13:32:30.925646 [DEBUG] switch_scheduler.c:249 Added task 62975 switch_core_session_sched_heartbeat (61c3f84a-bdf7-4a85-90bc-6be063b5812e) to run at 1542108810
  212. 2018-11-13 13:32:30.925646 [DEBUG] switch_scheduler.c:144 Deleting task 62974 switch_core_session_sched_heartbeat (61c3f84a-bdf7-4a85-90bc-6be063b5812e)
  213. 2018-11-13 13:32:30.925646 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('execute_on_answer', 'sched_hangup +7200 allotted_timeout')
  214. 2018-11-13 13:32:30.925646 [WARNING] switch_cpp.cpp:1376 ------->61c3f84a-bdf7-4a85-90bc-6be063b5812e - WS CALL Set value :.....: execute_on_answer sched_hangup +7200 allotted_timeout<-------------
  215. 2018-11-13 13:32:30.925646 [WARNING] switch_cpp.cpp:1376 No gateway found for LCR Route [1] <-----------
  216.  
  217. 2018-11-13 13:32:30.925646 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('proto_specific_hangup_cause', 'sip:503')
  218. 2018-11-13 13:32:30.925646 [WARNING] switch_cpp.cpp:1376 ------->61c3f84a-bdf7-4a85-90bc-6be063b5812e - WS CALL Set value :.....: proto_specific_hangup_cause sip:503<-------------
  219. 2018-11-13 13:32:30.925646 [DEBUG] switch_cpp.cpp:731 CoreSession::hangup
  220. 2018-11-13 13:32:30.925646 [NOTICE] switch_cpp.cpp:733 Hangup sofia/internal/user@sbc.pivotaldata.co.za [CS_EXECUTE] [BEARERCAPABILITY_NOTAVAIL]
  221. 2018-11-13 13:32:30.925646 [DEBUG] mod_hash.c:297 Usage for sbc.pivotaldata.co.za_pivotaloffice is now 0
  222. 2018-11-13 13:32:30.925646 [WARNING] switch_cpp.cpp:1376 The call failed with BEARERCAPABILITY_NOTAVAIL <-----------
  223.  
  224. 2018-11-13 13:32:30.925646 [DEBUG] switch_cpp.cpp:1122 sofia/internal/user@sbc.pivotaldata.co.za destroy/unlink session from object
  225. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_session.c:2885 sofia/internal/user@sbc.pivotaldata.co.za skip receive message [PHONE_EVENT] (channel is hungup already)
  226. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/user@sbc.pivotaldata.co.za) State EXECUTE going to sleep
  227. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/user@sbc.pivotaldata.co.za) Running State Change CS_HANGUP (Cur 9 Tot 26715)
  228. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/user@sbc.pivotaldata.co.za) Callstate Change RINGING -> HANGUP
  229. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/user@sbc.pivotaldata.co.za) State HANGUP
  230. 2018-11-13 13:32:30.925646 [DEBUG] mod_sofia.c:449 Channel sofia/internal/user@sbc.pivotaldata.co.za hanging up, cause: BEARERCAPABILITY_NOTAVAIL
  231. 2018-11-13 13:32:30.925646 [DEBUG] mod_sofia.c:588 Responding to INVITE with: 503
  232. 2018-11-13 13:32:30.925646 [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.2 per minute to account FCA8B96A-9A62-402A-A6C6-2D5709B2A93A
  233. 2018-11-13 13:32:30.925646 [DEBUG] mod_nibblebill.c:518 Not billing FCA8B96A-9A62-402A-A6C6-2D5709B2A93A - call is not in answered state
  234. 2018-11-13 13:32:30.925646 [DEBUG] mod_nibblebill.c:422 Doing lookup query
  235. [SELECT value AS nibble_balance FROM management_clientbalances WHERE balance_uuid='FCA8B96A-9A62-402A-A6C6-2D5709B2A93A']
  236. 2018-11-13 13:32:30.925646 [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account FCA8B96A-9A62-402A-A6C6-2D5709B2A93A (balance = -4272.972695)
  237. 2018-11-13 13:32:30.925646 [DEBUG] mod_nibblebill.c:523 Comparing -4272.972695 to hangup balance of -5000.000000, taking into account minimum charge of 0.003333
  238. 2018-11-13 13:32:30.925646 [DEBUG] mod_nibblebill.c:422 Doing lookup query
  239. [SELECT value AS nibble_balance FROM management_clientbalances WHERE balance_uuid='FCA8B96A-9A62-402A-A6C6-2D5709B2A93A']
  240. 2018-11-13 13:32:30.925646 [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account FCA8B96A-9A62-402A-A6C6-2D5709B2A93A (balance = -4272.972695)
  241. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:60 sofia/internal/user@sbc.pivotaldata.co.za Standard HANGUP, cause: BEARERCAPABILITY_NOTAVAIL
  242. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/user@sbc.pivotaldata.co.za) State HANGUP going to sleep
  243. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/user@sbc.pivotaldata.co.za) State Change CS_HANGUP -> CS_REPORTING
  244. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/user@sbc.pivotaldata.co.za) Running State Change CS_REPORTING (Cur 9 Tot 26715)
  245. 2018-11-13 13:32:30.925646 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/user@sbc.pivotaldata.co.za) State REPORTING
  246. 2018-11-13 13:32:30.925646 [INFO] mod_json_cdr.c:271 Process [61c3f84a-bdf7-4a85-90bc-6be063b5812e.cdr.json]
  247. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_state_machine.c:174 sofia/internal/user@sbc.pivotaldata.co.za Standard REPORTING, cause: BEARERCAPABILITY_NOTAVAIL
  248. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/user@sbc.pivotaldata.co.za) State REPORTING going to sleep
  249. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/user@sbc.pivotaldata.co.za) State Change CS_REPORTING -> CS_DESTROY
  250. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_session.c:1713 Session 26715 (sofia/internal/user@sbc.pivotaldata.co.za) Locked, Waiting on external entities
  251. 2018-11-13 13:32:30.965649 [NOTICE] switch_core_session.c:1731 Session 26715 (sofia/internal/user@sbc.pivotaldata.co.za) Ended
  252. 2018-11-13 13:32:30.965649 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/user@sbc.pivotaldata.co.za [CS_DESTROY]
  253. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/user@sbc.pivotaldata.co.za) Running State Change CS_DESTROY (Cur 8 Tot 26715)
  254. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/user@sbc.pivotaldata.co.za) State DESTROY
  255. 2018-11-13 13:32:30.965649 [DEBUG] mod_sofia.c:354 sofia/internal/user@sbc.pivotaldata.co.za SOFIA DESTROY
  256. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_state_machine.c:181 sofia/internal/user@sbc.pivotaldata.co.za Standard DESTROY
  257. 2018-11-13 13:32:30.965649 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/user@sbc.pivotaldata.co.za) State DESTROY going to sleep
  258. 2018-11-13 13:32:31.425644 [DEBUG] switch_scheduler.c:144 Deleting task 62975 switch_core_session_sched_heartbeat (61c3f84a-bdf7-4a85-90bc-6be063b5812e)
  259. 2018-11-13 13:32:32.305686 [NOTICE] sofia.c:1079 Hangup sofia/external/+27768220212@154.118.252.36 [CS_EXECUTE] [NORMAL_CLEARING]
  260. 2018-11-13 13:32:32.305686 [DEBUG] mod_hash.c:297 Usage for inbound_27104760100 is now 3
  261. 2018-11-13 13:32:32.305686 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/external/+27768220212@154.118.252.36]
  262. 2018-11-13 13:32:32.325664 [DEBUG] switch_ivr_bridge.c:825 sofia/external/+27768220212@154.118.252.36 ending bridge by request from write function
  263. 2018-11-13 13:32:32.325664 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/external_priv/0104760100@10.241.32.60:5060]
  264. 2018-11-13 13:32:32.325664 [NOTICE] switch_ivr_bridge.c:1034 Hangup sofia/external_priv/0104760100@10.241.32.60:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  265. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0 per minute to account E80EE2A6-744A-48CE-BCAA-EE0E3988BF43
  266. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:573 11 seconds passed since last bill time of 2018-11-13 13:32:20
  267. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:590 Billing $0.000000 to E80EE2A6-744A-48CE-BCAA-EE0E3988BF43 (Call: e6235368-ad6a-4d1c-95cd-ae35864ec736 / 0.000000 so far)
  268. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:395 Doing update query
  269. [UPDATE management_clientbalances SET value=value- 0.000000 WHERE balance_uuid='E80EE2A6-744A-48CE-BCAA-EE0E3988BF43']
  270. 2018-11-13 13:32:32.325664 [DEBUG] switch_ivr_bridge.c:1843 sofia/external_priv/0104760100@10.241.32.60:5060 skip receive message [TRANSFER] (channel is hungup already)
  271. 2018-11-13 13:32:32.325664 [DEBUG] switch_ivr_bridge.c:1846 sofia/external/+27768220212@154.118.252.36 skip receive message [TRANSFER] (channel is hungup already)
  272. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_session.c:2885 sofia/external/+27768220212@154.118.252.36 skip receive message [PHONE_EVENT] (channel is hungup already)
  273. 2018-11-13 13:32:32.325664 [WARNING] switch_cpp.cpp:1376 Primary Route Disposition: NORMAL_CLEARING <-----------
  274.  
  275. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:395 Doing update query
  276. [UPDATE management_clientbalances SET value=value- 0.000000 WHERE balance_uuid='E80EE2A6-744A-48CE-BCAA-EE0E3988BF43']
  277. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:422 Doing lookup query
  278. [SELECT value AS nibble_balance FROM management_clientbalances WHERE balance_uuid='E80EE2A6-744A-48CE-BCAA-EE0E3988BF43']
  279. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account E80EE2A6-744A-48CE-BCAA-EE0E3988BF43 (balance = -9.868668)
  280. 2018-11-13 13:32:32.325664 [WARNING] switch_core_session.c:1653 sofia/external_priv/0104760100@10.241.32.60:5060 using scheduler due to bypass media or media is not established.
  281. 2018-11-13 13:32:32.325664 [DEBUG] switch_scheduler.c:249 Added task 62976 switch_core_session_sched_heartbeat (e6235368-ad6a-4d1c-95cd-ae35864ec736) to run at 1542108812
  282. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:653 (sofia/external_priv/0104760100@10.241.32.60:5060) State EXCHANGE_MEDIA going to sleep
  283. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:584 (sofia/external_priv/0104760100@10.241.32.60:5060) Running State Change CS_HANGUP (Cur 8 Tot 26715)
  284. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:850 (sofia/external_priv/0104760100@10.241.32.60:5060) Callstate Change ACTIVE -> HANGUP
  285. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:852 (sofia/external_priv/0104760100@10.241.32.60:5060) State HANGUP
  286. 2018-11-13 13:32:32.325664 [DEBUG] mod_sofia.c:443 sofia/external_priv/0104760100@10.241.32.60:5060 Overriding SIP cause 480 with 200 from the other leg
  287. 2018-11-13 13:32:32.325664 [DEBUG] mod_sofia.c:449 Channel sofia/external_priv/0104760100@10.241.32.60:5060 hanging up, cause: NORMAL_CLEARING
  288. 2018-11-13 13:32:32.325664 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/external_priv/0104760100@10.241.32.60:5060
  289. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0 per minute to account E80EE2A6-744A-48CE-BCAA-EE0E3988BF43
  290. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:554 Received heartbeat, but final bill has been committed - ignoring
  291. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:422 Doing lookup query
  292. [SELECT value AS nibble_balance FROM management_clientbalances WHERE balance_uuid='E80EE2A6-744A-48CE-BCAA-EE0E3988BF43']
  293. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account E80EE2A6-744A-48CE-BCAA-EE0E3988BF43 (balance = -9.868668)
  294. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:60 sofia/external_priv/0104760100@10.241.32.60:5060 Standard HANGUP, cause: NORMAL_CLEARING
  295. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:852 (sofia/external_priv/0104760100@10.241.32.60:5060) State HANGUP going to sleep
  296. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:619 (sofia/external_priv/0104760100@10.241.32.60:5060) State Change CS_HANGUP -> CS_REPORTING
  297. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:584 (sofia/external_priv/0104760100@10.241.32.60:5060) Running State Change CS_REPORTING (Cur 8 Tot 26715)
  298. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:938 (sofia/external_priv/0104760100@10.241.32.60:5060) State REPORTING
  299. 2018-11-13 13:32:32.325664 [INFO] mod_json_cdr.c:271 Process [e6235368-ad6a-4d1c-95cd-ae35864ec736.cdr.json]
  300. 2018-11-13 13:32:32.325664 [DEBUG] switch_cpp.cpp:1122 sofia/external/+27768220212@154.118.252.36 destroy/unlink session from object
  301. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_session.c:2885 sofia/external/+27768220212@154.118.252.36 skip receive message [PHONE_EVENT] (channel is hungup already)
  302. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+27768220212@154.118.252.36) State EXECUTE going to sleep
  303. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+27768220212@154.118.252.36) Running State Change CS_HANGUP (Cur 8 Tot 26715)
  304. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:850 (sofia/external/+27768220212@154.118.252.36) Callstate Change ACTIVE -> HANGUP
  305. 2018-11-13 13:32:32.325664 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+27768220212@154.118.252.36) State HANGUP
  306. 2018-11-13 13:32:32.325664 [DEBUG] mod_sofia.c:449 Channel sofia/external/+27768220212@154.118.252.36 hanging up, cause: NORMAL_CLEARING
  307. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:506 Attempting to bill at $0.00 per minute to account C43C0362-7301-4B03-9F65-AF2655E2C67F
  308. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:573 9 seconds passed since last bill time of 2018-11-13 13:32:22
  309. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:590 Billing $0.000000 to C43C0362-7301-4B03-9F65-AF2655E2C67F (Call: 3fb7605b-fa34-46d0-b246-e9f2b2231d2f / 0.000000 so far)
  310. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:395 Doing update query
  311. [UPDATE management_clientbalances SET value=value- 0.000000 WHERE balance_uuid='C43C0362-7301-4B03-9F65-AF2655E2C67F']
  312. 2018-11-13 13:32:32.325664 [DEBUG] mod_nibblebill.c:395 Doing update query
  313. [UPDATE management_clientbalances SET value=value- 0.000000 WHERE balance_uuid='C43C0362-7301-4B03-9F65-AF2655E2C67F']
  314. 2018-11-13 13:32:32.345672 [DEBUG] mod_nibblebill.c:422 Doing lookup query
  315. [SELECT value AS nibble_balance FROM management_clientbalances WHERE balance_uuid='C43C0362-7301-4B03-9F65-AF2655E2C67F']
  316. 2018-11-13 13:32:32.345672 [DEBUG] mod_nibblebill.c:430 Retrieved current balance for account C43C0362-7301-4B03-9F65-AF2655E2C67F (balance = -2871.182205)
  317. 2018-11-13 13:32:32.345672 [DEBUG] switch_core_state_machine.c:60 sofia/external/+27768220212@154.118.252.36 Standard HANGUP, cause: NORMAL_CLEARING
  318. 2018-11-13 13:32:32.345672 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+27768220212@154.118.252.36) State HANGUP going to sleep
  319. 2018-11-13 13:32:32.345672 [DEBUG] switch_core_state_machine.c:619 (sofia/external/+27768220212@154.118.252.36) State Change CS_HANGUP -> CS_REPORTING
  320. 2018-11-13 13:32:32.345672 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+27768220212@154.118.252.36) Running State Change CS_REPORTING (Cur 8 Tot 26715)
  321. 2018-11-13 13:32:32.345672 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+27768220212@154.118.252.36) State REPORTING
  322. 2018-11-13 13:32:32.345672 [INFO] mod_json_cdr.c:271 Process [3fb7605b-fa34-46d0-b246-e9f2b2231d2f.cdr.json]
  323. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:174 sofia/external_priv/0104760100@10.241.32.60:5060 Standard REPORTING, cause: NORMAL_CLEARING
  324. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:938 (sofia/external_priv/0104760100@10.241.32.60:5060) State REPORTING going to sleep
  325. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:610 (sofia/external_priv/0104760100@10.241.32.60:5060) State Change CS_REPORTING -> CS_DESTROY
  326. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_session.c:1713 Session 26707 (sofia/external_priv/0104760100@10.241.32.60:5060) Locked, Waiting on external entities
  327. 2018-11-13 13:32:32.365671 [NOTICE] switch_core_session.c:1731 Session 26707 (sofia/external_priv/0104760100@10.241.32.60:5060) Ended
  328. 2018-11-13 13:32:32.365671 [NOTICE] switch_core_session.c:1735 Close Channel sofia/external_priv/0104760100@10.241.32.60:5060 [CS_DESTROY]
  329. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:741 (sofia/external_priv/0104760100@10.241.32.60:5060) Running State Change CS_DESTROY (Cur 7 Tot 26715)
  330. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:751 (sofia/external_priv/0104760100@10.241.32.60:5060) State DESTROY
  331. 2018-11-13 13:32:32.365671 [DEBUG] mod_sofia.c:354 sofia/external_priv/0104760100@10.241.32.60:5060 SOFIA DESTROY
  332. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:181 sofia/external_priv/0104760100@10.241.32.60:5060 Standard DESTROY
  333. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:751 (sofia/external_priv/0104760100@10.241.32.60:5060) State DESTROY going to sleep
  334. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:174 sofia/external/+27768220212@154.118.252.36 Standard REPORTING, cause: NORMAL_CLEARING
  335. 2018-11-13 13:32:32.365671 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+27768220212@154.118.252.36) State REPORTING going to sleep
  336. 2018-11-13 13:32:32.385683 [DEBUG] switch_core_state_machine.c:610 (sofia/external/+27768220212@154.118.252.36) State Change CS_REPORTING -> CS_DESTROY
  337. 2018-11-13 13:32:32.385683 [DEBUG] switch_core_session.c:1713 Session 26706 (sofia/external/+27768220212@154.118.252.36) Locked, Waiting on external entities
  338. 2018-11-13 13:32:32.385683 [NOTICE] switch_core_session.c:1731 Session 26706 (sofia/external/+27768220212@154.118.252.36) Ended
  339. 2018-11-13 13:32:32.385683 [NOTICE] switch_core_session.c:1735 Close Channel sofia/external/+27768220212@154.118.252.36 [CS_DESTROY]
  340. 2018-11-13 13:32:32.385683 [DEBUG] switch_core_state_machine.c:741 (sofia/external/+27768220212@154.118.252.36) Running State Change CS_DESTROY (Cur 6 Tot 26715)
  341. 2018-11-13 13:32:32.385683 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+27768220212@154.118.252.36) State DESTROY
  342. 2018-11-13 13:32:32.385683 [DEBUG] mod_sofia.c:354 sofia/external/+27768220212@154.118.252.36 SOFIA DESTROY
  343. 2018-11-13 13:32:32.385683 [DEBUG] switch_core_state_machine.c:181 sofia/external/+27768220212@154.118.252.36 Standard DESTROY
  344. 2018-11-13 13:32:32.385683 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+27768220212@154.118.252.36) State DESTROY going to sleep
  345. 2018-11-13 13:32:32.825655 [DEBUG] switch_scheduler.c:144 Deleting task 62958 switch_core_session_sched_heartbeat (3fb7605b-fa34-46d0-b246-e9f2b2231d2f)
  346. 2018-11-13 13:32:32.825655 [DEBUG] switch_scheduler.c:144 Deleting task 62976 switch_core_session_sched_heartbeat (e6235368-ad6a-4d1c-95cd-ae35864ec736)
  347. freeswitch@FS01> /exit
  348.  
  349. root@FS01:~# !sn
  350. sngrep
  351. root@FS01:~# !sn^C
  352. root@FS01:~# sngrep -c
  353. root@FS01:~# bearer capability not presently available [Q.850]
  354.