Here the detail trace for this problem, please notice the highlighted line.<br>Thanks alot for all your help.<br><br>2009-01-19 13:40:30 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/<a href="http://10110@192.168.1.122:7001">10110@192.168.1.122:7001</a> entering state [ready]<br>
recv 787 bytes from udp/[192.168.1.122]:5060 at 18:40:30.915571:<br> &nbsp; ------------------------------------------------------------------------<br> &nbsp; INVITE <a href="http://sip:0010111@192.168.1.116:5070">sip:0010111@192.168.1.116:5070</a> SIP/2.0<br>
 &nbsp; Record-Route: &lt;sip:192.168.1.122;lr=on;ftag=112&gt;<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.122;branch=z9hG4bK855f.d0dc6ad1.0<br> &nbsp; Via: SIP/2.0/UDP <a href="http://192.168.1.103:7001">192.168.1.103:7001</a><br> &nbsp; From: 10111 &lt;<a href="http://sip:10111@192.168.1.122:7001">sip:10111@192.168.1.122:7001</a>&gt;;tag=112<br>
 &nbsp; To: 0010111 &lt;<a href="mailto:sip%3A0010111@192.168.1.122">sip:0010111@192.168.1.122</a>&gt;<br> &nbsp; Call-ID: <a href="mailto:112-26869@192.168.1.103">112-26869@192.168.1.103</a><br> &nbsp; CSeq: 2 INVITE<br> &nbsp; Contact: &lt;<a href="http://sip:10111@192.168.1.103:7001">sip:10111@192.168.1.103:7001</a>&gt;<br>
 &nbsp; Max-Forwards: 69<br> &nbsp; User-Agent: Performance Test<br> &nbsp; Content-Type: application/sdp<br> &nbsp; Content-Length: &nbsp; 276<br> &nbsp; P-hint: inbound-&gt;inbound<br> &nbsp; <br> &nbsp; v=0<br> &nbsp; o=user1 53655765 2353687637 IN IP4 192.168.1.103<br>
 &nbsp; s=-<br> &nbsp; t=0 0<br> &nbsp; c=IN IP4 192.168.1.103<br> &nbsp; m=audio 6444 RTP/AVP 0 9 8 3 101<br> &nbsp; a=rtpmap:0 PCMU/8000<br> &nbsp; a=rtpmap:9 G722/8000<br> &nbsp; a=rtpmap:8 PCMA/8000<br> &nbsp; a=rtpmap:3 GSM/8000<br> &nbsp; a=rtpmap:101 telephone-event/8000<br>
 &nbsp; a=fmtp:101 0-15<br> &nbsp; a=sendrecv<br> &nbsp; ------------------------------------------------------------------------<br>send 364 bytes to udp/[192.168.1.122]:5060 at 18:40:30.916041:<br> &nbsp; ------------------------------------------------------------------------<br>
 &nbsp; SIP/2.0 100 Trying<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.122;branch=z9hG4bK855f.d0dc6ad1.0<br> &nbsp; Via: SIP/2.0/UDP <a href="http://192.168.1.103:7001">192.168.1.103:7001</a><br> &nbsp; Record-Route: &lt;sip:192.168.1.122;lr=on;ftag=112&gt;<br>
 &nbsp; From: 10111 &lt;<a href="http://sip:10111@192.168.1.122:7001">sip:10111@192.168.1.122:7001</a>&gt;;tag=112<br> &nbsp; To: 0010111 &lt;<a href="mailto:sip%3A0010111@192.168.1.122">sip:0010111@192.168.1.122</a>&gt;<br> &nbsp; Call-ID: <a href="mailto:112-26869@192.168.1.103">112-26869@192.168.1.103</a><br>
 &nbsp; CSeq: 2 INVITE<br> &nbsp; User-Agent: FREESWITCH Media Gateway<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>2009-01-19 13:40:30 [DEBUG] sofia.c:3762 sofia_handle_sip_i_invite() IP 192.168.1.122 Approved by acl &quot;lan[]&quot;. Access Granted.<br>
