<div dir="ltr">Here is the Siptrace coming from the Nortel to FS<br><br>+OK log level console [0]<br>freeswitch@internal&gt; sofia global siptrace on<br>+OK Global siptrace on<br>recv 2230 bytes from udp/[10.20.30.121]:5060 at 13:40:16.646438:<br>
   ------------------------------------------------------------------------<br>   INVITE sip:57331;phone-context=location.systemname@sip.nortel.net:5080;maddr=10.20.30.123;transport=udp;user=phone;x-nt-redirect=redirect-server SIP/2.0<br>
   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;<br>
   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>   CSeq: 1 INVITE<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5f0-aaf3b1c7-6e065518<br>   Max-Forwards: 70<br>   Supported: 100rel,x-nortel-sipvc,replaces<br>
   User-Agent: Nortel CS1000 SIP GW release_7.0 version_ssLinux-7.00.20<br>   P-Asserted-Identity: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;<br>
   Privacy: none<br>   History-Info: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;index=1<br>   x-nt-corr-id: 0001e65a082a171c02@00c08b0bbfdc-e4e16711<br>
   Contact: &lt;sip:52321;phone-context=location.systemname@sip.nortel.net:5060;maddr=10.20.30.121;transport=udp;user=phone&gt;<br>   Allow: INVITE,ACK,BYE,REGISTER,REFER,NOTIFY,CANCEL,PRACK,OPTIONS,INFO,SUBSCRIBE,UPDATE<br>
   Content-Type: multipart/mixed;boundary=unique-boundary-1<br>   Content-Length: 1008<br>   <br>   --unique-boundary-1<br>   Content-Type: application/sdp<br>   <br>   v=0<br>   o=- 270184 1 IN IP4 10.20.30.121<br>   s=-<br>
   c=IN IP4 10.20.30.122<br>   t=0 0<br>   m=audio 5252 RTP/AVP 0 8 18 101 111<br>   c=IN IP4 10.20.30.122<br>   a=fmtp:18 annexb=no<br>   a=rtpmap:101 telephone-event/8000<br>   a=fmtp:101 0-15<br>   a=rtpmap:111 X-nt-inforeq/8000<br>
   a=ptime:20<br>   a=sendrecv<br>   <br>   --unique-boundary-1<br>   Content-Type: application/x-nt-mcdn-frag-hex;version=ssLinux-7.00.20;base=x2611<br>   Content-Disposition: signal;handling=optional<br>   <br>   05000002<br>
   0107130081900000a200<br>   09090f00e9a0830001008000<br>   1315070011fa0f00a10d02010102020100cc040000754a00<br>   1e0403008183<br>   460e01000a0001000100010000000000<br>   4a1c01001800010000000000000023617137990004000000000025230100<br>
   --unique-boundary-1<br>   Content-Type: application/x-nt-esn5-frag-hex<br>   Content-Disposition: signal;handling=optional<br>   <br>   103<br>   --unique-boundary-1<br>   Content-Type: application/x-nt-epid-frag-hex;version=ssLinux-7.00.20;base=x2611<br>
   Content-Disposition: signal;handling=optional<br>   <br>   011201<br>   80:17:7d:4e:b2:38<br>   --unique-boundary-1--<br>   ------------------------------------------------------------------------<br>send 490 bytes to udp/[10.20.30.121]:5060 at 13:40:16.647161:<br>
   ------------------------------------------------------------------------<br>   SIP/2.0 100 Trying<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5f0-aaf3b1c7-6e065518<br>   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>
   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;<br>   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>   CSeq: 1 INVITE<br>
   User-Agent: FreeSWITCH-mod_sofia/1.2.22+git~20140224T012236Z~aabf813ae1~64bit<br>   Content-Length: 0<br>   <br>   ------------------------------------------------------------------------<br>send 1207 bytes to udp/[10.20.30.121]:5060 at 13:40:16.654280:<br>
   ------------------------------------------------------------------------<br>   SIP/2.0 200 OK<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5f0-aaf3b1c7-6e065518<br>   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>
   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=tme5Hj11yUycm<br>   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>
   CSeq: 1 INVITE<br>   Contact: &lt;sip:57331;phone-context=location.systemname@10.20.30.123:5080;transport=udp&gt;<br>   User-Agent: FreeSWITCH-mod_sofia/1.2.22+git~20140224T012236Z~aabf813ae1~64bit<br>   Accept: application/sdp<br>
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY<br>   Supported: timer, path, replaces<br>   Allow-Events: talk, hold, conference, refer<br>   Content-Type: application/sdp<br>   Content-Disposition: session<br>
   Content-Length: 222<br>   P-Asserted-Identity: &quot;57331;phone-context=location.systemname&quot; &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>&gt;<br>
   <br>   v=0<br>   o=FreeSWITCH 1393568474 1393568475 IN IP4 10.20.30.123<br>   s=FreeSWITCH<br>   c=IN IP4 10.20.30.123<br>   t=0 0<br>   m=audio 26342 RTP/AVP 0 101<br>   a=rtpmap:0 PCMU/8000<br>   a=rtpmap:101 telephone-event/8000<br>
   a=fmtp:101 0-16<br>   a=ptime:20<br>   ------------------------------------------------------------------------<br>recv 855 bytes from udp/[10.20.30.121]:5060 at 13:40:16.657553:<br>   ------------------------------------------------------------------------<br>
   ACK sip:57331;phone-context=location.systemname@10.20.30.123:5080;transport=udp SIP/2.0<br>   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>
   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=tme5Hj11yUycm<br>   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>
   CSeq: 1 ACK<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5f0-aaf3b1d3-5012ca8a<br>   Max-Forwards: 70<br>   User-Agent: Nortel CS1000 SIP GW release_7.0 version_ssLinux-7.00.20<br>   x-nt-corr-id: 0001e65a082a171c02@00c08b0bbfdc-e4e16711<br>
   Contact: &lt;sip:52321;phone-context=location.systemname@sip.nortel.net:5060;maddr=10.20.30.121;transport=udp;user=phone&gt;<br>   Allow: INVITE,ACK,BYE,REGISTER,REFER,NOTIFY,CANCEL,PRACK,OPTIONS,INFO,SUBSCRIBE,UPDATE<br>
   Content-Length: 0<br>   <br>   ------------------------------------------------------------------------<br>recv 905 bytes from udp/[10.20.30.121]:5060 at 13:40:16.658829:<br>   ------------------------------------------------------------------------<br>
   OPTIONS sip:57331;phone-context=location.systemname@10.20.30.123:5080;transport=udp SIP/2.0<br>   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>
   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=tme5Hj11yUycm<br>   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>
   CSeq: 2 OPTIONS<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5f0-aaf3b1d5-82ef650<br>   Max-Forwards: 70<br>   Supported: 100rel,x-nortel-sipvc,replaces<br>   User-Agent: Nortel CS1000 SIP GW release_7.0 version_ssLinux-7.00.20<br>
   x-nt-corr-id: 0001e65a082a171c02@00c08b0bbfdc-e4e16711<br>   Contact: &lt;sip:52321;phone-context=location.systemname@sip.nortel.net:5060;maddr=10.20.30.121;transport=udp;user=phone&gt;<br>   Allow: INVITE,ACK,BYE,REGISTER,REFER,NOTIFY,CANCEL,PRACK,OPTIONS,INFO,SUBSCRIBE,UPDATE<br>
   Content-Length: 0<br>   <br>   ------------------------------------------------------------------------<br>send 1041 bytes to udp/[10.20.30.121]:5060 at 13:40:16.674438:<br>   ------------------------------------------------------------------------<br>
   SIP/2.0 200 OK<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5f0-aaf3b1d5-82ef650<br>   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>
   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=tme5Hj11yUycm<br>   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>
   CSeq: 2 OPTIONS<br>   Contact: &lt;sip:57331;phone-context=location.systemname@10.20.30.123:5080;transport=udp&gt;<br>   User-Agent: FreeSWITCH-mod_sofia/1.2.22+git~20140224T012236Z~aabf813ae1~64bit<br>   Accept: application/sdp<br>
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY<br>   Supported: timer, path, replaces<br>   Allow-Events: talk, hold, conference, refer<br>   Content-Type: application/sdp<br>   Content-Length: 218<br>
   <br>   v=0<br>   o=FreeSWITCH 1393568474 1393568475 IN IP4 10.20.30.123<br>   s=FreeSWITCH<br>   c=IN IP4 10.20.30.123<br>   t=0 0<br>   m=audio 0 RTP/AVP 0 101<br>   a=rtpmap:0 PCMU/8000<br>   a=rtpmap:101 telephone-event/8000<br>
   a=fmtp:101 0-16<br>   a=ptime:20<br>   ------------------------------------------------------------------------<br>send 2373 bytes to udp/[10.20.30.151]:5060 at 13:40:20.244041:<br>   ------------------------------------------------------------------------<br>
   INVITE <a href="mailto:sip%3A57331@10.20.30.151">sip:57331@10.20.30.151</a> SIP/2.0<br>   Via: SIP/2.0/UDP 10.20.30.123;rport;branch=z9hG4bKSS3Kc2y6eNaFm<br>   Max-Forwards: 68<br>   From: &quot;52321;phone-context=location.systemname&quot; &lt;sip:52321;phone-context=<a href="mailto:location.systemname@10.20.30.123">location.systemname@10.20.30.123</a>&gt;;tag=K8vUtjFrQUjtg<br>
   To: &lt;<a href="mailto:sip%3A57331@10.20.30.151">sip:57331@10.20.30.151</a>&gt;<br>   Call-ID: b5166f21-1b20-1232-d8b6-d243871458ea<br>   CSeq: 56461346 INVITE<br>   Contact: &lt;<a href="http://sip:mod_sofia@10.20.30.123:5060">sip:mod_sofia@10.20.30.123:5060</a>&gt;<br>
   User-Agent: FreeSWITCH-mod_sofia/1.2.22+git~20140224T012236Z~aabf813ae1~64bit<br>   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>   Supported: timer, path, replaces<br>
   Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer<br>   Privacy: none<br>   Content-Type: multipart/mixed;boundary=ddb7d988-a07d-11e3-9b14-09241ec9370d<br>
   Content-Length: 1250<br>   x-nt-corr-id: 0001e65a082a171c02@00c08b0bbfdc-e4e16711<br>   X-FS-Support: update_display,send_info<br>   P-Asserted-Identity: &quot;52321;phone-context=location.systemname&quot; &lt;sip:52321;phone-context=<a href="mailto:location.systemname@10.20.30.123">location.systemname@10.20.30.123</a>&gt;<br>
   <br>   --ddb7d988-a07d-11e3-9b14-09241ec9370d<br>   Content-Type: application/sdp<br>   Content-Length: 262<br>   <br>   v=0<br>   o=- 270184 1 IN IP4 10.20.30.121<br>   s=-<br>   c=IN IP4 10.20.30.122<br>   t=0 0<br>   m=audio 5252 RTP/AVP 0 8 18 101 111<br>
   c=IN IP4 10.20.30.122<br>   a=fmtp:18 annexb=no<br>   a=rtpmap:101 telephone-event/8000<br>   a=fmtp:101 0-15<br>   a=rtpmap:111 X-nt-inforeq/8000<br>   a=ptime:20<br>   a=sendrecv<br>   <br>   --ddb7d988-a07d-11e3-9b14-09241ec9370d<br>
   Content-Type: application/x-nt-mcdn-frag-hex<br>   Content-Length: 217<br>   <br>   05000002<br>   0107130081900000a200<br>   09090f00e9a0830001008000<br>   1315070011fa0f00a10d02010102020100cc040000754a00<br>   1e0403008183<br>
   460e01000a0001000100010000000000<br>   4a1c01001800010000000000000023617137990004000000000025230100<br>   --ddb7d988-a07d-11e3-9b14-09241ec9370d<br>   Content-Type: application/x-nt-esn5-frag-hex<br>   Content-Length: 4<br>
   <br>   103<br>   --ddb7d988-a07d-11e3-9b14-09241ec9370d<br>   Content-Type: application/x-nt-epid-frag-hex<br>   Content-Length: 26<br>   <br>   011201<br>   80:17:7d:4e:b2:38<br>   --ddb7d988-a07d-11e3-9b14-09241ec9370d<br>
   Content-Type: application/sdp<br>   Content-Length: 208<br>   <br>   v=0<br>   o=FreeSWITCH 1393575650 1393575651 IN IP4 10.20.30.123<br>   s=FreeSWITCH<br>   c=IN IP4 10.20.30.123<br>   t=0 0<br>   m=audio 19170 RTP/AVP 0 8 101 13<br>
   a=rtpmap:101 telephone-event/8000<br>   a=fmtp:101 0-16<br>   a=ptime:20<br>   a=sendrecv<br>   <br>   --ddb7d988-a07d-11e3-9b14-09241ec9370d--<br>   ------------------------------------------------------------------------<br>
