[Freeswitch-users] Problem with first use.

Fri Oct 10 10:41:39 PDT 2008

Here is the debug info after press F8:

2008-10-10 13:33:00 [DEBUG] sofia.c:3134 sofia_handle_sip_i_invite() IP 10.1.1.129 Rejected by acl domains. Falling back to Digest auth.
2008-10-10 13:33:00 [DEBUG] sofia.c:3134 sofia_handle_sip_i_invite() IP 10.1.1.129 Rejected by acl domains. Falling back to Digest auth.
2008-10-10 13:33:00 [NOTICE] switch_channel.c:534 switch_channel_set_name() New Channel sofia/internal/1007 at 10.1.1.177 [41067ebd-0e4a-46e2-942c-f2a953819333]
2008-10-10 13:33:00 [DEBUG] sofia.c:3624 sofia_handle_sip_i_invite() Setting NAT mode based on via port
2008-10-10 13:33:00 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/internal/1007 at 10.1.1.177 entering state [received]
2008-10-10 13:33:00 [DEBUG] sofia.c:2133 sofia_handle_sip_i_state() Remote SDP:
v=0
o=Cisco-SIPUA 11819 0 IN IP4 10.1.1.129
s=SIP Call
t=0 0
m=audio 23142 RTP/AVP 0 8 18 101
c=IN IP4 10.1.1.129
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2008-10-10 13:33:00 [DEBUG] sofia_glue.c:2280 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[G722:9:8000]
2008-10-10 13:33:00 [DEBUG] sofia_glue.c:2280 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMU:0:8000]/[PCMU:0:8000]
2008-10-10 13:33:00 [DEBUG] sofia_glue.c:1530 sofia_glue_tech_set_codec() Set Codec sofia/internal/1007 at 10.1.1.177 PCMU/8000 20 ms 160 samples
2008-10-10 13:33:00 [DEBUG] sofia_glue.c:2243 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101
2008-10-10 13:33:00 [DEBUG] sofia.c:2270 sofia_handle_sip_i_state() sofia/internal/1007 at 10.1.1.177 State Change CS_NEW -> CS_INIT
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/1007 at 10.1.1.177 [BREAK]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/1007 at 10.1.1.177 Running State Change CS_INIT
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/1007 at 10.1.1.177) State INIT
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/1007 at 10.1.1.177 SOFIA INIT
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/1007 at 10.1.1.177 State Change CS_INIT -> CS_ROUTING
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/1007 at 10.1.1.177 [BREAK]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/1007 at 10.1.1.177) State INIT going to sleep
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/1007 at 10.1.1.177 Running State Change CS_ROUTING
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/1007 at 10.1.1.177) State ROUTING
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/1007 at 10.1.1.177 SOFIA ROUTING
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() Standard ROUTING sofia/internal/1007 at 10.1.1.177
2008-10-10 13:33:00 [INFO] mod_dialplan_xml.c:222 dialplan_hunt() Processing Line1->1001 at default
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${unroll_loops}(true) =~ /^true$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_looped_call}() =~ /^true$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${strftime(%H%M)}(1333) =~ /^((09|1[0-7])[0-5][0-9]|1800)$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^886$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^\*\*(\d+)$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^870$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${network_addr}(10.1.1.129) =~ /^$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${numbering_plan}() =~ /^$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${call_debug}(false) =~ /^true$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^9001$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^9000$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^88(.*)$|^\*0(.*)$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^779$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^\*69$|^869$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^80(\d{2})$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^81(\d{2})$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^82(\d{2})$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^83(\d{2})$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^8(10[01][0-9])$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^(10[01][0-9])$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:107 parse_exten() test conditions destination_number(1001) =~ /^1007$/
2008-10-10 13:33:00 [DEBUG] mod_dialplan_xml.c:109 parse_exten() Regex mismatch
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:97 switch_core_standard_on_routing() sofia/internal/1007 at 10.1.1.177 State Change CS_ROUTING -> CS_EXECUTE
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/1007 at 10.1.1.177 [BREAK]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/1007 at 10.1.1.177) State ROUTING going to sleep
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/1007 at 10.1.1.177 Running State Change CS_EXECUTE
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:430 switch_core_session_run() (sofia/internal/1007 at 10.1.1.177) State EXECUTE
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/internal/1007 at 10.1.1.177 SOFIA EXECUTE
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:129 switch_core_standard_on_execute() Standard EXECUTE
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set(open=true)
2008-10-10 13:33:00 [DEBUG] mod_dptools.c:678 set_function() SET [open]=[true]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set(use_profile=${cond(${acl(${network_addr} rfc1918)} == true ? nat : default)})
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String set(use_profile=default)
2008-10-10 13:33:00 [DEBUG] mod_dptools.c:678 set_function() SET [use_profile]=[default]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set_user(default@${domain})
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String set_user(default at 10.1.1.177)
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute db(insert/spymap/${caller_id_number}/${uuid})
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String db(insert/spymap/1007/41067ebd-0e4a-46e2-942c-f2a953819333)
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute db(insert/last_dial/${caller_id_number}/${destination_number})
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String db(insert/last_dial/1007/1001)
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute db(insert/last_dial/global/${uuid})
2008-10-10 13:33:01 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String db(insert/last_dial/global/41067ebd-0e4a-46e2-942c-f2a953819333)
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set(dialed_ext=1001)
2008-10-10 13:33:01 [DEBUG] mod_dptools.c:678 set_function() SET [dialed_ext]=[1001]
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute export(dialed_ext=1001)
2008-10-10 13:33:01 [DEBUG] mod_dptools.c:816 export_function() EXPORT [dialed_ext]=[1001]
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set(transfer_ringback=${us-ring})
2008-10-10 13:33:01 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String set(transfer_ringback=%(2000, 4000, 440.0, 480.0))
2008-10-10 13:33:01 [DEBUG] mod_dptools.c:678 set_function() SET [transfer_ringback]=[%(2000, 4000, 440.0, 480.0)]
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set(call_timeout=30)
2008-10-10 13:33:01 [DEBUG] mod_dptools.c:678 set_function() SET [call_timeout]=[30]
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set(hangup_after_bridge=true)
2008-10-10 13:33:01 [DEBUG] mod_dptools.c:678 set_function() SET [hangup_after_bridge]=[true]
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute set(continue_on_fail=true)
2008-10-10 13:33:01 [DEBUG] mod_dptools.c:678 set_function() SET [continue_on_fail]=[true]
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute db(insert/call_return/${dialed_ext}/${caller_id_number})
2008-10-10 13:33:01 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String db(insert/call_return/1001/1007)
2008-10-10 13:33:01 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute db(insert/last_dial_ext/${dialed_ext}/${uuid})
2008-10-10 13:33:01 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String db(insert/last_dial_ext/1001/41067ebd-0e4a-46e2-942c-f2a953819333)
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute bridge(user/${dialed_ext}@10.1.1.177)
2008-10-10 13:33:02 [DEBUG] switch_core_session.c:1045 switch_core_session_execute_application() sofia/internal/1007 at 10.1.1.177 Expanded String bridge(user/1001 at 10.1.1.177)
2008-10-10 13:33:02 [DEBUG] switch_ivr_originate.c:652 switch_ivr_originate() variable string 0 = [presence_id=1001 at 10.1.1.177]
2008-10-10 13:33:02 [DEBUG] switch_ivr_originate.c:652 switch_ivr_originate() variable string 1 = [transfer_fallback_extension=1001]
2008-10-10 13:33:02 [NOTICE] switch_channel.c:534 switch_channel_set_name() New Channel sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [1cd3e927-4dd5-413c-98f0-40312774ee02]
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:2001 sofia_outgoing_channel() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes State Change CS_NEW -> CS_INIT
2008-10-10 13:33:02 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [BREAK]
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes Running State Change CS_INIT
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State INIT
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes SOFIA INIT
2008-10-10 13:33:02 [DEBUG] sofia.c:197 sofia_event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes State Change CS_INIT -> CS_ROUTING
2008-10-10 13:33:02 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [BREAK]
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:415 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State INIT going to sleep
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes Running State Change CS_ROUTING
2008-10-10 13:33:02 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes entering state [calling]
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State ROUTING
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes SOFIA ROUTING
2008-10-10 13:33:02 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes State Change CS_ROUTING -> CS_CONSUME_MEDIA
2008-10-10 13:33:02 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [BREAK]
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:420 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State ROUTING going to sleep
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes Running State Change CS_CONSUME_MEDIA
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State CONSUME_MEDIA
2008-10-10 13:33:02 [DEBUG] sofia.c:197 sofia_event_callback() event [nua_r_invite] status [503][Service Unavailable] session: sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes
2008-10-10 13:33:02 [DEBUG] sofia.c:197 sofia_event_callback() event [nua_i_state] status [503][Service Unavailable] session: sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes
2008-10-10 13:33:02 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes entering state [terminated]
2008-10-10 13:33:02 [NOTICE] sofia.c:2545 sofia_handle_sip_i_state() Hangup sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
2008-10-10 13:33:02 [DEBUG] switch_channel.c:1361 switch_channel_perform_hangup() Kill sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [KILL]
2008-10-10 13:33:02 [DEBUG] switch_core_session.c:722 switch_core_session_signal_state_change() Kill sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [BREAK]
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:442 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State CONSUME_MEDIA going to sleep
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:365 switch_core_session_run() sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes Running State Change CS_HANGUP
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State HANGUP
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:264 sofia_on_hangup() Channel sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes hanging up, cause: NORMAL_TEMPORARY_FAILURE
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes, cause: NORMAL_TEMPORARY_FAILURE
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:393 switch_core_session_run() (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) State HANGUP going to sleep
2008-10-10 13:33:02 [DEBUG] switch_core_session.c:789 switch_core_session_thread() Session 11 (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) Locked, Waiting on external entities
2008-10-10 13:33:02 [DEBUG] switch_ivr_originate.c:1404 switch_ivr_originate() Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2008-10-10 13:33:02 [NOTICE] switch_core_session.c:807 switch_core_session_thread() Session 11 (sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes) Ended
2008-10-10 13:33:02 [NOTICE] switch_core_session.c:809 switch_core_session_thread() Close Channel sofia/internal/1001 at 10.1.1.117:51054;transport=udp;fs_nat=yes [CS_HANGUP]
2008-10-10 13:33:02 [ERR] switch_ivr_originate.c:926 switch_ivr_originate() Cannot create outgoing channel of type [user] cause: [NORMAL_TEMPORARY_FAILURE]
2008-10-10 13:33:02 [DEBUG] switch_ivr_originate.c:1404 switch_ivr_originate() Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2008-10-10 13:33:02 [INFO] mod_dptools.c:1789 audio_bridge_function() Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
2008-10-10 13:33:02 [DEBUG] mod_dptools.c:1810 audio_bridge_function() Continue on fail [true]:  Cause: NORMAL_TEMPORARY_FAILURE
2008-10-10 13:33:02 [DEBUG] switch_core_state_machine.c:140 switch_core_standard_on_execute() sofia/internal/1007 at 10.1.1.177 Execute answer()
2008-10-10 13:33:02 [DEBUG] sofia_glue.c:1739 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/1007 at 10.1.1.177] 10.1.1.177 port 17974 -> 10.1.1.129 port 23142 codec: 0 ms: 20
2008-10-10 13:33:02 [DEBUG] switch_rtp.c:813 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:430 sofia_answer_channel() Local SDP sofia/internal/1007 at 10.1.1.177:
v=0

Gary
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20081010/586e1b19/attachment-0002.html 