2009-01-19 13:40:30 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [a61933bc-e658-11dd-b62c-5db88ae44bdf]<br>2009-01-19 13:40:30 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> entering state [received]<br>
2009-01-19 13:40:30 [DEBUG] sofia.c:2533 sofia_handle_sip_i_state() Remote SDP:<br>v=0<br>o=user1 53655765 2353687637 IN IP4 192.168.1.103<br>s=-<br>c=IN IP4 192.168.1.103<br>t=0 0<br>m=audio 6444 RTP/AVP 0 9 8 3 101<br>a=rtpmap:0 PCMU/8000<br>
a=rtpmap:9 G722/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:3 GSM/8000<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-15<br> <br>2009-01-19 13:40:30 [DEBUG] sofia_glue.c:2409 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[G722:9:8000]<br>
2009-01-19 13:40:30 [DEBUG] sofia_glue.c:2409 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000]<br>2009-01-19 13:40:30 [DEBUG] sofia_glue.c:1601 sofia_glue_tech_set_codec() Set Codec sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> PCMU/8000 20 ms 160 samples<br>
2009-01-19 13:40:30 [DEBUG] sofia_glue.c:2373 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101<br>2009-01-19 13:40:30 [DEBUG] sofia.c:2685 sofia_handle_sip_i_state() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State Change CS_NEW -&gt; CS_INIT<br>
2009-01-19 13:40:30 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>2009-01-19 13:40:30 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) Running State Change CS_INIT<br>
2009-01-19 13:40:30 [DEBUG] switch_core_state_machine.c:432 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State INIT<br>2009-01-19 13:40:30 [DEBUG] mod_sofia.c:83 sofia_on_init() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SOFIA INIT<br>
2009-01-19 13:40:30 [DEBUG] mod_sofia.c:111 sofia_on_init() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State Change CS_INIT -&gt; CS_ROUTING<br>2009-01-19 13:40:30 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>
2009-01-19 13:40:30 [DEBUG] switch_core_state_machine.c:432 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State INIT going to sleep<br>2009-01-19 13:40:30 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) Running State Change CS_ROUTING<br>
2009-01-19 13:40:30 [DEBUG] switch_core_state_machine.c:435 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State ROUTING<br>2009-01-19 13:40:30 [DEBUG] mod_sofia.c:130 sofia_on_routing() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SOFIA ROUTING<br>
2009-01-19 13:40:30 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Standard ROUTING<br>2009-01-19 13:40:30 [INFO] mod_dialplan_xml.c:233 dialplan_hunt() Processing 10111-&gt;0010111 in context public<br>
2009-01-19 13:40:31 [CONSOLE] mod_xml_curl.c:236 xml_url_fetch() XML response is in /tmp/a619aae0-e658-11dd-b62c-5db88ae44bdf.tmp.xml<br>2009-01-19 13:40:31 [DEBUG] mod_dialplan_xml.c:117 parse_exten() Regex: [test9] destination_number(0010111) =~ /^(.*)$/<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:100 switch_core_standard_on_routing() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State Change CS_ROUTING -&gt; CS_EXECUTE<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:435 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State ROUTING going to sleep<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) Running State Change CS_EXECUTE<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State EXECUTE<br>
2009-01-19 13:40:31 [DEBUG] mod_sofia.c:173 sofia_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SOFIA EXECUTE<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:137 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Standard EXECUTE<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute export(hold_music=silence)<br>2009-01-19 13:40:31 [DEBUG] mod_dptools.c:819 export_function() EXPORT [hold_music]=[silence]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute set(call_timeout=120)<br>2009-01-19 13:40:31 [DEBUG] mod_dptools.c:681 set_function() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SET [call_timeout]=[120]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute set(hangup_after_bridge=true)<br>
2009-01-19 13:40:31 [DEBUG] mod_dptools.c:681 set_function() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SET [hangup_after_bridge]=[true]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute set(continue_on_fail=true)<br>
2009-01-19 13:40:31 [DEBUG] mod_dptools.c:681 set_function() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SET [continue_on_fail]=[true]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute set(language=zh)<br>
2009-01-19 13:40:31 [DEBUG] mod_dptools.c:681 set_function() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SET [language]=[zh]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute set(ringback=%(2000, 4000, 440.0, 480.0))<br>
2009-01-19 13:40:31 [DEBUG] mod_dptools.c:681 set_function() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> SET [ringback]=[%(2000, 4000, 440.0, 480.0)]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute bind_meta_app(1 a s &nbsp;execute_extension::a_record XML features)<br>
2009-01-19 13:40:31 [INFO] switch_ivr_async.c:1570 switch_ivr_bind_dtmf_meta_session() Bound A-Leg: 1 execute_extension::a_record XML features<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute bind_meta_app(2 a s &nbsp;execute_extension::a_stoprecord XML features)<br>
2009-01-19 13:40:31 [INFO] switch_ivr_async.c:1570 switch_ivr_bind_dtmf_meta_session() Bound A-Leg: 2 execute_extension::a_stoprecord XML features<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute bind_meta_app(3 a s &nbsp;execute_extension::a_att_xfer XML features)<br>
2009-01-19 13:40:31 [INFO] switch_ivr_async.c:1570 switch_ivr_bind_dtmf_meta_session() Bound A-Leg: 3 execute_extension::a_att_xfer XML features<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute bind_meta_app(1 b s &nbsp;execute_extension::b_record XML features)<br>
2009-01-19 13:40:31 [INFO] switch_ivr_async.c:1577 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 1 execute_extension::b_record XML features<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute bind_meta_app(2 b s &nbsp;execute_extension::b_stoprecord XML features)<br>
2009-01-19 13:40:31 [INFO] switch_ivr_async.c:1577 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 2 execute_extension::b_stoprecord XML features<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute bind_meta_app(3 b s &nbsp;execute_extension::b_att_xfer XML features)<br>
2009-01-19 13:40:31 [INFO] switch_ivr_async.c:1577 switch_ivr_bind_dtmf_meta_session() Bound B-Leg: 3 execute_extension::b_att_xfer XML features<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Execute bridge(sofia/gateway/openser/10111)<br>
2009-01-19 13:40:31 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel sofia/internal/10111 [a6274970-e658-11dd-b62c-5db88ae44bdf]<br>2009-01-19 13:40:31 [DEBUG] mod_sofia.c:2495 sofia_outgoing_channel() (sofia/internal/10111) State Change CS_NEW -&gt; CS_INIT<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/10111 [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10111) Running State Change CS_INIT<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:432 switch_core_session_run() (sofia/internal/10111) State INIT<br>2009-01-19 13:40:31 [DEBUG] mod_sofia.c:83 sofia_on_init() sofia/internal/10111 SOFIA INIT<br>2009-01-19 13:40:31 [DEBUG] mod_sofia.c:111 sofia_on_init() (sofia/internal/10111) State Change CS_INIT -&gt; CS_ROUTING<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/10111 [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:432 switch_core_session_run() (sofia/internal/10111) State INIT going to sleep<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10111) Running State Change CS_ROUTING<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:435 switch_core_session_run() (sofia/internal/10111) State ROUTING<br>
2009-01-19 13:40:31 [DEBUG] mod_sofia.c:130 sofia_on_routing() sofia/internal/10111 SOFIA ROUTING<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_originate.c:52 originate_on_routing() (sofia/internal/10111) State Change CS_ROUTING -&gt; CS_CONSUME_MEDIA<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/10111 [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:435 switch_core_session_run() (sofia/internal/10111) State ROUTING going to sleep<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10111) Running State Change CS_CONSUME_MEDIA<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:454 switch_core_session_run() (sofia/internal/10111) State CONSUME_MEDIA<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:454 switch_core_session_run() (sofia/internal/10111) State CONSUME_MEDIA going to sleep<br>send 1229 bytes to udp/[192.168.1.122]:5060 at 18:40:31.010618:<br> &nbsp; ------------------------------------------------------------------------<br>
 &nbsp; INVITE <a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a> SIP/2.0<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;rport;branch=z9hG4bKDvK1NF3F0K70m<br> &nbsp; Max-Forwards: 68<br> &nbsp; From: &quot;10111&quot; &lt;sip:FREESWITCHMediaGateway@openser;transport=udp&gt;;tag=3cZNea7Be27cB<br>
 &nbsp; To: &lt;<a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a>&gt;<br> &nbsp; Call-ID: 7d85cf3d-60fb-122c-f480-001517871e28<br> &nbsp; CSeq: 110076879 INVITE<br> &nbsp; Contact: &lt;sip:FREESWITCHMediaGateway@192.168.1.116:5070;transport=udp&gt;<br>
 &nbsp; User-Agent: FREESWITCH Media Gateway<br> &nbsp; Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH<br> &nbsp; Supported: timer, precondition, path, replaces<br> &nbsp; Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer<br>
 &nbsp; Min-SE: 120<br> &nbsp; Content-Type: application/sdp<br> &nbsp; Content-Disposition: session<br> &nbsp; Content-Length: 335<br> &nbsp; P-hint: inbound-&gt;inbound<br> &nbsp; Remote-Party-ID: &quot;10111&quot; &lt;sip:10111@openser&gt;;screen=yes;privacy=off<br>
 &nbsp; <br> &nbsp; v=0<br> &nbsp; o=FreeSWITCH 5124756122584252723 8222947971594082779 IN IP4 192.168.1.116<br> &nbsp; s=FreeSWITCH<br> &nbsp; c=IN IP4 192.168.1.116<br> &nbsp; t=0 0<br> &nbsp; m=audio 12396 RTP/AVP 0 9 8 3 101 13<br> &nbsp; a=rtpmap:0 PCMU/8000<br>
 &nbsp; a=rtpmap:9 G722/8000<br> &nbsp; a=rtpmap:8 PCMA/8000<br> &nbsp; a=rtpmap:3 GSM/8000<br> &nbsp; a=rtpmap:101 telephone-event/8000<br> &nbsp; a=fmtp:101 0-16<br> &nbsp; a=rtpmap:13 CN/8000<br> &nbsp; a=ptime:20<br> &nbsp; ------------------------------------------------------------------------<br>