recv 506 bytes from udp/[10.20.30.151]:5060 at 13:40:20.257663:<br>   ------------------------------------------------------------------------<br>   SIP/2.0 415 Unsupported Media Type<br>   Via: SIP/2.0/UDP 10.20.30.123;rport;branch=z9hG4bKSS3Kc2y6eNaFm<br>
   From: &quot;52321;phone-context=location.systemname&quot; &lt;sip:52321;phone-context=<a href="mailto:location.systemname@10.20.30.123">location.systemname@10.20.30.123</a>&gt;;tag=K8vUtjFrQUjtg<br>   To: &quot;Joel White&quot; &lt;<a href="mailto:sip%3A57331@10.20.30.151">sip:57331@10.20.30.151</a>&gt;;tag=AC57CD1-9FDEC368<br>
   CSeq: 56461346 INVITE<br>   Call-ID: b5166f21-1b20-1232-d8b6-d243871458ea<br>   Contact: &lt;<a href="mailto:sip%3A57331@10.20.30.151">sip:57331@10.20.30.151</a>&gt;<br>   User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.6.0314<br>
   Accept-Language: en<br>   Content-Length: 0<br>   <br>   ------------------------------------------------------------------------<br>send 409 bytes to udp/[10.20.30.151]:5060 at 13:40:20.257909:<br>   ------------------------------------------------------------------------<br>
   ACK <a href="mailto:sip%3A57331@10.20.30.151">sip:57331@10.20.30.151</a> SIP/2.0<br>   Via: SIP/2.0/UDP 10.20.30.123;rport;branch=z9hG4bKSS3Kc2y6eNaFm<br>   Max-Forwards: 68<br>   From: &quot;52321;phone-context=location.systemname&quot; &lt;sip:52321;phone-context=<a href="mailto:location.systemname@10.20.30.123">location.systemname@10.20.30.123</a>&gt;;tag=K8vUtjFrQUjtg<br>
   To: &quot;Joel White&quot; &lt;<a href="mailto:sip%3A57331@10.20.30.151">sip:57331@10.20.30.151</a>&gt;;tag=AC57CD1-9FDEC368<br>   Call-ID: b5166f21-1b20-1232-d8b6-d243871458ea<br>   CSeq: 56461346 ACK<br>   Content-Length: 0<br>
   <br>   ------------------------------------------------------------------------<br>recv 1068 bytes from udp/[10.20.30.121]:5060 at 13:40:28.637828:<br>   ------------------------------------------------------------------------<br>
   BYE sip:57331;phone-context=location.systemname@10.20.30.123:5080;transport=udp SIP/2.0<br>   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>
   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=tme5Hj11yUycm<br>   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>
   CSeq: 3 BYE<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5fc-aaf3e09e-75565f26<br>   Max-Forwards: 70<br>   Supported: 100rel,x-nortel-sipvc,replaces<br>   User-Agent: Nortel CS1000 SIP GW release_7.0 version_ssLinux-7.00.20<br>
   x-nt-corr-id: 0001e65a082a171c02@00c08b0bbfdc-e4e16711<br>   Allow: INVITE,ACK,BYE,REGISTER,REFER,NOTIFY,CANCEL,PRACK,OPTIONS,INFO,SUBSCRIBE,UPDATE<br>   Content-Type: application/sdp<br>   Content-Length: 261<br>   <br>
   v=0<br>   o=- 270184 1 IN IP4 10.20.30.121<br>   s=-<br>   c=IN IP4 10.20.30.122<br>   t=0 0<br>   m=audio 5252 RTP/AVP 0 8 18 101 111<br>   c=IN IP4 10.20.30.122<br>   a=fmtp:18 annexb=no<br>   a=rtpmap:101 telephone-event/8000<br>
   a=fmtp:101 0-15<br>   a=rtpmap:111 X-nt-inforeq/8000<br>   a=ptime:20<br>   a=sendrecv<br>   ------------------------------------------------------------------------<br>send 625 bytes to udp/[10.20.30.121]:5060 at 13:40:28.648789:<br>
   ------------------------------------------------------------------------<br>   SIP/2.0 200 OK<br>   Via: SIP/2.0/UDP 10.20.30.121:5060;branch=z9hG4bK-aed5fc-aaf3e09e-75565f26<br>   From: &lt;sip:52321;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=2ffc578-a0b6f0a-13c4-55013-aed5f0-7c8d636b-aed5f0<br>
   To: &lt;sip:57331;phone-context=<a href="mailto:location.systemname@sip.nortel.net">location.systemname@sip.nortel.net</a>;user=phone&gt;;tag=tme5Hj11yUycm<br>   Call-ID: 4c26298-a0b6f0a-13c4-55013-aed5f0-13370bf5-aed5f0<br>
   CSeq: 3 BYE<br>   User-Agent: FreeSWITCH-mod_sofia/1.2.22+git~20140224T012236Z~aabf813ae1~64bit<br>   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY<br>   Supported: timer, path, replaces<br>
   Content-Length: 0<br>   <br>   ------------------------------------------------------------------------<br><br></div><div class="gmail_extra"><br><br><div class="gmail_quote">On Thu, Feb 27, 2014 at 3:42 PM, Brian West <span dir="ltr">&lt;<a href="mailto:brian@freeswitch.org" target="_blank">brian@freeswitch.org</a>&gt;</span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">sofia global siptrace on<br>
