<html><body style="word-wrap: break-word; -webkit-nbsp-mode: space; -webkit-line-break: after-white-space; ">This issue is now fixed in svn trunk:<div><br></div><div><div style="margin-top: 0px; margin-right: 0px; margin-bottom: 0px; margin-left: 0px; font: normal normal normal 12px/normal Helvetica; "><span class="Apple-style-span" style="color: rgb(64, 64, 64); font-family: 'Lucida Grande'; font-size: 10px; "><a href="http://fisheye.freeswitch.org/changelog/FreeSWITCH/?cs=8062" title="http://fisheye.freeswitch.org/changelog/FreeSWITCH/?cs=8062" style="word-wrap: break-word; ">http://fisheye.freeswitch.org/changelog/FreeSWITCH/?cs=8062</a></span></div><div style="margin-top: 0px; margin-right: 0px; margin-bottom: 0px; margin-left: 0px; font: normal normal normal 12px/normal Helvetica; "><font class="Apple-style-span" color="#404040" face="'Lucida Grande'" size="2"><span class="Apple-style-span" style="font-size: 10px;"><br></span></font></div><div style="margin-top: 0px; margin-right: 0px; margin-bottom: 0px; margin-left: 0px; font: normal normal normal 12px/normal Helvetica; "><font class="Apple-style-span" color="#404040" face="'Lucida Grande'" size="2"><span class="Apple-style-span" style="font-size: 10px;">fix hung channels when using respond app with 1xx or 2xx responses or on re-invite in proxy/bypass media with 1xx and 2xx responses</span></font></div><div style="margin-top: 0px; margin-right: 0px; margin-bottom: 0px; margin-left: 0px; font: normal normal normal 12px/normal Helvetica; "><font class="Apple-style-span" color="#404040" face="'Lucida Grande'" size="2"><span class="Apple-style-span" style="font-size: 10px;"><br></span></font></div><div style="margin-top: 0px; margin-right: 0px; margin-bottom: 0px; margin-left: 0px; font: normal normal normal 12px/normal Helvetica; "><font class="Apple-style-span" color="#404040" face="'Lucida Grande'" size="2"><span class="Apple-style-span" style="font-size: 10px;">Mike</span></font></div><div style="margin-top: 0px; margin-right: 0px; margin-bottom: 0px; margin-left: 0px; font: normal normal normal 12px/normal Helvetica; "><font class="Apple-style-span" color="#404040" face="'Lucida Grande'" size="2"><span class="Apple-style-span" style="font-size: 10px;"><br></span></font></div><div style="margin-top: 0px; margin-right: 0px; margin-bottom: 0px; margin-left: 0px; font: normal normal normal 12px/normal Helvetica; "><br></div><div><html>On Apr 8, 2008, at 3:23 PM, kokoska rokoska wrote:</html><br class="Apple-interchange-newline"><blockquote type="cite"><br><br>kokoska rokoska napsal(a):<br><blockquote type="cite">kokoska rokoska napsal(a):<br></blockquote><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite">Anthony Minessale napsal(a):<br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite">That's all fine but the call leg does exist?<br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite">phoneA -- legA ---> FS &lt;--- legB--- phoneB<br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite">if phoneB leaves and FS did not get a bye legA will never end so naturally when you hangup<br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite">phoneA you will get a 200ok on it's leg.<br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite">That is why I asked you for the traces because it's most likely the case that legA is still up<br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite">because legB is as well and only phoneB thinks the call is over.<br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote></blockquote><blockquote type="cite"><blockquote type="cite">I sent the dump few minutes before...<br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite">BTW: Phone B sends BYE, recieves 200 OK and Freeswitch announce hangup on console. But leaves call leg A alive.<br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite">If I don't use "transfer" app, everything works ok.<br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite">Now I'm try to install IRC client and join conference :-)<br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite">Regards,<br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite">kokoska.rokoska<br></blockquote></blockquote><blockquote type="cite"><blockquote type="cite"><br></blockquote></blockquote><blockquote type="cite">My e-mail with dump (400 kB) was rejected. How can I send the dump?<br></blockquote><blockquote type="cite">Regards,<br></blockquote><blockquote type="cite">kokoska.rokoska<br></blockquote><br>Here is console dump.<br><br>regards,<br><br>kokoska.rokoska<br><br><br><br><br><br><br>2008-04-08 20:19:50 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [407][Proxy Authentication Required] session: n/a<br>2008-04-08 20:19:50 [NOTICE] switch_channel.c:531 switch_channel_set_name() New Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [0821700d-67c1-46dc-8d9e-8e2b77f8d9a0]<br>2008-04-08 20:19:50 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 entering state [received]<br>2008-04-08 20:19:50 [DEBUG] sofia.c:1599 sofia_handle_sip_i_state() Remote SDP:<br>v=0<br>o=- 3416667593 3416667593 IN IP4 192.168.1.101<br>s=SJphone<br>c=IN IP4 192.168.1.101<br>t=0 0<br>m=audio 49152 RTP/AVP 8 101<br>c=IN IP4 192.168.1.101<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-16<br>a=setup:active<br><br>2008-04-08 20:19:50 [DEBUG] sofia_glue.c:2011 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000]/[G722:9:8000]<br>2008-04-08 20:19:50 [DEBUG] sofia_glue.c:2011 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000]/[PCMA:8:8000]<br>2008-04-08 20:19:50 [DEBUG] sofia_glue.c:1333 sofia_glue_tech_set_codec() Set Codec <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 PCMA/8000 20 ms 160 samples<br>2008-04-08 20:19:50 [DEBUG] sofia_glue.c:1975 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101<br>2008-04-08 20:19:50 [DEBUG] sofia.c:1736 sofia_handle_sip_i_state() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_NEW -> CS_INIT<br>2008-04-08 20:19:50 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_INIT<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:413 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State INIT<br>2008-04-08 20:19:50 [DEBUG] mod_sofia.c:80 sofia_on_init() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA INIT<br>2008-04-08 20:19:50 [DEBUG] mod_sofia.c:107 sofia_on_init() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_INIT -> CS_RING<br>2008-04-08 20:19:50 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:413 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State INIT going to sleep<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_RING<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING<br>2008-04-08 20:19:50 [DEBUG] mod_sofia.c:119 sofia_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA RING<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:65 switch_core_standard_on_ring() Standard RING <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:50 [INFO] mod_dialplan_xml.c:223 dialplan_hunt() Processing unknown->23@default<br>2008-04-08 20:19:50 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(23) =~ /^.*$/<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:96 switch_core_standard_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_RING -> CS_EXECUTE<br>2008-04-08 20:19:50 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING going to sleep<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_EXECUTE<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE<br>2008-04-08 20:19:50 [DEBUG] mod_sofia.c:156 sofia_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA EXECUTE<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:128 switch_core_standard_on_execute() Standard EXECUTE<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(forward_counter=1)<br>2008-04-08 20:19:50 [DEBUG] mod_dptools.c:493 set_function() SET [forward_counter]=[1]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute export(forward_counter=1)<br>2008-04-08 20:19:50 [DEBUG] mod_dptools.c:626 export_function() EXPORT [forward_counter]=[1]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute respond(181 Call is being forwarded)<br>2008-04-08 20:19:50 [DEBUG] mod_sofia.c:990 sofia_receive_message() Responding with 181 Call is being forwarded<br>2008-04-08 20:19:50 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() sofia/default/0002*23@192.168.1.128:5065 Execute export(sip_h_Diversion=&lt;<a href="sip:24@192.168.1.128">sip:24@192.168.1.128</a>>;reason=uncoditional;counter=1)<br>2008-04-08 20:19:50 [DEBUG] mod_dptools.c:626 export_function() EXPORT [sip_h_Diversion]=[&lt;<a href="sip:24@192.168.1.128">sip:24@192.168.1.128</a>>;reason=uncoditional;counter=1]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute transfer(24)<br>2008-04-08 20:19:50 [DEBUG] switch_ivr.c:1007 switch_ivr_session_transfer() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_EXECUTE -> CS_RING<br>2008-04-08 20:19:50 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:50 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:50 [NOTICE] switch_ivr.c:1013 switch_ivr_session_transfer() Transfer <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 to XML[24@default]<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE going to sleep<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_RING<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING<br>2008-04-08 20:19:50 [DEBUG] mod_sofia.c:119 sofia_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA RING<br>2008-04-08 20:19:50 [DEBUG] switch_core_state_machine.c:65 switch_core_standard_on_ring() Standard RING <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:50 [INFO] mod_dialplan_xml.c:223 dialplan_hunt() Processing unknown->24@default<br>2008-04-08 20:19:50 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [181][Call is being forwarded] session: <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:51 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(24) =~ /^.*$/<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:96 switch_core_standard_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_RING -> CS_EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING going to sleep<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:156 sofia_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:128 switch_core_standard_on_execute() Standard EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(forward_counter=2)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:493 set_function() SET [forward_counter]=[2]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute export(forward_counter=2)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:626 export_function() EXPORT [forward_counter]=[2]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute respond(181 Call is being forwarded)<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:990 sofia_receive_message() Responding with 181 Call is being forwarded<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() sofia/default/0002*23@192.168.1.128:5065 Execute export(sip_h_Diversion=&lt;<a href="sip:22@192.168.1.128">sip:22@192.168.1.128</a>>;reason=uncoditional;counter=2)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:626 export_function() EXPORT [sip_h_Diversion]=[&lt;<a href="sip:22@192.168.1.128">sip:22@192.168.1.128</a>>;reason=uncoditional;counter=2]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute transfer(22)<br>2008-04-08 20:19:51 [DEBUG] switch_ivr.c:1007 switch_ivr_session_transfer() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_EXECUTE -> CS_RING<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [NOTICE] switch_ivr.c:1013 switch_ivr_session_transfer() Transfer <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 to XML[22@default]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE going to sleep<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_RING<br>2008-04-08 20:19:51 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 entering state [early]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:119 sofia_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA RING<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:65 switch_core_standard_on_ring() Standard RING <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:51 [INFO] mod_dialplan_xml.c:223 dialplan_hunt() Processing unknown->22@default<br>2008-04-08 20:19:51 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [181][Call is being forwarded] session: <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:51 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(22) =~ /^.*$/<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:96 switch_core_standard_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_RING -> CS_EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING going to sleep<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:156 sofia_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:128 switch_core_standard_on_execute() Standard EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(forward_counter=3)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:493 set_function() SET [forward_counter]=[3]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute export(forward_counter=3)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:626 export_function() EXPORT [forward_counter]=[3]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute respond(181 Call is being forwarded)<br>2008-04-08 20:19:51 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 entering state [early]<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:990 sofia_receive_message() Responding with 181 Call is being forwarded<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() sofia/default/0002*23@192.168.1.128:5065 Execute export(sip_h_Diversion=&lt;<a href="sip:21@192.168.1.128">sip:21@192.168.1.128</a>>;reason=uncoditional;counter=3)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:626 export_function() EXPORT [sip_h_Diversion]=[&lt;<a href="sip:21@192.168.1.128">sip:21@192.168.1.128</a>>;reason=uncoditional;counter=3]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute transfer(21)<br>2008-04-08 20:19:51 [DEBUG] switch_ivr.c:1007 switch_ivr_session_transfer() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_EXECUTE -> CS_RING<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [NOTICE] switch_ivr.c:1013 switch_ivr_session_transfer() Transfer <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 to XML[21@default]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE going to sleep<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_RING<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:119 sofia_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA RING<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:65 switch_core_standard_on_ring() Standard RING <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:51 [INFO] mod_dialplan_xml.c:223 dialplan_hunt() Processing unknown->21@default<br>2008-04-08 20:19:51 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [181][Call is being forwarded] session: <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:51 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(21) =~ /^.*$/<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:96 switch_core_standard_on_ring() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 State Change CS_RING -> CS_EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State RING going to sleep<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:156 sofia_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 SOFIA EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:128 switch_core_standard_on_execute() Standard EXECUTE<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(dialed_ext=21)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:493 set_function() SET [dialed_ext]=[21]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute export(dialed_ext=21)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:626 export_function() EXPORT [dialed_ext]=[21]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute export(dst_user=0002*21)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:626 export_function() EXPORT [dst_user]=[0002*21]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(call_timeout=10)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:493 set_function() SET [call_timeout]=[10]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(sip_exclude_contact=${network_addr})<br>2008-04-08 20:19:51 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 entering state [early]<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:984 switch_core_session_execute_application() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Expanded String set(sip_exclude_contact=192.168.1.101)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:493 set_function() SET [sip_exclude_contact]=[192.168.1.101]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(hangup_after_bridge=true)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:493 set_function() SET [hangup_after_bridge]=[true]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute set(continue_on_fail=true)<br>2008-04-08 20:19:51 [DEBUG] mod_dptools.c:493 set_function() SET [continue_on_fail]=[true]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:139 switch_core_standard_on_execute() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Execute bridge(sofia/default/${dst_user}%192.168.1.128)<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:984 switch_core_session_execute_application() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Expanded String bridge(sofia/default/0002*21%192.168.1.128)<br>2008-04-08 20:19:51 [NOTICE] switch_channel.c:531 switch_channel_set_name() New Channel sofia/default/0002*21 [0ded99a5-5044-48d2-96ab-66f43af1a03c]<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:1829 sofia_outgoing_channel() sofia/default/0002*21 State Change CS_NEW -> CS_INIT<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/0002*21 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() sofia/default/0002*21 Running State Change CS_INIT<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:413 switch_core_session_run() (sofia/default/0002*21) State INIT<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/default/0002*21 SOFIA INIT<br>2008-04-08 20:19:51 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/default/0002*21<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/default/0002*21 State Change CS_INIT -> CS_RING<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/0002*21 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:413 switch_core_session_run() (sofia/default/0002*21) State INIT going to sleep<br>2008-04-08 20:19:51 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel sofia/default/0002*21 entering state [calling]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() sofia/default/0002*21 Running State Change CS_RING<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (sofia/default/0002*21) State RING<br>2008-04-08 20:19:51 [DEBUG] mod_sofia.c:119 sofia_on_ring() sofia/default/0002*21 SOFIA RING<br>2008-04-08 20:19:51 [DEBUG] switch_ivr_originate.c:55 originate_on_ring() sofia/default/0002*21 State Change CS_RING -> CS_HOLD<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/0002*21 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:418 switch_core_session_run() (sofia/default/0002*21) State RING going to sleep<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() sofia/default/0002*21 Running State Change CS_HOLD<br>2008-04-08 20:19:51 [DEBUG] switch_core_state_machine.c:440 switch_core_session_run() (sofia/default/0002*21) State HOLD<br>2008-04-08 20:19:51 [DEBUG] switch_ivr_originate.c:951 switch_ivr_originate() Raw Codec Activation Success L16@8000hz 1 channel 20ms<br>2008-04-08 20:19:51 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_r_invite] status [180][Ringing] session: sofia/default/0002*21<br>2008-04-08 20:19:51 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: sofia/default/0002*21<br>2008-04-08 20:19:51 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel sofia/default/0002*21 entering state [proceeding]<br>2008-04-08 20:19:51 [NOTICE] sofia.c:1633 sofia_handle_sip_i_state() Ring-Ready sofia/default/0002*21!<br>2008-04-08 20:19:51 [NOTICE] mod_sofia.c:1017 sofia_receive_message() Ring-Ready <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065!<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [NOTICE] switch_ivr_originate.c:1026 switch_ivr_originate() Ring Ready <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065!<br>2008-04-08 20:19:51 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:51 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [180][Ringing] session: <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065<br>2008-04-08 20:19:51 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 entering state [early]<br>2008-04-08 20:19:53 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel sofia/default/0002*21 entering state [ready]<br>2008-04-08 20:19:53 [DEBUG] sofia.c:1599 sofia_handle_sip_i_state() Remote SDP:<br>v=0<br>o=- 18939677 18939677 IN IP4 192.168.1.151<br>s=-<br>c=IN IP4 192.168.1.151<br>t=0 0<br>m=audio 16424 RTP/AVP 8 100 101<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:100 NSE/8000<br>a=fmtp:100 192-193<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-15<br>a=ptime:20<br><br>2008-04-08 20:19:53 [DEBUG] sofia_glue.c:2011 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000]/[PCMA:8:8000]<br>2008-04-08 20:19:53 [DEBUG] sofia_glue.c:1333 sofia_glue_tech_set_codec() Set Codec sofia/default/0002*21 PCMA/8000 20 ms 160 samples<br>2008-04-08 20:19:53 [DEBUG] sofia_glue.c:1975 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101<br>2008-04-08 20:19:53 [DEBUG] sofia_glue.c:1523 sofia_glue_activate_rtp() AUDIO RTP [sofia/default/0002*21] 192.168.1.128:26702->192.168.1.151:16424 codec: 8 ms: 20<br>2008-04-08 20:19:53 [DEBUG] switch_rtp.c:716 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms<br>2008-04-08 20:19:53 [DEBUG] switch_channel.c:1506 switch_channel_perform_mark_answered() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:53 [NOTICE] sofia.c:1945 sofia_handle_sip_i_state() Channel [sofia/default/0002*21] has been answered<br>2008-04-08 20:19:53 [DEBUG] sofia_glue.c:1523 sofia_glue_activate_rtp() AUDIO RTP [<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065] 192.168.1.128:22042->192.168.1.101:49152 codec: 8 ms: 20<br>2008-04-08 20:19:53 [DEBUG] switch_rtp.c:716 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms<br>2008-04-08 20:19:53 [DEBUG] mod_sofia.c:376 sofia_answer_channel() Local SDP <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065:<br>v=0<br>o=FreeSWITCH 1207656751 1207656752 IN IP4 192.168.1.128<br>s=FreeSWITCH<br>c=IN IP4 192.168.1.128<br>t=0 0<br>a=sendrecv<br>m=audio 22042 RTP/AVP 8 101 13<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-16<br>a=rtpmap:13 CN/8000<br>a=ptime:20<br><br>2008-04-08 20:19:53 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:53 [NOTICE] switch_ivr_originate.c:1171 switch_ivr_originate() Channel [<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065] has been answered<br>2008-04-08 20:19:53 [DEBUG] switch_ivr_originate.c:1205 switch_ivr_originate() Originate Resulted in Success: [sofia/default/0002*21]<br>2008-04-08 20:19:53 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill sofia/default/0002*21 [BREAK]<br>2008-04-08 20:19:53 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:19:53 [DEBUG] switch_ivr_bridge.c:756 switch_ivr_multi_threaded_bridge() sofia/default/0002*21 State Change CS_HOLD -> CS_LOOPBACK<br>2008-04-08 20:19:53 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/0002*21 [BREAK]<br>2008-04-08 20:19:53 [DEBUG] switch_core_state_machine.c:440 switch_core_session_run() (sofia/default/0002*21) State HOLD going to sleep<br>2008-04-08 20:19:53 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() sofia/default/0002*21 Running State Change CS_LOOPBACK<br>2008-04-08 20:19:53 [DEBUG] switch_core_state_machine.c:431 switch_core_session_run() (sofia/default/0002*21) State LOOPBACK<br>2008-04-08 20:19:53 [DEBUG] mod_sofia.c:314 sofia_on_loopback() SOFIA LOOPBACK<br>2008-04-08 20:19:53 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 entering state [completed]<br>2008-04-08 20:19:53 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 entering state [ready]<br>2008-04-08 20:20:08 [DEBUG] sofia.c:1595 sofia_handle_sip_i_state() Channel sofia/default/0002*21 entering state [terminated]<br>2008-04-08 20:20:08 [NOTICE] sofia.c:1992 sofia_handle_sip_i_state() Hangup sofia/default/0002*21 [CS_LOOPBACK] [NORMAL_CLEARING]<br>2008-04-08 20:20:08 [DEBUG] switch_channel.c:1355 switch_channel_perform_hangup() Kill sofia/default/0002*21 [KILL]<br>2008-04-08 20:20:08 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/0002*21 [BREAK]<br>2008-04-08 20:20:08 [DEBUG] switch_ivr_bridge.c:275 audio_bridge_thread() read: sofia/default/0002*21 Bad Frame.... Bubye!<br>2008-04-08 20:20:08 [DEBUG] switch_ivr_bridge.c:344 audio_bridge_thread() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:20:08 [DEBUG] switch_ivr_bridge.c:347 audio_bridge_thread() BRIDGE THREAD DONE [sofia/default/0002*21]<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:431 switch_core_session_run() (sofia/default/0002*21) State LOOPBACK going to sleep<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() sofia/default/0002*21 Running State Change CS_HANGUP<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:392 switch_core_session_run() (sofia/default/0002*21) State HANGUP<br>2008-04-08 20:20:08 [DEBUG] mod_sofia.c:255 sofia_on_hangup() Channel sofia/default/0002*21 hanging up, cause: NORMAL_CLEARING<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/default/0002*21, cause: NORMAL_CLEARING<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:392 switch_core_session_run() (sofia/default/0002*21) State HANGUP going to sleep<br>2008-04-08 20:20:08 [DEBUG] switch_core_session.c:730 switch_core_session_thread() Session 2 (sofia/default/0002*21) Locked, Waiting on external entities<br>2008-04-08 20:20:08 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:20:08 [DEBUG] switch_ivr_bridge.c:344 audio_bridge_thread() Kill sofia/default/0002*21 [BREAK]<br>2008-04-08 20:20:08 [DEBUG] switch_ivr_bridge.c:347 audio_bridge_thread() BRIDGE THREAD DONE [<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065]<br>2008-04-08 20:20:08 [NOTICE] switch_ivr_bridge.c:802 switch_ivr_multi_threaded_bridge() Hangup <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [CS_EXECUTE] [NORMAL_CLEARING]<br>2008-04-08 20:20:08 [DEBUG] switch_channel.c:1355 switch_channel_perform_hangup() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [KILL]<br>2008-04-08 20:20:08 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [BREAK]<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:428 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State EXECUTE going to sleep<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:364 switch_core_session_run() <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 Running State Change CS_HANGUP<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:392 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State HANGUP<br>2008-04-08 20:20:08 [DEBUG] mod_sofia.c:255 sofia_on_hangup() Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 hanging up, cause: NORMAL_CLEARING<br>2008-04-08 20:20:08 [NOTICE] switch_core_session.c:748 switch_core_session_thread() Session 2 (sofia/default/0002*21) Ended<br>2008-04-08 20:20:08 [NOTICE] switch_core_session.c:750 switch_core_session_thread() Close Channel sofia/default/0002*21 [CS_HANGUP]<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065, cause: NORMAL_CLEARING<br>2008-04-08 20:20:08 [DEBUG] switch_core_state_machine.c:392 switch_core_session_run() (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) State HANGUP going to sleep<br>2008-04-08 20:20:08 [DEBUG] switch_core_session.c:730 switch_core_session_thread() Session 1 (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) Locked, Waiting on external entities<br>2008-04-08 20:20:08 [NOTICE] switch_core_session.c:748 switch_core_session_thread() Session 1 (<a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065) Ended<br>2008-04-08 20:20:08 [NOTICE] switch_core_session.c:750 switch_core_session_thread() Close Channel <a href="mailto:sofia/default/0002*23@192.168.1.128">sofia/default/0002*23@192.168.1.128</a>:5065 [CS_HANGUP]<br>_______________________________________________<br>Freeswitch-dev mailing list<br><a href="mailto:Freeswitch-dev@lists.freeswitch.org">Freeswitch-dev@lists.freeswitch.org</a><br>http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev<br>UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev<br>http://www.freeswitch.org<br></blockquote></div><br></div></body></html>