2009-01-19 13:40:31 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/10111 entering state [calling]<br>recv 354 bytes from udp/[192.168.1.122]:5060 at 18:40:31.031915:<br> &nbsp; ------------------------------------------------------------------------<br>
 &nbsp; SIP/2.0 100 Giving a try<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;rport=5070;branch=z9hG4bKDvK1NF3F0K70m<br> &nbsp; From: &quot;10111&quot; &lt;sip:FREESWITCHMediaGateway@openser;transport=udp&gt;;tag=3cZNea7Be27cB<br> &nbsp; To: &lt;<a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a>&gt;<br>
 &nbsp; Call-ID: 7d85cf3d-60fb-122c-f480-001517871e28<br> &nbsp; CSeq: 110076879 INVITE<br> &nbsp; Server: OpenSIPS (1.4.3-notls (x86_64/linux))<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>
recv 382 bytes from udp/[192.168.1.122]:5060 at 18:40:31.035869:<br> &nbsp; ------------------------------------------------------------------------<br> &nbsp; SIP/2.0 180 Ringing<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;received=192.168.1.116;rport=5070;branch=z9hG4bKDvK1NF3F0K70m<br>
 &nbsp; From: &quot;10111&quot; &lt;sip:FREESWITCHMediaGateway@openser;transport=udp&gt;;tag=3cZNea7Be27cB<br> &nbsp; To: &lt;<a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a>&gt;;tag=117<br> &nbsp; Call-ID: 7d85cf3d-60fb-122c-f480-001517871e28<br>
 &nbsp; CSeq: 110076879 INVITE<br> &nbsp; Contact: &lt;sip:192.168.1.107:7000;transport=UDP&gt;<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>recv 688 bytes from udp/[192.168.1.122]:5060 at 18:40:31.036002:<br>
 &nbsp; ------------------------------------------------------------------------<br> &nbsp; SIP/2.0 200 OK<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;received=192.168.1.116;rport=5070;branch=z9hG4bKDvK1NF3F0K70m<br> &nbsp; From: &quot;10111&quot; &lt;sip:FREESWITCHMediaGateway@openser;transport=udp&gt;;tag=3cZNea7Be27cB<br>
 &nbsp; To: &lt;<a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a>&gt;;tag=117<br> &nbsp; Call-ID: 7d85cf3d-60fb-122c-f480-001517871e28<br> &nbsp; CSeq: 110076879 INVITE<br> &nbsp; Contact: &lt;sip:192.168.1.107:7000;transport=UDP&gt;<br>
 &nbsp; Content-Type: application/sdp<br> &nbsp; Content-Length: &nbsp; 276<br> &nbsp; <br> &nbsp; v=0<br> &nbsp; o=user1 53655765 2353687637 IN IP4 192.168.1.107<br> &nbsp; s=-<br> &nbsp; c=IN IP4 192.168.1.107<br> &nbsp; t=0 0<br> &nbsp; m=audio 6000 RTP/AVP 0 9 8 3 101<br>
 &nbsp; a=rtpmap:0 PCMU/8000<br> &nbsp; a=rtpmap:9 G722/8000<br> &nbsp; a=rtpmap:8 PCMA/8000<br> &nbsp; a=rtpmap:3 GSM/8000<br> &nbsp; a=rtpmap:101 telephone-event/8000<br> &nbsp; a=fmtp:101 0-15<br> &nbsp; a=sendrecv<br>2009-01-19 13:40:31 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/10111 entering state [proceeding]<br>
 &nbsp; ------------------------------------------------------------------------<br>2009-01-19 13:40:31 [NOTICE] sofia.c:2583 sofia_handle_sip_i_state() Ring-Ready sofia/internal/10111!<br>send 414 bytes to udp/[192.168.1.107]:7000 at 18:40:31.036398:<br>
 &nbsp; ------------------------------------------------------------------------<br> &nbsp; ACK sip:192.168.1.107:7000;transport=UDP SIP/2.0<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;rport;branch=z9hG4bKe5ctQamKXvXKg<br> &nbsp; Max-Forwards: 70<br>
 &nbsp; From: &quot;10111&quot; &lt;sip:FREESWITCHMediaGateway@openser;transport=udp&gt;;tag=3cZNea7Be27cB<br> &nbsp; To: &lt;<a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a>&gt;;tag=117<br> &nbsp; Call-ID: 7d85cf3d-60fb-122c-f480-001517871e28<br>
 &nbsp; CSeq: 110076879 ACK<br> &nbsp; Contact: &lt;sip:FREESWITCHMediaGateway@192.168.1.116:5070;transport=udp&gt;<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>2009-01-19 13:40:31 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/10111 entering state [ready]<br>
