<!--<param name="apply-nat-acl" value="rfc1918"/>--> <!--<param name="aggressive-nat-detection" value="true"/>--> Comment out those two lines in internal.xml, clear your sofia_* from the db directory in /usr/local/freeswitch/db Restart FS and try again. /b On Oct 10, 2008, at 12:41 PM, gary wrote:
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@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@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@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@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@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@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@10.1.1.177) State INIT 2008-10-10 13:33:00 [DEBUG] mod_sofia.c:80 sofia_on_init() sofia/internal/1007@10.1.1.177 SOFIA INIT 2008-10-10 13:33:00 [DEBUG] mod_sofia.c:107 sofia_on_init() sofia/internal/1007@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@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@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@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@10.1.1.177) State ROUTING 2008-10-10 13:33:00 [DEBUG] mod_sofia.c:119 sofia_on_routing() sofia/internal/1007@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@10.1.1.177 2008-10-10 13:33:00 [INFO] mod_dialplan_xml.c:222 dialplan_hunt() Processing Line1->1001@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@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@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@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@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@10.1.1.177) State EXECUTE 2008-10-10 13:33:00 [DEBUG] mod_sofia.c:156 sofia_on_execute() sofia/internal/1007@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@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@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@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@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@10.1.1.177 Expanded String set_user(default@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@10.1.1.177 Expanded String bridge(user/1001@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@10.1.1.177: v=0
Gary _______________________________________________ Freeswitch-users mailing list Freeswitch-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org