[Freeswitch-users] Problem with first use.

Brian West brian at freeswitch.org
Fri Oct 10 10:47:35 PDT 2008


     <!--<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 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
> _______________________________________________
> Freeswitch-users mailing list
> Freeswitch-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org

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


More information about the FreeSWITCH-users mailing list