2009-01-19 13:40:31 [DEBUG] sofia.c:2533 sofia_handle_sip_i_state() Remote SDP:<br>v=0<br>o=user1 53655765 2353687637 IN IP4 192.168.1.107<br>s=-<br>c=IN IP4 192.168.1.107<br>t=0 0<br>m=audio 6000 RTP/AVP 0 9 8 3 101<br>a=rtpmap:0 PCMU/8000<br>
a=rtpmap:9 G722/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:3 GSM/8000<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-15<br> <br>2009-01-19 13:40:31 [DEBUG] sofia_glue.c:2409 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000]<br>
2009-01-19 13:40:31 [DEBUG] sofia_glue.c:1601 sofia_glue_tech_set_codec() Set Codec sofia/internal/10111 PCMU/8000 20 ms 160 samples<br>2009-01-19 13:40:31 [DEBUG] sofia_glue.c:2373 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101<br>
2009-01-19 13:40:31 [DEBUG] sofia_glue.c:1825 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/10111] 192.168.1.116 port 12396 -&gt; 192.168.1.107 port 6000 codec: 0 ms: 20<br>2009-01-19 13:40:31 [DEBUG] switch_rtp.c:859 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms<br>
2009-01-19 13:40:31 [DEBUG] switch_channel.c:1710 switch_channel_perform_mark_answered() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>2009-01-19 13:40:31 [NOTICE] sofia.c:3018 sofia_handle_sip_i_state() Channel [sofia/internal/10111] has been answered<br>
2009-01-19 13:40:31 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/10111 receive message [SWITCH_MESSAGE_INDICATE_AUDIO_SYNC]<br>2009-01-19 13:40:31 [DEBUG] switch_channel.c:1631 switch_channel_perform_pre_answer() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> receive message [SWITCH_MESSAGE_INDICATE_PROGRESS]<br>
2009-01-19 13:40:31 [INFO] mod_sofia.c:1272 sofia_receive_message() Asked to send early media by sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a><br>2009-01-19 13:40:31 [DEBUG] sofia_glue.c:1825 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>] 192.168.1.116 port 12410 -&gt; 192.168.1.103 port 6444 codec: 0 ms: 20<br>
2009-01-19 13:40:31 [DEBUG] switch_rtp.c:859 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms<br>2009-01-19 13:40:31 [INFO] mod_sofia.c:1313 sofia_receive_message() Ring SDP:<br>v=0<br>o=FreeSWITCH 1232378021 1232378022 IN IP4 192.168.1.116<br>
s=FreeSWITCH<br>c=IN IP4 192.168.1.116<br>t=0 0<br>m=audio 12410 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=silenceSupp:off - - - -<br>a=ptime:20<br>a=sendrecv<br> <br>
2009-01-19 13:40:31 [NOTICE] mod_sofia.c:1316 sofia_receive_message() Ring-Ready sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>!<br>2009-01-19 13:40:31 [NOTICE] mod_sofia.c:1316 sofia_receive_message() Pre-Answer sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>!<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:510 switch_core_session_perform_receive_message() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_originate.c:1287 switch_ivr_originate() Raw Codec Activation Success L16@8000hz 1 channel 20ms<br>
2009-01-19 13:40:31 [DEBUG] switch_ivr_originate.c:1345 switch_ivr_originate() Play Ringback Tone [%(2000, 4000, 440.0, 480.0)]<br>send 1094 bytes to udp/[192.168.1.122]:5060 at 18:40:31.121578:<br> &nbsp; ------------------------------------------------------------------------<br>
 &nbsp; SIP/2.0 183 Session Progress<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.122;branch=z9hG4bK855f.d0dc6ad1.0<br> &nbsp; Via: SIP/2.0/UDP <a href="http://192.168.1.103:7001">192.168.1.103:7001</a><br> &nbsp; Record-Route: &lt;sip:192.168.1.122;lr=on;ftag=112&gt;<br>
 &nbsp; From: 10111 &lt;<a href="http://sip:10111@192.168.1.122:7001">sip:10111@192.168.1.122:7001</a>&gt;;tag=112<br> &nbsp; To: 0010111 &lt;<a href="mailto:sip%3A0010111@192.168.1.122">sip:0010111@192.168.1.122</a>&gt;;tag=235vcFp8gSHtF<br>
 &nbsp; Call-ID: <a href="mailto:112-26869@192.168.1.103">112-26869@192.168.1.103</a><br> &nbsp; CSeq: 2 INVITE<br> &nbsp; Contact: &lt;sip:mod_sofia@192.168.1.116:5070;transport=udp&gt;<br> &nbsp; User-Agent: FREESWITCH Media Gateway<br> &nbsp; Accept: application/sdp<br>
 &nbsp; Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH<br> &nbsp; Supported: timer, precondition, path, replaces<br> &nbsp; Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer<br>
 &nbsp; Content-Type: application/sdp<br> &nbsp; Content-Disposition: session<br> &nbsp; Content-Length: 267<br> &nbsp; <br> &nbsp; v=0<br> &nbsp; o=FreeSWITCH 3481863127924572429 7277142438139379053 IN IP4 192.168.1.116<br> &nbsp; s=FreeSWITCH<br> &nbsp; c=IN IP4 192.168.1.116<br>
 &nbsp; t=0 0<br> &nbsp; m=audio 12410 RTP/AVP 0 101<br> &nbsp; a=rtpmap:0 PCMU/8000<br> &nbsp; a=rtpmap:101 telephone-event/8000<br> &nbsp; a=fmtp:101 0-16<br> &nbsp; a=silenceSupp:off - - - -<br> &nbsp; a=ptime:20<br> &nbsp; ------------------------------------------------------------------------<br>
