<html xmlns:v="urn:schemas-microsoft-com:vml" xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns="http://www.w3.org/TR/REC-html40">
<head>
<meta http-equiv="Content-Type" content="text/html; charset=us-ascii">
<meta name="Generator" content="Microsoft Word 15 (filtered medium)">
<style><!--
/* Font Definitions */
@font-face
{font-family:"Cambria Math";
panose-1:2 4 5 3 5 4 6 3 2 4;}
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0cm;
margin-bottom:.0001pt;
font-size:11.0pt;
font-family:"Calibri",sans-serif;
mso-fareast-language:EN-US;}
a:link, span.MsoHyperlink
{mso-style-priority:99;
color:#0563C1;
text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
{mso-style-priority:99;
color:#954F72;
text-decoration:underline;}
p.MsoListParagraph, li.MsoListParagraph, div.MsoListParagraph
{mso-style-priority:34;
margin-top:0cm;
margin-right:0cm;
margin-bottom:0cm;
margin-left:36.0pt;
margin-bottom:.0001pt;
font-size:11.0pt;
font-family:"Calibri",sans-serif;
mso-fareast-language:EN-US;}
span.EmailStyle17
{mso-style-type:personal-compose;
font-family:"Calibri",sans-serif;
color:windowtext;}
.MsoChpDefault
{mso-style-type:export-only;
font-family:"Calibri",sans-serif;
mso-fareast-language:EN-US;}
@page WordSection1
{size:612.0pt 792.0pt;
margin:72.0pt 72.0pt 72.0pt 72.0pt;}
div.WordSection1
{page:WordSection1;}
--></style><!--[if gte mso 9]><xml>
<o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext="edit">
<o:idmap v:ext="edit" data="1" />
</o:shapelayout></xml><![endif]-->
</head>
<body lang="EN-AU" link="#0563C1" vlink="#954F72">
<div class="WordSection1">
<p class="MsoNormal">Hi All,<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">Have been pulling my hair out trying to work out why we’re getting a strange delay upon completion of a t38 fax using rxfax.<o:p></o:p></p>
<p class="MsoNormal">Upon finishing the receiving of a fax, and sending BYE to the originator, freeswitch seems to keep the channel open for exactly 30 seconds then tries to issue another hangup.<o:p></o:p></p>
<p class="MsoNormal">Have tried all sorts of things in dialplan, including moving the answer to before the hangup hook – I prefer the answer to be post hangup hook to aid in hunting to different servers in case of API call failures.<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">What’s also weird, 30 seconds after rxfax completes there are debug entries for time stamps in the CLI from calls in the past?!@<o:p></o:p></p>
<p class="MsoNormal">If I do a show channels at idle – there are 0 total<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt">freeswitch@7f9729cbe5af> show channels<o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"><o:p> </o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt">0 total.<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">----------------------------<o:p></o:p></p>
<p class="MsoNormal">Dialplan as follows<o:p></o:p></p>
<p class="MsoNormal">----------------------------<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"><?xml version="1.0" encoding="UTF-8" standalone="no"?><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"><document type="freeswitch/xml"><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <section name="dialplan" description="Incoming Fax"><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <context name="public"><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <extension name="Fax{{ destination_number }}"><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <condition field="destination_number" expression="^({{ destination_number }})$"><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="set" data="api_hangup_hook=system /data/python/process_fax.py {{ uuid }} /tmp/{{ uuid }}.tif {{ formats }} --caller {{ caller }} --fax_result_code \\\${fax_result_$........................cut<o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="answer" /><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="playback" data="silence_stream://2000"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> {% if subscription.verbose_logging %}<o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="set" data="fax_verbose=true"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> {% endif %}<o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="set" data="fax_enable_t38_request=true"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="set" data="fax_enable_t38=true"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="set" data="fax_ident={{ subscription.rendered_fax_ident }}"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="set" data="fax_header={{ subscription.rendered_fax_header }}"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="rxfax" data="/tmp/{{ uuid }}.tif"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> <action application="hangup"/><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> </condition><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> </extension><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> </context><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"> </section><o:p></o:p></p>
<p class="MsoNormal" style="margin-left:36.0pt"></document><o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">----------------------------<o:p></o:p></p>
<p class="MsoNormal">Debug logs<o:p></o:p></p>
<p class="MsoNormal">----------------------------<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_spandsp_fax.c:496 ==============================================================================<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_spandsp_fax.c:502 Fax successfully received.<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_spandsp_fax.c:526 ==============================================================================<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T FAX exchange complete<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Call completed<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state CALL_FINISHED<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_codec.c:248 sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx Restore previous codec PCMA:8.<o:p></o:p></p>
<p class="MsoNormal">EXECUTE sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx hangup()<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [NOTICE] mod_dptools.c:1286 Hangup sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_session.c:2815 sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_state_machine.c:650 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State EXECUTE going to sleep<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_state_machine.c:584 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Running State Change CS_HANGUP (Cur 1 Tot 68)<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_state_machine.c:850 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Callstate Change ACTIVE -> HANGUP<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_state_machine.c:852 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State HANGUP<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_sofia.c:438 Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx hanging up, cause: NORMAL_CLEARING<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">>> here’s the BYE<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_state_machine.c:60 sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx Standard HANGUP, cause: NORMAL_CLEARING<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [DEBUG] switch_core_state_machine.c:852 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State HANGUP going to sleep<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">>> 30 second delay???<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:783 Hangup Command with Session system(/data/python/process_fax.py 4407e217-a0bb-4e04-a185-b2e42c6e36ff /tmp/4407e217-a0bb-4e04-a185-b2e42c6e36ff.tif --pdf --pdf --caller 61XXXXXXXXX
--fax_result_code 0 --fax_result_text 'OK' --fax_remote_station_id '' --fax_document_total_pages 2 --fax_document_transferred_pages 2 --fax_image_resolution '8040x3850' --fax_image_size 37216 --fax_success 1 --fax_v17_disabled 0 --t38_gateway_format '' --t38_peer
'' --fax_transfer_rate 14400 --t38_trace_read ''):<o:p></o:p></p>
<p class="MsoNormal">Fax host is api:8000<o:p></o:p></p>
<p class="MsoNormal">Fax host scheme is http<o:p></o:p></p>
<p class="MsoNormal">{'fax_document_total_pages': '2', 't38_gateway_format': '', 't38_trace_read': '', 't38_peer': '', 'fax_image_resolution': '8040x3850', 'fax_result_text': 'OK', 'fax_v17_disabled': '0', 'caller': '61XXXXXXXXX', 'fax_remote_station_id': '',
'fax_result_code': '0', 'fax_document_transferred_pages': '2', 'fax_image_size': '37216', 'fax_transfer_rate': '14400'}<o:p></o:p></p>
<p class="MsoNormal">Updating fax status: http://api:8000/fax/update<o:p></o:p></p>
<p class="MsoNormal">OK<o:p></o:p></p>
<p class="MsoNormal">Attempting to convert<o:p></o:p></p>
<p class="MsoNormal">Updating fax status: http://api:8000/fax/update<o:p></o:p></p>
<p class="MsoNormal">OK<o:p></o:p></p>
<p class="MsoNormal">Uploading fax files: http://api:8000/fax/upload<o:p></o:p></p>
<p class="MsoNormal">{'tiff': <open file '/tmp/4407e217-a0bb-4e04-a185-b2e42c6e36ff.tif', mode 'rb' at 0x7fb570877e40>, 'pdf': <open file '/tmp/3f8e90cc-5b01-4d82-8674-18227449b9c0.pdf', mode 'rb' at 0x7fb570877ed0>}<o:p></o:p></p>
<p class="MsoNormal">3.32.124.126 [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">> > Notices for calls which completed in the past??<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">2018-08-08 10:33:54.207573 [NOTICE] switch_core_session.c:1683 Session 65 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Ended<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:33:54.207573 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_DESTROY]<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.707631 [NOTICE] switch_channel.c:1104 New Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [9a93cb39-1f2b-4ce2-bfc3-5feee3c9fa01]<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.707631 [INFO] mod_dialplan_xml.c:637 Processing 61XXXXXXXXX <61XXXXXXXXX>->61XXXXXXXXX in context public<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.787568 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx!<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.787568 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx] has been answered<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:48.487606 [NOTICE] mod_dptools.c:1286 Hangup sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:40:19.608111 [NOTICE] switch_core_session.c:1683 Session 66 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Ended<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:40:19.608111 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_DESTROY]<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.127574 [NOTICE] switch_channel.c:1104 New Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [33f5c499-20e6-4a29-a6f6-daa90b6d35dc]<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.127574 [INFO] mod_dialplan_xml.c:637 Processing 61XXXXXXXXX <61XXXXXXXXX>->61XXXXXXXXX in context public<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.207595 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx!<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.207595 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx] has been answered<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:52:46.007578 [NOTICE] mod_dptools.c:1286 Hangup sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:53:17.807983 [NOTICE] switch_core_session.c:1683 Session 67 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Ended<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:53:17.807983 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_DESTROY]<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.187572 [NOTICE] switch_channel.c:1104 New Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [4407e217-a0bb-4e04-a185-b2e42c6e36ff]<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.187572 [INFO] mod_dialplan_xml.c:637 Processing 61XXXXXXXXX <61XXXXXXXXX>->61XXXXXXXXX in context public<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.307599 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx!<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.307599 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx] has been answered<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [NOTICE] mod_dptools.c:1286 Hangup sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">3.32.124.126 [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:33:54.207573 [NOTICE] switch_core_session.c:1683 Session 65 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Ended<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:33:54.207573 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_DESTROY]<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.707631 [NOTICE] switch_channel.c:1104 New Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [9a93cb39-1f2b-4ce2-bfc3-5feee3c9fa01]<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.707631 [INFO] mod_dialplan_xml.c:637 Processing 61XXXXXXXXX <61XXXXXXXXX>->61XXXXXXXXX in context public<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.787568 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx!<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:01.787568 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx] has been answered<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:39:48.487606 [NOTICE] mod_dptools.c:1286 Hangup sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:40:19.608111 [NOTICE] switch_core_session.c:1683 Session 66 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Ended<o:p></o:p></p>
<p class="MsoNormal">2018-08-08 10:40:19.608111 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_DESTROY]<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.127574 [NOTICE] switch_channel.c:1104 New Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [33f5c499-20e6-4a29-a6f6-daa90b6d35dc]<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.127574 [INFO] mod_dialplan_xml.c:637 Processing 61XXXXXXXXX <61XXXXXXXXX>->61XXXXXXXXX in context public<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.207595 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx!<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:51:59.207595 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx] has been answered<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:52:46.007578 [NOTICE] mod_dptools.c:1286 Hangup sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:53:17.807983 [NOTICE] switch_core_session.c:1683 Session 67 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Ended<o:p></o:p></p>
<p class="MsoNormal">2018-08-09 09:53:17.807983 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_DESTROY]<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.187572 [NOTICE] switch_channel.c:1104 New Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [4407e217-a0bb-4e04-a185-b2e42c6e36ff]<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.187572 [INFO] mod_dialplan_xml.c:637 Processing 61XXXXXXXXX <61XXXXXXXXX>->61XXXXXXXXX in context public<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.307599 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx!<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:40:52.307599 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx] has been answered<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:41:39.127583 [NOTICE] mod_dptools.c:1286 Hangup sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_EXECUTE] [NORMAL_CLEARING]<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:619 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State Change CS_HANGUP -> CS_REPORTING<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:584 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Running State Change CS_REPORTING (Cur 1 Tot 68)<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:938 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State REPORTING<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:174 sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx Standard REPORTING, cause: NORMAL_CLEARING<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:938 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State REPORTING going to sleep<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:610 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State Change CS_REPORTING -> CS_DESTROY<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_session.c:1665 Session 68 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Locked, Waiting on external entities<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [NOTICE] switch_core_session.c:1683 Session 68 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Ended<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx [CS_DESTROY]<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:741 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) Running State Change CS_DESTROY (Cur 0 Tot 68)<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:751 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State DESTROY<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] mod_sofia.c:343 sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx SOFIA DESTROY<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:181 sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx Standard DESTROY<o:p></o:p></p>
<p class="MsoNormal">2018-08-13 05:42:10.847595 [DEBUG] switch_core_state_machine.c:751 (sofia/external/61XXXXXXXXX@2xx.xx.xxx.xxx) State DESTROY going to sleep<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">Cheers,<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">Tim<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
</div>
</body>
</html>