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