<br>
That will help me a little more.<br>
<div class="im HOEnZb"><br>
--<br>
Brian West<br>
<a href="mailto:brian@freeswitch.org">brian@freeswitch.org</a><br>
FreeSWITCH Solutions, LLC<br>
PO BOX 2531<br>
Brookfield, WI 53008-2531<br>
Twitter: @FreeSWITCH , @briankwest<br>
<a href="http://www.freeswitchbook.com" target="_blank">http://www.freeswitchbook.com</a><br>
<a href="http://www.freeswitchcookbook.com" target="_blank">http://www.freeswitchcookbook.com</a><br>
<br>
T: <a href="tel:%2B1.918.420.9001" value="+19184209001">+1.918.420.9001</a>  |  F: <a href="tel:%2B1.918.420.9002" value="+19184209002">+1.918.420.9002</a>  |  M: +1.918.424.WEST<br>
iNUM: <a href="tel:%2B883%205100%201420%209001" value="+883510014209001">+883 5100 1420 9001</a><br>
ISN: 410*543<br>
Skype:briankwest<br>
PGP Key: <a href="http://www.bkw.org/key.txt" target="_blank">http://www.bkw.org/key.txt</a> (AB93356707C76CED)<br>
<br>
<br>
<br>
<br>
<br>
<br>
<br>
<br>
<br>
<br>
<br>
<br>
<br>
</div><div class="HOEnZb"><div class="h5">On Feb 27, 2014, at 2:23 PM, Joel White &lt;<a href="mailto:joelewhite@gmail.com">joelewhite@gmail.com</a>&gt; wrote:<br>
<br>
&gt; I did add a line to the public incoming xml that uses the Say command to repeat back what $1 variable is set to.  I figured it might help me in debugging<br>
&gt;<br>
&gt;<br>
&gt;<br>
&gt; +OK log level debug [7]<br>
&gt; 2014-02-27 15:13:05.086534 [NOTICE] switch_channel.c:1050 New Channel sofia/external/23673 [910ede7a-9feb-11e3-9a55-09241ec9370d]<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_NEW<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] sofia.c:7868 sofia/external/23673 receiving invite from <a href="http://10.111.11.10:5060" target="_blank">10.111.11.10:5060</a> version: 1.2.22 git aabf813 2014-02-24 01:22:36Z 64bit<br>