2009-01-19 13:40:31 [DEBUG] switch_channel.c:1768 switch_channel_perform_answer() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> receive message [SWITCH_MESSAGE_INDICATE_ANSWER]<br>2009-01-19 13:40:31 [DEBUG] mod_sofia.c:503 sofia_answer_channel() Local SDP sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>:<br>
v=0<br>o=FreeSWITCH 1232378021 1232378023 IN IP4 192.168.1.116<br>s=FreeSWITCH<br>c=IN IP4 192.168.1.116<br>t=0 0<br>m=audio 12410 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=silenceSupp:off - - - -<br>a=ptime:20<br>a=sendrecv<br> <br>2009-01-19 13:40:31 [DEBUG] switch_core_session.c:510 switch_core_session_perform_receive_message() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>
2009-01-19 13:40:31 [NOTICE] switch_ivr_originate.c:1581 switch_ivr_originate() Channel [sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>] has been answered<br>2009-01-19 13:40:31 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> receive message [SWITCH_MESSAGE_INDICATE_AUDIO_SYNC]<br>
2009-01-19 13:40:31 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> entering state [early]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_originate.c:1621 switch_ivr_originate() Originate Resulted in Success: [sofia/internal/10111]<br>
2009-01-19 13:40:31 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> entering state [completed]<br>2009-01-19 13:40:31 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/10111 receive message [SWITCH_MESSAGE_INDICATE_AUDIO_SYNC]<br>
2009-01-19 13:40:31 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> receive message [SWITCH_MESSAGE_INDICATE_AUDIO_SYNC]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:862 switch_ivr_multi_threaded_bridge() sofia/internal/10111 receive message [SWITCH_MESSAGE_INDICATE_BRIDGE]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:510 switch_core_session_perform_receive_message() Send signal sofia/internal/10111 [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:869 switch_ivr_multi_threaded_bridge() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> receive message [SWITCH_MESSAGE_INDICATE_BRIDGE]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:510 switch_core_session_perform_receive_message() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:913 switch_ivr_multi_threaded_bridge() (sofia/internal/10111) State Change CS_CONSUME_MEDIA -&gt; CS_EXCHANGE_MEDIA<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/10111 [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10111) Running State Change CS_EXCHANGE_MEDIA<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:445 switch_core_session_run() (sofia/internal/10111) State EXCHANGE_MEDIA<br>2009-01-19 13:40:31 [DEBUG] mod_sofia.c:404 sofia_on_exchange_media() SOFIA LOOPBACK<br>
2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:293 audio_bridge_thread() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> receive message [SWITCH_MESSAGE_INDICATE_RINGING]<br>2009-01-19 13:40:31 [DEBUG] switch_core_session.c:510 switch_core_session_perform_receive_message() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>
send 658 bytes to udp/[192.168.1.122]:5060 at 18:40:31.154213:<br> &nbsp; ------------------------------------------------------------------------<br> &nbsp; BYE <a href="http://sip:10111@192.168.1.103:7001">sip:10111@192.168.1.103:7001</a> SIP/2.0<br>
 &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;rport;branch=z9hG4bKFe6jS54pt5K6B<br> &nbsp; Route: &lt;sip:192.168.1.122;lr=on;ftag=112&gt;<br> &nbsp; Max-Forwards: 70<br> &nbsp; From: 0010111 &lt;<a href="mailto:sip%3A0010111@192.168.1.122">sip:0010111@192.168.1.122</a>&gt;;tag=235vcFp8gSHtF<br>
 &nbsp; To: 10111 &lt;<a href="http://sip:10111@192.168.1.122:7001">sip:10111@192.168.1.122:7001</a>&gt;;tag=112<br> &nbsp; Call-ID: <a href="mailto:112-26869@192.168.1.103">112-26869@192.168.1.103</a><br> &nbsp; CSeq: 110076879 BYE<br>
 &nbsp; Contact: &lt;sip:mod_sofia@192.168.1.116:5070;transport=udp&gt;<br> &nbsp; User-Agent: FREESWITCH Media Gateway<br> &nbsp; Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH<br>
 &nbsp; Supported: timer, precondition, path, replaces<br> &nbsp; Reason: SIP;cause=408;text=&quot;ACK Timeout&quot;<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>2009-01-19 13:40:31 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> entering state [terminating]<br>
send 316 bytes to udp/[192.168.1.122]:5060 at 18:40:31.154944:<br> &nbsp; ------------------------------------------------------------------------<br> <span style="color: rgb(255, 0, 0);">&nbsp; SIP/2.0 500 Internal Server Error</span><br style="color: rgb(255, 0, 0);">
<span style="color: rgb(255, 0, 0);"> &nbsp; Via: SIP/2.0/UDP 192.168.1.122;branch=z9hG4bK855f.d0dc6ad1.0</span><br style="color: rgb(255, 0, 0);"><span style="color: rgb(255, 0, 0);"> &nbsp; Via: SIP/2.0/UDP <a href="http://192.168.1.103:7001">192.168.1.103:7001</a></span><br style="color: rgb(255, 0, 0);">
<span style="color: rgb(255, 0, 0);"> &nbsp; From: 10111 &lt;<a href="http://sip:10111@192.168.1.122:7001">sip:10111@192.168.1.122:7001</a>&gt;;tag=112</span><br style="color: rgb(255, 0, 0);"><span style="color: rgb(255, 0, 0);"> &nbsp; To: 0010111 &lt;<a href="mailto:sip%3A0010111@192.168.1.122">sip:0010111@192.168.1.122</a>&gt;;tag=235vcFp8gSHtF</span><br style="color: rgb(255, 0, 0);">
<span style="color: rgb(255, 0, 0);"> &nbsp; Call-ID: <a href="mailto:112-26869@192.168.1.103">112-26869@192.168.1.103</a></span><br style="color: rgb(255, 0, 0);"><span style="color: rgb(255, 0, 0);"> &nbsp; CSeq: 2 INVITE</span><br style="color: rgb(255, 0, 0);">
<span style="color: rgb(255, 0, 0);"> &nbsp; Content-Length: 0</span><br style="color: rgb(255, 0, 0);"><span style="color: rgb(255, 0, 0);"> &nbsp; </span><br style="color: rgb(255, 0, 0);"><span style="color: rgb(255, 0, 0);"> &nbsp; ---------------------------</span>---------------------------------------------<br>
2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:360 audio_bridge_thread() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> ending bridge by request from read function<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:409 audio_bridge_thread() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> receive message [SWITCH_MESSAGE_INDICATE_UNBRIDGE]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:510 switch_core_session_perform_receive_message() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread() BRIDGE THREAD DONE [sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>]<br>
2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread() Send signal sofia/internal/10111 [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:409 audio_bridge_thread() sofia/internal/10111 receive message [SWITCH_MESSAGE_INDICATE_UNBRIDGE]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_session.c:510 switch_core_session_perform_receive_message() Send signal sofia/internal/10111 [BREAK]<br>2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:435 audio_bridge_thread() BRIDGE THREAD DONE [sofia/internal/10111]<br>
2009-01-19 13:40:31 [DEBUG] switch_ivr_bridge.c:439 audio_bridge_thread() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>2009-01-19 13:40:31 [NOTICE] switch_ivr_bridge.c:470 audio_bridge_on_exchange_media() Hangup sofia/internal/10111 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]<br>
2009-01-19 13:40:31 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal sofia/internal/10111 [KILL]<br>2009-01-19 13:40:31 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/10111 [BREAK]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:445 switch_core_session_run() (sofia/internal/10111) State EXCHANGE_MEDIA going to sleep<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/10111) Running State Change CS_HANGUP<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:400 switch_core_session_run() (sofia/internal/10111) State HANGUP<br>2009-01-19 13:40:31 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel sofia/internal/10111 hanging up, cause: NORMAL_CLEARING<br>
2009-01-19 13:40:31 [DEBUG] mod_sofia.c:344 sofia_on_hangup() Sending BYE to sofia/internal/10111<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() sofia/internal/10111 Standard HANGUP, cause: NORMAL_CLEARING<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:400 switch_core_session_run() (sofia/internal/10111) State HANGUP going to sleep<br>2009-01-19 13:40:31 [DEBUG] switch_core_session.c:938 switch_core_session_thread() Session 805 (sofia/internal/10111) Locked, Waiting on external entities<br>
recv 351 bytes from udp/[192.168.1.122]:5060 at 18:40:31.177548:<br> &nbsp; ------------------------------------------------------------------------<br> &nbsp; SIP/2.0 200 OK<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;received=192.168.1.116;rport=5070;branch=z9hG4bKFe6jS54pt5K6B<br>
 &nbsp; From: 0010111 &lt;<a href="mailto:sip%3A0010111@192.168.1.122">sip:0010111@192.168.1.122</a>&gt;;tag=235vcFp8gSHtF<br> &nbsp; To: 10111 &lt;<a href="http://sip:10111@192.168.1.122:7001">sip:10111@192.168.1.122:7001</a>&gt;;tag=112<br>
 &nbsp; Call-ID: <a href="mailto:112-26869@192.168.1.103">112-26869@192.168.1.103</a><br> &nbsp; CSeq: 110076879 BYE<br> &nbsp; Contact: &lt;sip:192.168.1.103:7001;transport=UDP&gt;<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>
