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/[EMAIL PROTECTED] [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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
[BREAK]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:365
switch_core_session_run() sofia/internal/[EMAIL PROTECTED] Running State Change
CS_INIT
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:415
switch_core_session_run() (sofia/internal/[EMAIL PROTECTED]) State INIT
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:80 sofia_on_init()
sofia/internal/[EMAIL PROTECTED] SOFIA INIT
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:107 sofia_on_init()
sofia/internal/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
[BREAK]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:415
switch_core_session_run() (sofia/internal/[EMAIL PROTECTED]) State INIT going
to sleep
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:365
switch_core_session_run() sofia/internal/[EMAIL PROTECTED] Running State Change
CS_ROUTING
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:420
switch_core_session_run() (sofia/internal/[EMAIL PROTECTED]) State ROUTING
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:119 sofia_on_routing()
sofia/internal/[EMAIL PROTECTED] SOFIA ROUTING
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:64
switch_core_standard_on_routing() Standard ROUTING sofia/internal/[EMAIL
PROTECTED]
2008-10-10 13:33:00 [INFO] mod_dialplan_xml.c:222 dialplan_hunt() Processing
Line1->[EMAIL PROTECTED]
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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
[BREAK]
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:420
switch_core_session_run() (sofia/internal/[EMAIL PROTECTED]) State ROUTING
going to sleep
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:365
switch_core_session_run() sofia/internal/[EMAIL PROTECTED] Running State Change
CS_EXECUTE
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:430
switch_core_session_run() (sofia/internal/[EMAIL PROTECTED]) State EXECUTE
2008-10-10 13:33:00 [DEBUG] mod_sofia.c:156 sofia_on_execute()
sofia/internal/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
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/[EMAIL PROTECTED] Execute
set_user([EMAIL PROTECTED])
2008-10-10 13:33:00 [DEBUG] switch_core_session.c:1045
switch_core_session_execute_application() sofia/internal/[EMAIL PROTECTED]
Expanded String set_user([EMAIL PROTECTED])
2008-10-10 13:33:00 [DEBUG] switch_core_state_machine.c:140
switch_core_standard_on_execute() sofia/internal/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
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/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]
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/[EMAIL PROTECTED] Execute
bridge(user/[EMAIL PROTECTED])
2008-10-10 13:33:02 [DEBUG] switch_core_session.c:1045
switch_core_session_execute_application() sofia/internal/[EMAIL PROTECTED]
Expanded String bridge(user/[EMAIL PROTECTED])
2008-10-10 13:33:02 [DEBUG] switch_ivr_originate.c:652 switch_ivr_originate()
variable string 0 = [EMAIL PROTECTED]
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/[EMAIL PROTECTED]: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/[EMAIL PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]:51054;transport=udp;fs_nat=yes) State INIT
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:80 sofia_on_init()
sofia/internal/[EMAIL PROTECTED]: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/[EMAIL
PROTECTED]:51054;transport=udp;fs_nat=yes
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:107 sofia_on_init()
sofia/internal/[EMAIL PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL PROTECTED]: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/[EMAIL
PROTECTED]:51054;transport=udp;fs_nat=yes) State ROUTING
2008-10-10 13:33:02 [DEBUG] mod_sofia.c:119 sofia_on_routing()
sofia/internal/[EMAIL PROTECTED]: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/[EMAIL PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]:51054;transport=udp;fs_nat=yes
2008-10-10 13:33:02 [DEBUG] sofia.c:2129 sofia_handle_sip_i_state() Channel
sofia/internal/[EMAIL PROTECTED]: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/[EMAIL PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL
PROTECTED]: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/[EMAIL PROTECTED] Execute
answer()
2008-10-10 13:33:02 [DEBUG] sofia_glue.c:1739 sofia_glue_activate_rtp() AUDIO
RTP [sofia/internal/[EMAIL PROTECTED] 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/[EMAIL PROTECTED]:
v=0
Gary_______________________________________________
Freeswitch-users mailing list
[email protected]
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org