&gt; 2014-02-27 15:13:05.086534 [DEBUG] sofia.c:5811 Channel sofia/external/23673 entering state [received][100]<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] sofia.c:5824 Remote SDP:<br>
&gt; v=0<br>
&gt; o=- 269323 1 IN IP4 10.111.11.10<br>
&gt; s=-<br>
&gt; c=IN IP4 10.111.11.17<br>
&gt; t=0 0<br>
&gt; m=audio 5218 RTP/AVP 0 8 18 101 111<br>
&gt; c=IN IP4 10.111.11.17<br>
&gt; a=fmtp:18 annexb=no<br>
&gt; a=rtpmap:101 telephone-event/8000<br>
&gt; a=fmtp:101 0-15<br>
&gt; a=rtpmap:111 X-nt-inforeq/8000<br>
&gt; a=ptime:20<br>
&gt;<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] sofia.c:6071 (sofia/external/23673) State Change CS_NEW -&gt; CS_INIT<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_state_machine.c:434 (sofia/external/23673) State NEW<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_INIT<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_state_machine.c:455 (sofia/external/23673) State INIT<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] mod_sofia.c:87 sofia/external/23673 SOFIA INIT<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] mod_sofia.c:127 (sofia/external/23673) State Change CS_INIT -&gt; CS_ROUTING<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_state_machine.c:455 (sofia/external/23673) State INIT going to sleep<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_ROUTING<br>
&gt; 2014-02-27 15:13:05.086534 [DEBUG] switch_channel.c:2163 (sofia/external/23673) Callstate Change DOWN -&gt; RINGING<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_state_machine.c:471 (sofia/external/23673) State ROUTING<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] mod_sofia.c:150 sofia/external/23673 SOFIA ROUTING<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_state_machine.c:117 sofia/external/23673 Standard ROUTING<br>
&gt; 2014-02-27 15:13:05.106494 [INFO] mod_dialplan_xml.c:558 Processing 23673;phone-context=location.systemname &lt;23673;phone-context=location.systemname&gt;-&gt;28365;phone-context=location.systemname in context public<br>

