[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