2009-01-19 13:40:31 [DEBUG] sofia.c:2529 sofia_handle_sip_i_state() Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> entering state [terminated]<br>send 657 bytes to udp/[192.168.1.107]:7000 at 18:40:31.178007:<br>
 &nbsp; ------------------------------------------------------------------------<br> &nbsp; BYE sip:192.168.1.107:7000;transport=UDP SIP/2.0<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;rport;branch=z9hG4bKgQZBU0NtQeaSQ<br> &nbsp; Max-Forwards: 70<br>
 &nbsp; From: &quot;10111&quot; &lt;sip:FREESWITCHMediaGateway@openser;transport=udp&gt;;tag=3cZNea7Be27cB<br> &nbsp; To: &lt;<a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a>&gt;;tag=117<br> &nbsp; Call-ID: 7d85cf3d-60fb-122c-f480-001517871e28<br>
 &nbsp; CSeq: 110076880 BYE<br> &nbsp; Contact: &lt;sip:FREESWITCHMediaGateway@192.168.1.116:5070;transport=udp&gt;<br> &nbsp; User-Agent: FREESWITCH Media Gateway<br> &nbsp; Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH<br>
 &nbsp; Supported: timer, precondition, path, replaces<br> &nbsp; Reason: Q.850;cause=16;text=&quot;NORMAL_CLEARING&quot;<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>
