Hi Brian,<br><br>Here is the full log. Please kindly take a look:<br><br>2008-04-18 08:51:44 [DEBUG] switch_core_session.c:730 switch_core_session_thread() Session 14 (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) Locked, Waiting on external entities<br>
2008-04-18 08:51:44 [NOTICE] switch_core_session.c:748 switch_core_session_thread() Session 14 (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) Ended<br>2008-04-18 08:51:44 [NOTICE] switch_core_session.c:750 switch_core_session_thread() Close Channel sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [CS_HANGUP]<br>
2008-04-18 08:52:16 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_r_refer] status [408][Request Timeout] session: n/a<br>2008-04-18 08:52:44 [NOTICE] switch_channel.c:531 switch_channel_set_name() New Channel sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [c13cf5e4-0ce1-11dd-bee9-2524da607c1a]<br>
2008-04-18 08:52:44 [DEBUG] sofia.c:1565 sofia_handle_sip_i_state() Channel sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> entering state [received]<br>2008-04-18 08:52:44 [DEBUG] sofia.c:1569 sofia_handle_sip_i_state() Remote SDP:<br>
v=0<br>o=1001 1594153517 1594153838 IN IP4 <a href="http://192.168.1.104">192.168.1.104</a><br>s=X-Lite<br>c=IN IP4 <a href="http://192.168.1.104">192.168.1.104</a><br>t=0 0<br>m=audio 8000 RTP/AVP 0 8 3 98 97 101<br>a=rtpmap:0 pcmu/8000<br>
a=rtpmap:8 pcma/8000<br>a=rtpmap:3 gsm/8000<br>a=rtpmap:98 iLBC/8000<br>a=rtpmap:97 speex/8000<br>a=rtpmap:101 telephone-event/8000<br>a=fmtp:101 0-15<br><br>2008-04-18 08:52:44 [DEBUG] sofia_glue.c:2011 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[G722:9:8000]<br>
2008-04-18 08:52:44 [DEBUG] sofia_glue.c:2011 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[PCMU:0:8000]<br>2008-04-18 08:52:44 [DEBUG] sofia_glue.c:1333 sofia_glue_tech_set_codec() Set Codec sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> PCMU/8000 20 ms 160 samples<br>
2008-04-18 08:52:44 [DEBUG] sofia_glue.c:1975 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101<br>2008-04-18 08:52:44 [DEBUG] sofia.c:1706 sofia_handle_sip_i_state() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> State Change CS_NEW -> CS_INIT<br>
2008-04-18 08:52:44 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [BREAK]<br>2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:406 switch_core_session_run() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> Running State Change CS_INIT<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:455 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State INIT<br>2008-04-18 08:52:44 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> SOFIA INIT<br>
2008-04-18 08:52:44 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> State Change CS_INIT -> CS_RING<br>2008-04-18 08:52:44 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [BREAK]<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:455 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State INIT going to sleep<br>2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:406 switch_core_session_run() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> Running State Change CS_RING<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:460 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State RING<br>2008-04-18 08:52:44 [DEBUG] mod_sofia.c:119 sofia_on_ring() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> SOFIA RING<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:65 switch_core_standard_on_ring() Standard RING sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a><br>2008-04-18 08:52:44 [INFO] mod_dialplan_xml.c:223 dialplan_hunt() Processing 1001->1004@public<br>
2008-04-18 08:52:44 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${unroll_loops}(true) =~ /^true$/<br>2008-04-18 08:52:44 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_looped_call}(true) =~ /^true$/<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:96 switch_core_standard_on_ring() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> State Change CS_RING -> CS_EXECUTE<br>2008-04-18 08:52:44 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [BREAK]<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:460 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State RING going to sleep<br>2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:406 switch_core_session_run() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> Running State Change CS_EXECUTE<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:470 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State EXECUTE<br>2008-04-18 08:52:44 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> SOFIA EXECUTE<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:129 switch_core_standard_on_execute() Standard EXECUTE<br>2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:142 switch_core_standard_on_execute() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> Execute deflect(${destination_number})<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:172 switch_core_standard_on_execute() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> Expanded String deflect(1004)<br>2008-04-18 08:52:44 [DEBUG] switch_core_session.c:383 switch_core_session_receive_message() Kill sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [BREAK]<br>
2008-04-18 08:52:44 [NOTICE] switch_core_state_machine.c:198 switch_core_standard_on_execute() Hangup sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [CS_EXECUTE] [NORMAL_CLEARING]<br>2008-04-18 08:52:44 [DEBUG] switch_channel.c:1355 switch_channel_perform_hangup() Kill sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [KILL]<br>
2008-04-18 08:52:44 [DEBUG] switch_core_session.c:670 switch_core_session_signal_state_change() Kill sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [BREAK]<br>2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:470 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State EXECUTE going to sleep<br>
2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:406 switch_core_session_run() sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> Running State Change CS_HANGUP<br>2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:434 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State HANGUP<br>
2008-04-18 08:52:44 [DEBUG] mod_sofia.c:255 sofia_on_hangup() Channel sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> hanging up, cause: NORMAL_CLEARING<br>2008-04-18 08:52:44 [DEBUG] mod_sofia.c:294 sofia_on_hangup() Responding to INVITE with: 480<br>
2008-04-18 08:52:44 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_state] status [480][Temporarily Unavailable] session: sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a><br>2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>, cause: NORMAL_CLEARING2008-04-18 08:52:44 [DEBUG] switch_core_state_machine.c:434 switch_core_session_run() (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) State HANGUP going to sleep<br>
2008-04-18 08:52:44 [DEBUG] sofia.c:227 sofia_event_callback() Channel is already hungup.<br>2008-04-18 08:52:44 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_i_terminated] status [480][Temporarily Unavailable] session: n/a<br>
2008-04-18 08:52:44 [DEBUG] switch_core_session.c:730 switch_core_session_thread() Session 15 (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) Locked, Waiting on external entities<br>2008-04-18 08:52:44 [NOTICE] switch_core_session.c:748 switch_core_session_thread() Session 15 (sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a>) Ended<br>
2008-04-18 08:52:44 [NOTICE] switch_core_session.c:750 switch_core_session_thread() Close Channel sofia/default/<a href="http://1001@192.168.1.104:5061">1001@192.168.1.104:5061</a> [CS_HANGUP]<br>2008-04-18 08:53:16 [DEBUG] sofia.c:219 sofia_event_callback() event [nua_r_refer] status [408][Request Timeout] session: n/a<br>
<br>Thanks for your help.<br><br>Regards,<br>Pete<br><br>