<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;}
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-CA link="#0563C1" vlink="#954F72"><div class=WordSection1><p class=MsoNormal><a href="mailto:freeswitch-users@lists.freeswitch.org"></a> Still no solutions, Here is a log of a call. Any suggestion would be appreciated<o:p></o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal><o:p> </o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/15068559226) State ROUTING<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] mod_sofia.c:143 sofia/internal/15068559226 SOFIA ROUTING<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/15068559226) State Change CS_ROUTING -> CS_CONSUME_MEDIA<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/15068559226) State ROUTING going to sleep<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/15068559226) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 136)<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/15068559226) State CONSUME_MEDIA<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/15068559226) State CONSUME_MEDIA going to sleep<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.236974 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [proceeding][183]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] sofia.c:7058 Remote SDP:<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 v=0<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 o=FreeSWITCH 1493799827 1493799828 IN IP4 72.55.158.152<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 s=FreeSWITCH<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 c=IN IP4 72.55.158.152<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 t=0 0<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 m=audio 22274 RTP/AVP 0 101 13<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:0 PCMU/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:101 telephone-event/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=fmtp:101 0-16<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:13 CN/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=ptime:20<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 <o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 101@8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/15068559226 PCMU/8000 20 ms 160 samples 64000 bits 1 channels<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_codec.c:111 sofia/internal/15068559226 Original read codec set to PCMU:0<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 101@8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:4754 sofia/internal/15068559226 Set 2833 dtmf send payload to 101 recv payload to 101<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/15068559226] 192.168.20.161 port 29764 -> 72.55.158.152 port 22274 codec: 0 ms: 20<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:7166 sofia/internal/15068559226 Set 2833 dtmf send payload to 101<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:7173 sofia/internal/15068559226 Set 2833 dtmf receive payload to 101<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:7196 sofia/internal/15068559226 Set rtp dtmf delay to 40<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:7202 Set comfort noise payload to 13<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/15068559226!<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_channel.c:3473 (sofia/internal/15068559226) Callstate Change DOWN -> EARLY<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_ivr_originate.c:410 Setting codec string on sofia/internal/200@192.168.20.161 to PCMU@8000h@20i<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [INFO] switch_ivr_originate.c:3639 Sending early media<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/200@192.168.20.161] 192.168.20.161 port 16564 -> 192.168.20.150 port 19542 codec: 0 ms: 20<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_core_media.c:7166 sofia/internal/200@192.168.20.161 Set 2833 dtmf send payload to 101<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_core_media.c:7173 sofia/internal/200@192.168.20.161 Set 2833 dtmf receive payload to 101<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_core_media.c:7196 sofia/internal/200@192.168.20.161 Set rtp dtmf delay to 40<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] mod_sofia.c:2363 Ring SDP:<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c v=0<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c o=FreeSWITCH 1493805534 1493805535 IN IP4 192.168.20.161<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c s=FreeSWITCH<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c c=IN IP4 192.168.20.161<o:p></o:p></p><p class=MsoNormal><span lang=FR-CA>cc1d0c18-e576-47a6-bf4f-35ab835f920c t=0 0<o:p></o:p></span></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c m=audio 16564 RTP/AVP 0 101<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:0 PCMU/8000<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:101 telephone-event/8000<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=fmtp:101 0-16<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=ptime:20<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=sendrecv<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c <o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [NOTICE] mod_sofia.c:2366 Pre-Answer sofia/internal/200@192.168.20.161!<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_channel.c:3473 (sofia/internal/200@192.168.20.161) <span style='color:red'>Callstate Change RINGING -> EARLY</span><o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/15068559226]<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] sofia.c:7048 Channel sofia/internal/200@192.168.20.161 entering state [early][183]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG] switch_ivr_bridge.c:1601 (sofia/internal/15068559226) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/15068559226) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 136)<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/15068559226) State EXCHANGE_MEDIA<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.796985 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.877072 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.317073 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [completing][200]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.317073 [DEBUG] sofia.c:7055 Duplicate SDP<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 v=0<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 o=FreeSWITCH 1493799827 1493799828 IN IP4 72.55.158.152<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 s=FreeSWITCH<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 c=IN IP4 72.55.158.152<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 t=0 0<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 m=audio 22274 RTP/AVP 0 101 13<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:0 PCMU/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:101 telephone-event/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=fmtp:101 0-16<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:13 CN/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=ptime:20<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 <o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [ready][200]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [NOTICE] sofia.c:8123 Channel [sofia/internal/15068559226]<span style='color:red'> has been answered</span><o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [DEBUG] switch_channel.c:3772 (sofia/internal/15068559226) <span style='color:red'>Callstate Change EARLY -> ACTIVE</span><o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG] switch_core_media.c:6848 Audio params are unchanged for sofia/internal/200@192.168.20.161.<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/200@192.168.20.161:<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c v=0<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c o=FreeSWITCH 1493805534 1493805536 IN IP4 192.168.20.161<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c s=FreeSWITCH<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c c=IN IP4 192.168.20.161<o:p></o:p></p><p class=MsoNormal><span lang=FR-CA>cc1d0c18-e576-47a6-bf4f-35ab835f920c t=0 0<o:p></o:p></span></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c m=audio 16564 RTP/AVP 0 101<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:0 PCMU/8000<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:101 telephone-event/8000<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=fmtp:101 0-16<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=ptime:20<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c a=sendrecv<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c <o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [NOTICE] switch_ivr_bridge.c:623 Channel [sofia/internal/200@192.168.20.161] has been answered<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG] switch_channel.c:3772 (sofia/internal/200@192.168.20.161) Callstate Change EARLY -> ACTIVE<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG] sofia.c:7048 Channel sofia/internal/200@192.168.20.161 entering state [completed][200]<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.377070 [DEBUG] sofia.c:7048 Channel sofia/internal/200@192.168.20.161 entering state [ready][200]<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.396999 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.396999 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.<o:p></o:p></p><p class=MsoNormal>2017-05-03 11:35:00.396999 [INFO] sofia.c:1279 sofia/internal/15068559226 Update Callee ID to "Outbound Call" <+15068559226><o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:02.717070 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:03.757073 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [completing][200]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:03.757073 [DEBUG] sofia.c:7055 Duplicate SDP<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 v=0<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 o=FreeSWITCH 1493799827 1493799828 IN IP4 72.55.158.152<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 s=FreeSWITCH<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 c=IN IP4 72.55.158.152<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 t=0 0<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 m=audio 22274 RTP/AVP 0 101 13<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:0 PCMU/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:101 telephone-event/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=fmtp:101 0-16<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:13 CN/8000<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 a=ptime:20<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 <o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:03.777033 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [ready][200]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:03.737072 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]<o:p></o:p></p><p class=MsoNormal><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 entering state [terminating][408]<o:p></o:p></span></p><p class=MsoNormal><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [NOTICE] sofia.c:8237 Hangup sofia/internal/15068559226 [CS_EXCHANGE_MEDIA] [RECOVERY_ON_TIMER_EXPIRE]<o:p></o:p></span></p><p class=MsoNormal><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_ivr_bridge.c:712 sofia/internal/15068559226 ending bridge by request from read function<o:p></o:p></span></p><p class=MsoNormal><b><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/15068559226]<o:p></o:p></span></b></p><p class=MsoNormal><b><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/15068559226) State EXCHANGE_MEDIA going to sleep<o:p></o:p></span></b></p><p class=MsoNormal><b><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/15068559226) Running State Change CS_HANGUP (Cur 2 Tot 136)<o:p></o:p></span></b></p><p class=MsoNormal><b><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/15068559226) Callstate Change ACTIVE -> HANGUP<o:p></o:p></span></b></p><p class=MsoNormal><b><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/15068559226) State HANGUP<o:p></o:p></span></b></p><p class=MsoNormal><b><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] mod_sofia.c:438 Channel sofia/internal/15068559226 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE<o:p></o:p></span></b></p><p class=MsoNormal><b><span style='color:red'>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:60 sofia/internal/15068559226 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE<o:p></o:p></span></b></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/15068559226) State HANGUP going to sleep<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/15068559226) State Change CS_HANGUP -> CS_REPORTING<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/15068559226) Running State Change CS_REPORTING (Cur 2 Tot 136)<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/15068559226) State REPORTING<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:174 sofia/internal/15068559226 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/15068559226) State REPORTING going to sleep<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/15068559226) State Change CS_REPORTING -> CS_DESTROY<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_session.c:1664 Session 136 (sofia/internal/15068559226) Locked, Waiting on external entities<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/15068559226 ending bridge by request from write function<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/200@192.168.20.161]<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [NOTICE] switch_ivr_bridge.c:1751 Hangup sofia/internal/200@192.168.20.161 [CS_EXECUTE] [RECOVERY_ON_TIMER_EXPIRE]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [NOTICE] switch_core_session.c:1682 Session 136 (sofia/internal/15068559226) Ended<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/15068559226 [CS_DESTROY]<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/15068559226) Running State Change CS_DESTROY (Cur 1 Tot 136)<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_session.c:2814 sofia/internal/200@192.168.20.161 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/200@192.168.20.161) State EXECUTE going to sleep<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/200@192.168.20.161) Running State Change CS_HANGUP (Cur 1 Tot 136)<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/15068559226) State DESTROY<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] mod_sofia.c:343 sofia/internal/15068559226 SOFIA DESTROY<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/200@192.168.20.161) Callstate Change ACTIVE -> HANGUP<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/200@192.168.20.161) State HANGUP<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] mod_sofia.c:432 sofia/internal/200@192.168.20.161 Overriding SIP cause 504 with 408 from the other leg<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] mod_sofia.c:438 Channel sofia/internal/200@192.168.20.161 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/200@192.168.20.161<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:60 sofia/internal/200@192.168.20.161 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/200@192.168.20.161) State HANGUP going to sleep<o:p></o:p></p><p class=MsoNormal>2017-05-03 11:37:35.757070 [DEBUG] switch_nat.c:542 unmapped public port 29764 protocol UDP to localport 29764<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/200@192.168.20.161) State Change CS_HANGUP -> CS_REPORTING<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/200@192.168.20.161) Running State Change CS_REPORTING (Cur 1 Tot 136)<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/200@192.168.20.161) State REPORTING<o:p></o:p></p><p class=MsoNormal>2017-05-03 11:37:35.757070 [DEBUG] switch_nat.c:542 unmapped public port 29765 protocol UDP to localport 29765<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:181 sofia/internal/15068559226 Standard DESTROY<o:p></o:p></p><p class=MsoNormal>3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/15068559226) State DESTROY going to sleep<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG] switch_core_state_machine.c:174 sofia/internal/200@192.168.20.161 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/200@192.168.20.161) State REPORTING going to sleep<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/200@192.168.20.161) State Change CS_REPORTING -> CS_DESTROY<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG] switch_core_session.c:1664 Session 135 (sofia/internal/200@192.168.20.161) Locked, Waiting on external entities<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [NOTICE] switch_core_session.c:1682 Session 135 (sofia/internal/200@192.168.20.161) Ended<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/200@192.168.20.161 [CS_DESTROY]<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/200@192.168.20.161) Running State Change CS_DESTROY (Cur 0 Tot 136)<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/200@192.168.20.161) State DESTROY<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG] mod_sofia.c:343 sofia/internal/200@192.168.20.161 SOFIA DESTROY<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG] switch_core_state_machine.c:181 sofia/internal/200@192.168.20.161 Standard DESTROY<o:p></o:p></p><p class=MsoNormal>cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/200@192.168.20.161) State DESTROY going to sleep<o:p></o:p></p><p class=MsoNormal><o:p> </o:p></p></div></body></html>