recv 354 bytes from udp/[192.168.1.122]:5060 at 18:40:31.178123:<br> &nbsp; ------------------------------------------------------------------------<br> &nbsp; ACK <a href="http://sip:0010111@192.168.1.116:5070">sip:0010111@192.168.1.116:5070</a> SIP/2.0<br>
 &nbsp; Via: SIP/2.0/UDP 192.168.1.122;branch=z9hG4bK855f.d0dc6ad1.0<br> &nbsp; From: 10111 &lt;<a href="http://sip:10111@192.168.1.122:7001">sip:10111@192.168.1.122:7001</a>&gt;;tag=112<br> &nbsp; Call-ID: <a href="mailto:112-26869@192.168.1.103">112-26869@192.168.1.103</a><br>
 &nbsp; To: 0010111 &lt;<a href="mailto:sip%3A0010111@192.168.1.122">sip:0010111@192.168.1.122</a>&gt;;tag=235vcFp8gSHtF<br> &nbsp; CSeq: 2 ACK<br> &nbsp; Max-Forwards: 70<br> &nbsp; User-Agent: OpenSIPS (1.4.3-notls (x86_64/linux))<br> &nbsp; Content-Length: 0<br>
 &nbsp; <br> &nbsp; ------------------------------------------------------------------------<br>2009-01-19 13:40:31 [NOTICE] switch_ivr_bridge.c:954 switch_ivr_multi_threaded_bridge() Hangup sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [CS_EXECUTE] [NORMAL_CLEARING]<br>