&gt; Dialplan: sofia/external/23673 parsing [public-&gt;unloop] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [public-&gt;outside_call] continue=true<br>
&gt; Dialplan: sofia/external/23673 Absolute Condition [outside_call]<br>
&gt; Dialplan: sofia/external/23673 Action set(outside_call=true)<br>
&gt; Dialplan: sofia/external/23673 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})<br>
&gt; Dialplan: sofia/external/23673 parsing [public-&gt;call_debug] continue=true<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never<br>
&gt; Dialplan: sofia/external/23673 parsing [public-&gt;public_extensions] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [public_extensions] destination_number(28365;phone-context=location.systemname) =~ /^([456][0-9][0-9][0-9]/d?)$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [public-&gt;public_did_mgw00_inbound] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [public_did_mgw00_inbound] destination_number(28365;phone-context=location.systemname) =~ /^(\d{5})$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [public-&gt;public_did_nortel_inbound] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (PASS) [public_did_nortel_inbound] destination_number(28365;phone-context=location.systemname) =~ /^(\d{5});(.*)$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 Action answer()<br>
&gt; Dialplan: sofia/external/23673 Action say(en number iterated 28365)<br>
&gt; Dialplan: sofia/external/23673 Action sleep(1000)<br>
&gt; Dialplan: sofia/external/23673 Action set(domain_name=10.20.30.123)<br>
&gt; Dialplan: sofia/external/23673 Action transfer(28365 XML default)<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_state_machine.c:167 (sofia/external/23673) State Change CS_ROUTING -&gt; CS_EXECUTE<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_state_machine.c:471 (sofia/external/23673) State ROUTING going to sleep<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_EXECUTE<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_state_machine.c:478 (sofia/external/23673) State EXECUTE<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] mod_sofia.c:243 sofia/external/23673 SOFIA EXECUTE<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_state_machine.c:209 sofia/external/23673 Standard EXECUTE<br>
&gt; EXECUTE sofia/external/23673 set(outside_call=true)<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [outside_call]=[true]<br>
&gt; EXECUTE sofia/external/23673 export(RFC2822_DATE=Thu, 27 Feb 2014 15:13:05 -0500)<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_channel.c:1242 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 27 Feb 2014 15:13:05 -0500]<br>
&gt; EXECUTE sofia/external/23673 answer()<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:3190 Set Codec sofia/external/23673 PCMU/8000 20 ms 160 samples 64000 bits<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_codec.c:111 sofia/external/23673 Original read codec set to PCMU:0<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:5451 Set 2833 dtmf send/recv payload to 101<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:3449 AUDIO RTP [sofia/external/23673] 10.20.30.123 port 20854 -&gt; 10.111.11.17 port 5218 codec: 0 ms: 20<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_rtp.c:2033 Starting timer [soft] 160 bytes per 20ms<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:3716 Set 2833 dtmf send payload to 101<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:3722 Set 2833 dtmf receive payload to 101<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia_glue.c:3749 sofia/external/23673 Set rtp dtmf delay to 40<br>
&gt; 2014-02-27 15:13:05.106494 [NOTICE] sofia_glue.c:4360 Pre-Answer sofia/external/23673!<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_channel.c:3367 (sofia/external/23673) Callstate Change RINGING -&gt; EARLY<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] mod_sofia.c:866 Local SDP sofia/external/23673:<br>
&gt; v=0<br>
&gt; o=FreeSWITCH 1393511131 1393511132 IN IP4 10.20.30.123<br>
&gt; s=FreeSWITCH<br>
&gt; c=IN IP4 10.20.30.123<br>
&gt; t=0 0<br>
&gt; m=audio 20854 RTP/AVP 0 101<br>
&gt; a=rtpmap:0 PCMU/8000<br>
&gt; a=rtpmap:101 telephone-event/8000<br>
&gt; a=fmtp:101 0-16<br>
&gt; a=ptime:20<br>
&gt; a=sendrecv<br>
&gt;<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] sofia.c:5811 Channel sofia/external/23673 entering state [completed][200]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_session.c:871 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.106494 [NOTICE] mod_dptools.c:1225 Channel [sofia/external/23673] has been answered<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_channel.c:3646 (sofia/external/23673) Callstate Change EARLY -&gt; ACTIVE<br>
&gt; EXECUTE sofia/external/23673 say(en number iterated 28365)<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.106494 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:05.126485 [DEBUG] sofia.c:5811 Channel sofia/external/23673 entering state [ready][200]<br>
&gt; 2014-02-27 15:13:05.126485 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms<br>
&gt; 2014-02-27 15:13:05.226537 [DEBUG] switch_rtp.c:3749 Correct ip/port confirmed.<br>
&gt; 2014-02-27 15:13:07.666516 [DEBUG] switch_ivr_play_say.c:1717 done playing file file_string://digits/2.wav!digits/8.wav!digits/3.wav!digits/6.wav!digits/5.wav<br>
&gt; EXECUTE sofia/external/23673 sleep(1000)<br>
&gt; EXECUTE sofia/external/23673 set(domain_name=10.20.30.123)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [domain_name]=[10.20.30.123]<br>
&gt; EXECUTE sofia/external/23673 transfer(28365 XML default)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_ivr.c:1832 (sofia/external/23673) State Change CS_EXECUTE -&gt; CS_ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_session.c:871 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:08.666549 [NOTICE] switch_ivr.c:1839 Transfer sofia/external/23673 to XML[28365@default]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:478 (sofia/external/23673) State EXECUTE going to sleep<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:2163 (sofia/external/23673) Callstate Change ACTIVE -&gt; RINGING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:471 (sofia/external/23673) State ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:150 sofia/external/23673 SOFIA ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:117 sofia/external/23673 Standard ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [INFO] mod_dialplan_xml.c:558 Processing 23673;phone-context=location.systemname &lt;23673;phone-context=location.systemname&gt;-&gt;28365 in context default<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;unloop] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;tod_example] continue=true<br>
&gt; Dialplan: sofia/external/23673 Date/Time Match (PASS) [tod_example] break=on-false<br>
&gt; Dialplan: sofia/external/23673 Action set(open=true)<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;tod_brevard] continue=true<br>
&gt; Dialplan: sofia/external/23673 Date/Time Match (PASS) [tod_brevard] break=on-false<br>
&gt; Dialplan: sofia/external/23673 Action set(open=true)<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;holiday_example] continue=true<br>
&gt; Dialplan: sofia/external/23673 Date/TimeMatch (FAIL) [holiday_example] break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;global-intercept] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [global-intercept] destination_number(28365) =~ /^886$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;group-intercept] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [group-intercept] destination_number(28365) =~ /^\*8$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;intercept-ext] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [intercept-ext] destination_number(28365) =~ /^\*\*(\d+)$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;redial] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [redial] destination_number(28365) =~ /^(redial|870)$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;global] continue=true<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [global] ${endpoint_disposition}(ANSWER) =~ /^(DELAYED NEGOTIATION)/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;eavesdrop] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [eavesdrop] destination_number(28365) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;eavesdrop] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [eavesdrop] destination_number(28365) =~ /^779$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;call_return] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [call_return] destination_number(28365) =~ /^\*69$|^869$|^lcr$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;del-group] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [del-group] destination_number(28365) =~ /^80(\d{2})$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;add-group] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [add-group] destination_number(28365) =~ /^81(\d{2})$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;call-group-simo] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [call-group-simo] destination_number(28365) =~ /^82(\d{2})$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;call-group-order] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [call-group-order] destination_number(28365) =~ /^83(\d{2})$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;extension-intercom] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [extension-intercom] destination_number(28365) =~ /^8([456][0-9][0-9][0-9][0-9])$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;Local_Extension_4_digit] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (FAIL) [Local_Extension_4_digit] destination_number(28365) =~ /^(10[01][0-9])$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 parsing [default-&gt;Local_Extension_5_digit] continue=false<br>
&gt; Dialplan: sofia/external/23673 Regex (PASS) [Local_Extension_5_digit] destination_number(28365) =~ /^(5[0-9][0-9][0-9][0-9])$/ break=on-false<br>
&gt; Dialplan: sofia/external/23673 Action export(dialed_extension=28365)<br>
&gt; Dialplan: sofia/external/23673 Action bind_meta_app(1 b s execute_extension::dx XML features)<br>
&gt; Dialplan: sofia/external/23673 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)<br>
&gt; Dialplan: sofia/external/23673 Action bind_meta_app(3 b s execute_extension::cf XML features)<br>
&gt; Dialplan: sofia/external/23673 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)<br>
&gt; Dialplan: sofia/external/23673 Action set(ringback=${us-ring})<br>
&gt; Dialplan: sofia/external/23673 Action set(transfer_ringback=local_stream://moh)<br>
&gt; Dialplan: sofia/external/23673 Action set(call_timeout=30)<br>
&gt; Dialplan: sofia/external/23673 Action set(hangup_after_bridge=true)<br>
&gt; Dialplan: sofia/external/23673 Action set(continue_on_fail=true)<br>
&gt; Dialplan: sofia/external/23673 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})<br>
&gt; Dialplan: sofia/external/23673 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})<br>
&gt; Dialplan: sofia/external/23673 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})<br>
&gt; Dialplan: sofia/external/23673 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})<br>
&gt; Dialplan: sofia/external/23673 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})<br>
&gt; Dialplan: sofia/external/23673 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})<br>
&gt; Dialplan: sofia/external/23673 Action bridge(user/${dialed_extension}@${domain_name})<br>
&gt; Dialplan: sofia/external/23673 Action answer()<br>
&gt; Dialplan: sofia/external/23673 Action sleep(1000)<br>
&gt; Dialplan: sofia/external/23673 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:167 (sofia/external/23673) State Change CS_ROUTING -&gt; CS_EXECUTE<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:471 (sofia/external/23673) State ROUTING going to sleep<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_EXECUTE<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:2165 (sofia/external/23673) Callstate Change RINGING -&gt; ACTIVE<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:478 (sofia/external/23673) State EXECUTE<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:243 sofia/external/23673 SOFIA EXECUTE<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:209 sofia/external/23673 Standard EXECUTE<br>
&gt; EXECUTE sofia/external/23673 set(open=true)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [open]=[true]<br>
&gt; EXECUTE sofia/external/23673 set(open=true)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [open]=[true]<br>
&gt; EXECUTE sofia/external/23673 export(dialed_extension=28365)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:1242 EXPORT (export_vars) [dialed_extension]=[28365]<br>
&gt; EXECUTE sofia/external/23673 bind_meta_app(1 b s execute_extension::dx XML features)<br>
&gt; 2014-02-27 15:13:08.666549 [INFO] switch_ivr_async.c:3701 Bound B-Leg: *1 execute_extension::dx XML features<br>
&gt; EXECUTE sofia/external/23673 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/23673;phone-context=location.systemname.2014-02-27-15-13-08.wav)<br>
&gt; 2014-02-27 15:13:08.666549 [INFO] switch_ivr_async.c:3701 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/23673;phone-context=location.systemname.2014-02-27-15-13-08.wav<br>
&gt; EXECUTE sofia/external/23673 bind_meta_app(3 b s execute_extension::cf XML features)<br>
&gt; 2014-02-27 15:13:08.666549 [INFO] switch_ivr_async.c:3701 Bound B-Leg: *3 execute_extension::cf XML features<br>
&gt; EXECUTE sofia/external/23673 bind_meta_app(4 b s execute_extension::att_xfer XML features)<br>
&gt; 2014-02-27 15:13:08.666549 [INFO] switch_ivr_async.c:3701 Bound B-Leg: *4 execute_extension::att_xfer XML features<br>
&gt; EXECUTE sofia/external/23673 set(ringback=%(2000,4000,440,480))<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [ringback]=[%(2000,4000,440,480)]<br>
&gt; EXECUTE sofia/external/23673 set(transfer_ringback=local_stream://moh)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [transfer_ringback]=[local_stream://moh]<br>
&gt; EXECUTE sofia/external/23673 set(call_timeout=30)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [call_timeout]=[30]<br>
&gt; EXECUTE sofia/external/23673 set(hangup_after_bridge=true)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [hangup_after_bridge]=[true]<br>
&gt; EXECUTE sofia/external/23673 set(continue_on_fail=true)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [continue_on_fail]=[true]<br>
&gt; EXECUTE sofia/external/23673 hash(insert/10.20.30.123-call_return/28365/23673;phone-context=location.systemname)<br>
&gt; EXECUTE sofia/external/23673 hash(insert/10.20.30.123-last_dial_ext/28365/910ede7a-9feb-11e3-9a55-09241ec9370d)<br>
&gt; EXECUTE sofia/external/23673 set(called_party_callgroup=techsupport)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_dptools.c:1402 sofia/external/23673 SET [called_party_callgroup]=[techsupport]<br>
&gt; EXECUTE sofia/external/23673 hash(insert/10.20.30.123-last_dial_ext/techsupport/910ede7a-9feb-11e3-9a55-09241ec9370d)<br>
&gt; EXECUTE sofia/external/23673 hash(insert/10.20.30.123-last_dial_ext/global/910ede7a-9feb-11e3-9a55-09241ec9370d)<br>
&gt; EXECUTE sofia/external/23673 hash(insert/10.20.30.123-last_dial/techsupport/910ede7a-9feb-11e3-9a55-09241ec9370d)<br>
&gt; EXECUTE sofia/external/23673 bridge(user/<a href="mailto:28365@10.20.30.123">28365@10.20.30.123</a>)<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:1763 (sofia/external/23673) Callstate Change ACTIVE -&gt; RING_WAIT<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:1196 sofia/external/23673 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 27 Feb 2014 15:13:05 -0500] to event<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:1196 sofia/external/23673 EXPORTING[export_vars] [dialed_extension]=[28365] to event<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_ivr_originate.c:2069 Parsing global variables<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:1196 sofia/external/23673 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 27 Feb 2014 15:13:05 -0500] to event<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_channel.c:1196 sofia/external/23673 EXPORTING[export_vars] [dialed_extension]=[28365] to event<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_ivr_originate.c:2069 Parsing global variables<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_event.c:1661 Parsing variable [sip_invite_domain]=[10.20.30.123]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_event.c:1661 Parsing variable [presence_id]=[<a href="mailto:28365@10.20.30.123">28365@10.20.30.123</a>]<br>
&gt; 2014-02-27 15:13:08.666549 [NOTICE] switch_channel.c:1050 New Channel sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [93309eb4-9feb-11e3-9a76-09241ec9370d]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:5230 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State Change CS_NEW -&gt; CS_INIT<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:5300 [zrtp_passthru] Setting a-leg inherit_codec=true<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:5303 [zrtp_passthru] Setting b-leg absolute_codec_string=&#39;PCMU@8000h@20i@64000b,PCMA@8000h@20i@64000b&#39;<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Running State Change CS_INIT<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:455 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State INIT<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:87 sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> SOFIA INIT<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] sofia_glue.c:2744 sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> sending invite version: 1.2.22 git aabf813 2014-02-24 01:22:36Z 64bit<br>
&gt; Local SDP:<br>
&gt; v=0<br>
&gt; o=FreeSWITCH 1393508912 1393508913 IN IP4 10.20.30.123<br>
&gt; s=FreeSWITCH<br>
&gt; c=IN IP4 10.20.30.123<br>
&gt; t=0 0<br>
&gt; m=audio 23076 RTP/AVP 0 8 101 13<br>
&gt; a=rtpmap:101 telephone-event/8000<br>
&gt; a=fmtp:101 0-16<br>
&gt; a=ptime:20<br>
&gt; a=sendrecv<br>
&gt;<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:127 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State Change CS_INIT -&gt; CS_ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:455 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State INIT going to sleep<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Running State Change CS_ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] sofia.c:5811 Channel sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> entering state [calling][0]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:471 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] mod_sofia.c:150 sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> SOFIA ROUTING<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State Change CS_ROUTING -&gt; CS_CONSUME_MEDIA<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.666549 [DEBUG] switch_core_state_machine.c:471 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State ROUTING going to sleep<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Running State Change CS_CONSUME_MEDIA<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:490 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State CONSUME_MEDIA<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:490 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State CONSUME_MEDIA going to sleep<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] sofia.c:5811 Channel sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> entering state [terminated][415]<br>
&gt; 2014-02-27 15:13:08.686500 [NOTICE] sofia.c:6655 Hangup sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_channel.c:3186 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [KILL]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Running State Change CS_HANGUP<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Callstate Change DOWN -&gt; HANGUP<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:680 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State HANGUP<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] mod_sofia.c:506 Channel sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> hanging up, cause: SERVICE_NOT_IMPLEMENTED<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:48 sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:680 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State HANGUP going to sleep<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:447 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State Change CS_HANGUP -&gt; CS_REPORTING<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Running State Change CS_REPORTING<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State REPORTING<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:92 sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State REPORTING going to sleep<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_state_machine.c:441 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State Change CS_REPORTING -&gt; CS_DESTROY<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [BREAK]<br>
&gt; 2014-02-27 15:13:08.686500 [DEBUG] switch_core_session.c:1559 Session 2 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Locked, Waiting on external entities<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_ivr_originate.c:3657 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_channel.c:1967 (sofia/external/23673) Callstate Change RING_WAIT -&gt; ACTIVE<br>
&gt; 2014-02-27 15:13:08.706527 [NOTICE] switch_ivr_originate.c:2670 Cannot create outgoing channel of type [user] cause: [SERVICE_NOT_IMPLEMENTED]<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_ivr_originate.c:3657 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED]<br>
&gt; 2014-02-27 15:13:08.706527 [INFO] mod_dptools.c:3201 Originate Failed.  Cause: SERVICE_NOT_IMPLEMENTED<br>
&gt; EXECUTE sofia/external/23673 answer()<br>
&gt; EXECUTE sofia/external/23673 sleep(1000)<br>
&gt; 2014-02-27 15:13:08.706527 [NOTICE] switch_core_session.c:1577 Session 2 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Ended<br>
&gt; 2014-02-27 15:13:08.706527 [NOTICE] switch_core_session.c:1581 Close Channel sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> [CS_DESTROY]<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_core_state_machine.c:566 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Callstate Change HANGUP -&gt; DOWN<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) Running State Change CS_DESTROY<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State DESTROY<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] mod_sofia.c:399 sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> SOFIA DESTROY<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_core_state_machine.c:99 sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a> Standard DESTROY<br>
&gt; 2014-02-27 15:13:08.706527 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/<a href="mailto:sip%3A28365@10.20.30.121">sip:28365@10.20.30.121</a>) State DESTROY going to sleep<br>
&gt; EXECUTE sofia/external/23673 bridge(loopback/app=voicemail:default 10.20.30.123 28365)<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_channel.c:1763 (sofia/external/23673) Callstate Change ACTIVE -&gt; RING_WAIT<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_channel.c:1196 sofia/external/23673 EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 27 Feb 2014 15:13:05 -0500] to event<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_channel.c:1196 sofia/external/23673 EXPORTING[export_vars] [dialed_extension]=[28365] to event<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_ivr_originate.c:2069 Parsing global variables<br>
&gt; 2014-02-27 15:13:09.706545 [NOTICE] switch_channel.c:1050 New Channel loopback/app=voicemail:default 10.20.30.123 28365-a [93cd50f6-9feb-11e3-9a7f-09241ec9370d]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:157 loopback/app=voicemail:default 10.20.30.123 28365-a setup codec PCMU/8000/20<br>
&gt; 2014-02-27 15:13:09.706545 [NOTICE] switch_channel.c:1048 Rename Channel loopback/app=voicemail:default 10.20.30.123 28365-a-&gt;loopback/voicemail-a [93cd50f6-9feb-11e3-9a7f-09241ec9370d]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:1158 (loopback/voicemail-a) State Change CS_NEW -&gt; CS_INIT<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_INIT<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:455 (loopback/voicemail-a) State INIT<br>
&gt; 2014-02-27 15:13:09.706545 [NOTICE] switch_channel.c:1050 New Channel loopback/voicemail-b [93cd7874-9feb-11e3-9a83-09241ec9370d]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:157 loopback/voicemail-b setup codec PCMU/8000/20<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:272 (loopback/voicemail-b) State Change CS_NEW -&gt; CS_INIT<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-b [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:357 (loopback/voicemail-a) State Change CS_INIT -&gt; CS_ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:455 (loopback/voicemail-a) State INIT going to sleep<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:471 (loopback/voicemail-a) State ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:389 loopback/voicemail-a CHANNEL ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_ivr_originate.c:67 (loopback/voicemail-a) State Change CS_ROUTING -&gt; CS_CONSUME_MEDIA<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:471 (loopback/voicemail-a) State ROUTING going to sleep<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_CONSUME_MEDIA<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:490 (loopback/voicemail-a) State CONSUME_MEDIA<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:648 CHANNEL CONSUME_MEDIA<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:490 (loopback/voicemail-a) State CONSUME_MEDIA going to sleep<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_INIT<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:455 (loopback/voicemail-b) State INIT<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:357 (loopback/voicemail-b) State Change CS_INIT -&gt; CS_ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-b [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:455 (loopback/voicemail-b) State INIT going to sleep<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_channel.c:2163 (loopback/voicemail-b) Callstate Change DOWN -&gt; RINGING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:471 (loopback/voicemail-b) State ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:389 loopback/voicemail-b CHANNEL ROUTING<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:408 (loopback/voicemail-b) State Change CS_ROUTING -&gt; CS_EXECUTE<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-b [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:471 (loopback/voicemail-b) State ROUTING going to sleep<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_EXECUTE<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:478 (loopback/voicemail-b) State EXECUTE<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:431 loopback/voicemail-b CHANNEL EXECUTE<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_state_machine.c:209 loopback/voicemail-b Standard EXECUTE<br>
&gt; EXECUTE loopback/voicemail-b pre_answer()<br>
&gt; 2014-02-27 15:13:09.706545 [NOTICE] mod_loopback.c:945 Pre-Answer loopback/voicemail-a!<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_channel.c:3363 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_channel.c:3367 (loopback/voicemail-a) Callstate Change DOWN -&gt; EARLY<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_core_session.c:871 Send signal loopback/voicemail-b [BREAK]<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.706545 [NOTICE] mod_dptools.c:1260 Pre-Answer loopback/voicemail-b!<br>
&gt; 2014-02-27 15:13:09.706545 [DEBUG] switch_channel.c:3367 (loopback/voicemail-b) Callstate Change RINGING -&gt; EARLY<br>
&gt; EXECUTE loopback/voicemail-b voicemail(default 10.20.30.123 28365)<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_ivr_originate.c:3513 Originate Resulted in Success: [loopback/voicemail-a]<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_channel.c:1967 (sofia/external/23673) Callstate Change RING_WAIT -&gt; ACTIVE<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_core_session.c:871 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_core_session.c:871 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_ivr_bridge.c:1429 (loopback/voicemail-a) State Change CS_CONSUME_MEDIA -&gt; CS_EXCHANGE_MEDIA<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_EXCHANGE_MEDIA<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] switch_core_state_machine.c:481 (loopback/voicemail-a) State EXCHANGE_MEDIA<br>
&gt; 2014-02-27 15:13:09.726502 [DEBUG] mod_loopback.c:610 CHANNEL LOOPBACK<br>
&gt; 2014-02-27 15:13:09.826538 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]<br>
&gt; 2014-02-27 15:13:09.826538 [DEBUG] switch_ivr_play_say.c:251 Handle play-file:[voicemail/vm-person.wav] (en:en)<br>
&gt; 2014-02-27 15:13:09.826538 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms<br>
&gt; 2014-02-27 15:13:11.206539 [DEBUG] switch_ivr_play_say.c:1717 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-person.wav<br>
&gt; 2014-02-27 15:13:11.306570 [DEBUG] switch_ivr_play_say.c:251 Handle say:[28365] (en:en)<br>
&gt; 2014-02-27 15:13:11.306570 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms<br>
&gt; 2014-02-27 15:13:13.866518 [DEBUG] switch_ivr_play_say.c:1717 done playing file file_string://digits/5.wav!digits/7.wav!digits/3.wav!digits/3.wav!digits/1.wav<br>
&gt; 2014-02-27 15:13:13.966519 [DEBUG] switch_ivr_play_say.c:251 Handle play-file:[voicemail/vm-not_available.wav] (en:en)<br>
&gt; 2014-02-27 15:13:13.966519 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms<br>
&gt; 2014-02-27 15:13:14.946549 [DEBUG] switch_ivr_play_say.c:1717 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-not_available.wav<br>
&gt; 2014-02-27 15:13:15.046549 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]<br>
&gt; 2014-02-27 15:13:15.046549 [DEBUG] switch_ivr_play_say.c:251 Handle play-file:[voicemail/vm-record_message.wav] (en:en)<br>
&gt; 2014-02-27 15:13:15.046549 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16@8000hz 1 channels 20ms<br>
&gt; 2014-02-27 15:13:15.646531 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [NOTICE] sofia.c:737 Hangup sofia/external/23673 [CS_EXECUTE] [NORMAL_CLEARING]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_channel.c:3186 Send signal sofia/external/23673 [KILL]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_ivr_bridge.c:633 BRIDGE THREAD DONE [sofia/external/23673]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_ivr_bridge.c:661 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_ivr_bridge.c:557 sofia/external/23673 ending bridge by request from write function<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_ivr_bridge.c:633 BRIDGE THREAD DONE [loopback/voicemail-a]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_ivr_bridge.c:661 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [NOTICE] switch_ivr_bridge.c:722 Hangup loopback/voicemail-a [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_channel.c:3186 Send signal loopback/voicemail-a [KILL]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:481 (loopback/voicemail-a) State EXCHANGE_MEDIA going to sleep<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_HANGUP<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-a) Callstate Change EARLY -&gt; HANGUP<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:680 (loopback/voicemail-a) State HANGUP<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:535 loopback/voicemail-a CHANNEL HANGUP<br>
&gt; 2014-02-27 15:13:15.666534 [NOTICE] mod_loopback.c:551 Hangup loopback/voicemail-b [CS_EXECUTE] [ORIGINATOR_CANCEL]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_channel.c:3186 Send signal loopback/voicemail-b [KILL]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-b [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:48 loopback/voicemail-a Standard HANGUP, cause: ORIGINATOR_CANCEL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:680 (loopback/voicemail-a) State HANGUP going to sleep<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:447 (loopback/voicemail-a) State Change CS_HANGUP -&gt; CS_REPORTING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_REPORTING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:764 (loopback/voicemail-a) State REPORTING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:92 loopback/voicemail-a Standard REPORTING, cause: ORIGINATOR_CANCEL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:764 (loopback/voicemail-a) State REPORTING going to sleep<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:441 (loopback/voicemail-a) State Change CS_REPORTING -&gt; CS_DESTROY<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-a [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_loopback.c:588 loopback/voicemail-a CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1559 Session 3 (loopback/voicemail-a) Locked, Waiting on external entities<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_ivr_bridge.c:1530 sofia/external/23673 skip receive message [UNBRIDGE] (channel is hungup already)<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:2810 sofia/external/23673 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:478 (sofia/external/23673) State EXECUTE going to sleep<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_HANGUP<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:678 (sofia/external/23673) Callstate Change ACTIVE -&gt; HANGUP<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:680 (sofia/external/23673) State HANGUP<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_sofia.c:500 sofia/external/23673 Overriding SIP cause 480 with 415 from the other leg<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_sofia.c:506 Channel sofia/external/23673 hanging up, cause: NORMAL_CLEARING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:48 sofia/external/23673 Standard HANGUP, cause: NORMAL_CLEARING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:680 (sofia/external/23673) State HANGUP going to sleep<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:447 (sofia/external/23673) State Change CS_HANGUP -&gt; CS_REPORTING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:415 (sofia/external/23673) Running State Change CS_REPORTING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:764 (sofia/external/23673) State REPORTING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:92 sofia/external/23673 Standard REPORTING, cause: NORMAL_CLEARING<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:764 (sofia/external/23673) State REPORTING going to sleep<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:441 (sofia/external/23673) State Change CS_REPORTING -&gt; CS_DESTROY<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/23673 [BREAK]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_session.c:1559 Session 1 (sofia/external/23673) Locked, Waiting on external entities<br>
&gt; 2014-02-27 15:13:15.666534 [NOTICE] switch_core_session.c:1577 Session 1 (sofia/external/23673) Ended<br>
&gt; 2014-02-27 15:13:15.666534 [NOTICE] switch_core_session.c:1581 Close Channel sofia/external/23673 [CS_DESTROY]<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:566 (sofia/external/23673) Callstate Change HANGUP -&gt; DOWN<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:569 (sofia/external/23673) Running State Change CS_DESTROY<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:579 (sofia/external/23673) State DESTROY<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] mod_sofia.c:399 sofia/external/23673 SOFIA DESTROY<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:99 sofia/external/23673 Standard DESTROY<br>
&gt; 2014-02-27 15:13:15.666534 [DEBUG] switch_core_state_machine.c:579 (sofia/external/23673) State DESTROY going to sleep<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_ivr_play_say.c:1717 done playing file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-record_message.wav<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_session.c:2810 loopback/voicemail-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:478 (loopback/voicemail-b) State EXECUTE going to sleep<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_HANGUP<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-b) Callstate Change EARLY -&gt; HANGUP<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:680 (loopback/voicemail-b) State HANGUP<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] mod_loopback.c:535 loopback/voicemail-b CHANNEL HANGUP<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:48 loopback/voicemail-b Standard HANGUP, cause: ORIGINATOR_CANCEL<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:680 (loopback/voicemail-b) State HANGUP going to sleep<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:447 (loopback/voicemail-b) State Change CS_HANGUP -&gt; CS_REPORTING<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-b [BREAK]<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_REPORTING<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:764 (loopback/voicemail-b) State REPORTING<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:92 loopback/voicemail-b Standard REPORTING, cause: ORIGINATOR_CANCEL<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:764 (loopback/voicemail-b) State REPORTING going to sleep<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:441 (loopback/voicemail-b) State Change CS_REPORTING -&gt; CS_DESTROY<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_session.c:1351 Send signal loopback/voicemail-b [BREAK]<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] mod_loopback.c:588 loopback/voicemail-b CHANNEL KILL<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_session.c:1559 Session 4 (loopback/voicemail-b) Locked, Waiting on external entities<br>
&gt; 2014-02-27 15:13:15.686490 [NOTICE] switch_core_session.c:1577 Session 4 (loopback/voicemail-b) Ended<br>
&gt; 2014-02-27 15:13:15.686490 [NOTICE] switch_core_session.c:1581 Close Channel loopback/voicemail-b [CS_DESTROY]<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:566 (loopback/voicemail-b) Callstate Change HANGUP -&gt; DOWN<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:569 (loopback/voicemail-b) Running State Change CS_DESTROY<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:579 (loopback/voicemail-b) State DESTROY<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:99 loopback/voicemail-b Standard DESTROY<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:579 (loopback/voicemail-b) State DESTROY going to sleep<br>
&gt; 2014-02-27 15:13:15.686490 [NOTICE] switch_core_session.c:1577 Session 3 (loopback/voicemail-a) Ended<br>
&gt; 2014-02-27 15:13:15.686490 [NOTICE] switch_core_session.c:1581 Close Channel loopback/voicemail-a [CS_DESTROY]<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:566 (loopback/voicemail-a) Callstate Change HANGUP -&gt; DOWN<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:569 (loopback/voicemail-a) Running State Change CS_DESTROY<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:579 (loopback/voicemail-a) State DESTROY<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:99 loopback/voicemail-a Standard DESTROY<br>
&gt; 2014-02-27 15:13:15.686490 [DEBUG] switch_core_state_machine.c:579 (loopback/voicemail-a) State DESTROY going to sleep<br>
&gt;<br>
</div></div><div class="HOEnZb"><div class="h5">&gt; _________________________________________________________________________<br>
&gt; Professional FreeSWITCH Consulting Services:<br>
&gt; <a href="mailto:consulting@freeswitch.org">consulting@freeswitch.org</a><br>
&gt; <a href="http://www.freeswitchsolutions.com" target="_blank">http://www.freeswitchsolutions.com</a><br>
&gt;<br>
&gt; FreeSWITCH-powered IP PBX: The CudaTel Communication Server<br>
&gt; <a href="http://www.cudatel.com" target="_blank">http://www.cudatel.com</a><br>
&gt;<br>
&gt; Official FreeSWITCH Sites<br>
&gt; <a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
&gt; <a href="http://wiki.freeswitch.org" target="_blank">http://wiki.freeswitch.org</a><br>
&gt; <a href="http://www.cluecon.com" target="_blank">http://www.cluecon.com</a><br>
&gt;<br>
&gt; FreeSWITCH-users mailing list<br>
&gt; <a href="mailto:FreeSWITCH-users@lists.freeswitch.org">FreeSWITCH-users@lists.freeswitch.org</a><br>
&gt; <a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
&gt; UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
&gt; <a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
<br>
</div></div><br>_________________________________________________________________________<br>
Professional FreeSWITCH Consulting Services:<br>
<a href="mailto:consulting@freeswitch.org">consulting@freeswitch.org</a><br>
<a href="http://www.freeswitchsolutions.com" target="_blank">http://www.freeswitchsolutions.com</a><br>
<br>
FreeSWITCH-powered IP PBX: The CudaTel Communication Server<br>
<a href="http://www.cudatel.com" target="_blank">http://www.cudatel.com</a><br>
<br>
Official FreeSWITCH Sites<br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
<a href="http://wiki.freeswitch.org" target="_blank">http://wiki.freeswitch.org</a><br>
<a href="http://www.cluecon.com" target="_blank">http://www.cluecon.com</a><br>
<br>
FreeSWITCH-users mailing list<br>
<a href="mailto:FreeSWITCH-users@lists.freeswitch.org">FreeSWITCH-users@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
<br></blockquote></div><br></div>