2009-01-19 13:40:31 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [KILL]<br>2009-01-19 13:40:31 [DEBUG] switch_core_session.c:806 switch_core_session_signal_state_change() Send signal sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [BREAK]<br>
2009-01-19 13:40:31 [NOTICE] switch_core_session.c:956 switch_core_session_thread() Session 805 (sofia/internal/10111) Ended<br>2009-01-19 13:40:31 [NOTICE] switch_core_session.c:958 switch_core_session_thread() Close Channel sofia/internal/10111 [CS_HANGUP]<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State EXECUTE going to sleep<br>2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:369 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) Running State Change CS_HANGUP<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:400 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State HANGUP<br>2009-01-19 13:40:31 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> hanging up, cause: NORMAL_CLEARING<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> Standard HANGUP, cause: NORMAL_CLEARING<br>
2009-01-19 13:40:31 [DEBUG] switch_core_state_machine.c:400 switch_core_session_run() (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) State HANGUP going to sleep<br>2009-01-19 13:40:31 [DEBUG] switch_core_session.c:938 switch_core_session_thread() Session 804 (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) Locked, Waiting on external entities<br>
2009-01-19 13:40:31 [NOTICE] switch_core_session.c:956 switch_core_session_thread() Session 804 (sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a>) Ended<br>2009-01-19 13:40:31 [NOTICE] switch_core_session.c:958 switch_core_session_thread() Close Channel sofia/internal/<a href="http://10111@192.168.1.122:7001">10111@192.168.1.122:7001</a> [CS_HANGUP]<br>
send 657 bytes to udp/[192.168.1.107]:7000 at 18:40:31.677674:<br> &nbsp; ------------------------------------------------------------------------<br> &nbsp; BYE sip:192.168.1.107:7000;transport=UDP SIP/2.0<br> &nbsp; Via: SIP/2.0/UDP 192.168.1.116:5070;rport;branch=z9hG4bKgQZBU0NtQeaSQ<br>
 &nbsp; Max-Forwards: 70<br> &nbsp; From: &quot;10111&quot; &lt;sip:FREESWITCHMediaGateway@openser;transport=udp&gt;;tag=3cZNea7Be27cB<br> &nbsp; To: &lt;<a href="http://sip:10111@192.168.1.122:5060">sip:10111@192.168.1.122:5060</a>&gt;;tag=117<br>
 &nbsp; Call-ID: 7d85cf3d-60fb-122c-f480-001517871e28<br> &nbsp; CSeq: 110076880 BYE<br> &nbsp; Contact: &lt;sip:FREESWITCHMediaGateway@192.168.1.116:5070;transport=udp&gt;<br> &nbsp; User-Agent: FREESWITCH Media Gateway<br> &nbsp; Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH<br>
 &nbsp; Supported: timer, precondition, path, replaces<br> &nbsp; Reason: Q.850;cause=16;text=&quot;NORMAL_CLEARING&quot;<br> &nbsp; Content-Length: 0<br> &nbsp; <br> &nbsp; ------------------------------------------------------------------------