[Freeswitch-users] Freeswitch translates "480 Temporarily Unavailable" as 200 OK and then BYE
Steven Ayre
steveayre at gmail.com
Fri Mar 15 23:39:03 MSK 2013
As I suspected, you're answering the call after the bridge.
EXECUTE sofia/internal/557100060084 at pxext.redvoiss.net answer()
-Steve
On 15 March 2013 18:55, Camila Troncoso <ctroncoso at redvoiss.net> wrote:
> Hi Steve,
>
>
>
> Thanks for answering.
>
>
>
> Here is FS debug :
>
>
>
> 2013-03-15 10:38:45.052210 [NOTICE] switch_channel.c:930 New Channel
> sofia/internal/557100060084 at pxext.redvoiss.net[86723e49-20c7-4cc0-b61e-af9010df4095]
>
> 2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5494 Channel sofia/internal/
> 557100060084 at pxext.redvoiss.net entering state [received][100]
>
> 2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5505 Remote SDP:
>
> v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
>
>
> 2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5697 (sofia/internal/
> 557100060084 at pxext.redvoiss.net) State Change CS_NEW -> CS_INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401
> (sofia/internal/557100060084 at pxext.redvoiss.net) State INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:85 sofia/internal/
> 557100060084 at pxext.redvoiss.net SOFIA INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:125 (sofia/internal/
> 557100060084 at pxext.redvoiss.net) State Change CS_INIT -> CS_ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401
> (sofia/internal/557100060084 at pxext.redvoiss.net) State INIT going to sleep
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1890 (sofia/internal/
> 557100060084 at pxext.redvoiss.net) Callstate Change DOWN -> RINGING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/557100060084 at pxext.redvoiss.net) State ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/
> 557100060084 at pxext.redvoiss.net SOFIA ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:104
> sofia/internal/557100060084 at pxext.redvoiss.net Standard ROUTING
>
> 2013-03-15 10:38:45.052210 [INFO] mod_dialplan_xml.c:481 Processing
> 557100060084 <557100060084>->02670056228235098 in context public
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [public->from_LCR] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [from_LCR] network_addr(64.76.154.110) =~ /^64\.76\.154\.36$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [public->from_LCR_INC] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [from_LCR_INC] network_addr(64.76.154.110) =~ /^64\.76\.154\.198$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [public->from_PROXY_Borde] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [from_PROXY_Borde] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [from_PROXY_Borde] destination_number(02670056228235098) =~ /^(.+)$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Action
> transfer(02670056228235098 XML default)
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:154
> (sofia/internal/557100060084 at pxext.redvoiss.net) State Change CS_ROUTING
> -> CS_EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/557100060084 at pxext.redvoiss.net) State ROUTING going to
> sleep
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:417
> (sofia/internal/557100060084 at pxext.redvoiss.net) State EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:241 sofia/internal/
> 557100060084 at pxext.redvoiss.net SOFIA EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:192
> sofia/internal/557100060084 at pxext.redvoiss.net Standard EXECUTE
>
> EXECUTE sofia/internal/557100060084 at pxext.redvoiss.nettransfer(02670056228235098 XML default)
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_ivr.c:1711 (sofia/internal/
> 557100060084 at pxext.redvoiss.net) State Change CS_EXECUTE -> CS_ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:729 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [NOTICE] switch_ivr.c:1717 Transfer
> sofia/internal/557100060084 at pxext.redvoiss.net to
> XML[02670056228235098 at default]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:417
> (sofia/internal/557100060084 at pxext.redvoiss.net) State EXECUTE going to
> sleep
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/557100060084 at pxext.redvoiss.net) State ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/
> 557100060084 at pxext.redvoiss.net SOFIA ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:104
> sofia/internal/557100060084 at pxext.redvoiss.net Standard ROUTING
>
> 2013-03-15 10:38:45.052210 [INFO] mod_dialplan_xml.c:481 Processing
> 557100060084 <557100060084>->02670056228235098 in context default
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->header_test_extension] continue=true
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [header_test_extension] ${sip_user_agent}(AddPac AP200 8.30W) =~ /AddPac
> SIP Gateway/ b
>
> reak=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition1] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition1] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition1] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:18 annexb=no)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition2] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition2] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition2] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:18 annexb=yes)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition3] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition3] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition3] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:18 annexb=no)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition4] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition4] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition4] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:18 annexb=yes)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition5] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition5] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition5] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:18 annexb=no)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition6] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition6] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition6] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:18 annexb=yes)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition7] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition7] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition7] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:4 annexa=no)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition8] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition8] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (FAIL)
> [to_LCR_condition8] ${switch_r_sdp}(v=0
>
> o=557100060084 600543 600543 IN IP4 192.168.1.100
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.154.110
>
> t=0 0
>
> m=audio 58994 RTP/AVP 18 4 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:4 G723/8000/1
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
> ) =~ //(.*)(a=fmtp:4 annexa=yes)(.*)/s/ break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net parsing
> [default->to_LCR_condition9] continue=false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Regex (PASS)
> [to_LCR_condition9] network_addr(64.76.154.110) =~ /^64\.76\.154\.110$/
> break=on-false
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Action
> set(call_timeout=50)
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Action
> set(hangup_after_bridge=true)
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Action
> export(nolocal:absolute_codec_string=G729,G723)
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Action
> set(sip_invite_domain=siplcr.redvoiss.net)
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Action
> bridge({sip_append_audio_sdp='a=rtpmap:18 G729/8000\n' 'a=fmtp:18
> annexb=no\n' 'a=rtpmap:4 G7
>
> 23/8000\n' 'a=fmtp:4 annexa=no\n'}sofia/
> 64.76.154.148/${destination_number}@siplcr.redvoiss.net<http://64.76.154.148/$%7Bdestination_number%7D@siplcr.redvoiss.net>)
>
>
> Dialplan: sofia/internal/557100060084 at pxext.redvoiss.net Action answer()
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:154
> (sofia/internal/557100060084 at pxext.redvoiss.net) State Change CS_ROUTING
> -> CS_EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/557100060084 at pxext.redvoiss.net) State ROUTING going to
> sleep
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:417
> (sofia/internal/557100060084 at pxext.redvoiss.net) State EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:241 sofia/internal/
> 557100060084 at pxext.redvoiss.net SOFIA EXECUTE
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:192
> sofia/internal/557100060084 at pxext.redvoiss.net Standard EXECUTE
>
> EXECUTE sofia/internal/557100060084 at pxext.redvoiss.netset(call_timeout=50)
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 557100060084 at pxext.redvoiss.net SET [call_timeout]=[50]
>
> EXECUTE sofia/internal/557100060084 at pxext.redvoiss.netset(hangup_after_bridge=true)
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 557100060084 at pxext.redvoiss.net SET [hangup_after_bridge]=[true]
>
> EXECUTE sofia/internal/557100060084 at pxext.redvoiss.netexport(nolocal:absolute_codec_string=G729,G723)
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1097 EXPORT
> (export_vars) (REMOTE ONLY) [absolute_codec_string]=[G729,G723]
>
> EXECUTE sofia/internal/557100060084 at pxext.redvoiss.netset(sip_invite_domain=
> siplcr.redvoiss.net)
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/
> 557100060084 at pxext.redvoiss.net SET [sip_invite_domain]=[
> siplcr.redvoiss.net]
>
> EXECUTE sofia/internal/557100060084 at pxext.redvoiss.netbridge({sip_append_audio_sdp='a=rtpmap:18 G729/8000\n' 'a=fmtp:18
> annexb=no\n' 'a=rtpmap:4 G723/8000\n
>
> ' 'a=fmtp:4 annexa=no\n'}sofia/
> 64.76.154.148/02670056228235098 at siplcr.redvoiss.net)
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1051 sofia/internal/
> 557100060084 at pxext.redvoiss.net EXPORTING[export_vars]
> [absolute_codec_string]=[G729,
>
> G723] to event
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_ivr_originate.c:1884 Parsing
> global variables
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_event.c:1521 Parsing variable
> [sip_append_audio_sdp]=[a=rtpmap:18 G729/8000
>
> a=fmtp:18 annexb=no
>
> a=rtpmap:4 G723/8000
>
> a=fmtp:4 annexa=no
>
> ]
>
> 2013-03-15 10:38:45.052210 [NOTICE] switch_channel.c:930 New Channel
> sofia/internal/02670056228235098 at siplcr.redvoiss.net[0faa4f45-6c78-485b-b496-f93aa011f0
>
> e6]
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:4659 (sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net) State Change CS_NEW -> CS_INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Running State
> Change CS_INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:85 sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net SOFIA INIT
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:125 (sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net) State Change CS_INIT -> CS_ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State INIT going
> to sleep
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Running State
> Change CS_ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1890 (sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net) Callstate Change DOWN -> RINGING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net SOFIA ROUTING
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_ivr_originate.c:66
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State Change
> CS_ROUTING -> CS_CONSUME_MED
>
> IA
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State ROUTING
> going to sleep
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Running State
> Change CS_CONSUME_MED
>
> IA
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:429
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State CONSUME_MEDIA
>
> 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:429
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State
> CONSUME_MEDIA going to sleep
>
> 2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5494 Channel sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net entering state [calling][0]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia.c:5494 Channel sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net entering state [proceeding][183]
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia.c:5505 Remote SDP:
>
> v=0
>
> o=02670056228235098 1363340323 1363340323 IN IP4 64.76.155.5
>
> s=AddPac Gateway SDP
>
> c=IN IP4 64.76.155.5
>
> t=1363340323 0
>
> m=audio 23622 RTP/AVP 18 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:101 telephone-event/8000/1
>
> a=fmtp:101 0-15
>
> a=ptime:20
>
>
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:4798 Audio Codec Compare
> [G729:18:8000:20:8000]/[G729:18:8000:20:8000]
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:327 Sangoma init
> called (encoding = 1, decoding = 1, iana = 18)
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:377 Sangoma init
> done for codec G729/Sangoma G729, iana = 18
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:327 Sangoma init
> called (encoding = 1, decoding = 1, iana = 18)
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:377 Sangoma init
> done for codec G729/Sangoma G729, iana = 18
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:2919 Set Codec
> sofia/internal/02670056228235098 at siplcr.redvoiss.net G729/8000 20 ms 160
> samples 8000 bits
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_codec.c:111 sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net Original read codec set to G729:18
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:4912 Set 2833 dtmf send
> payload to 101
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:3171 AUDIO RTP
> [sofia/internal/02670056228235098 at siplcr.redvoiss.net] 64.76.154.148 port
> 25378 -> 64.76.155.5
>
> port 23622 codec: 18 ms: 20
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_rtp.c:1659 Starting timer [soft]
> 160 bytes per 20ms
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:3435 Set 2833 dtmf send
> payload to 101
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:3441 Set 2833 dtmf receive
> payload to 101
>
> 2013-03-15 10:38:45.122202 [NOTICE] sofia_glue.c:3945 Pre-Answer
> sofia/internal/02670056228235098 at siplcr.redvoiss.net!
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2936 (sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net) Callstate Change RINGING -> EARLY
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2978 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [INFO] switch_ivr_originate.c:3215 Sending
> early media
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:4798 Audio Codec Compare
> [G729:18:8000:20:8000]/[G729:18:8000:20:8000]
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:327 Sangoma init
> called (encoding = 1, decoding = 1, iana = 18)
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:377 Sangoma init
> done for codec G729/Sangoma G729, iana = 18
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:327 Sangoma init
> called (encoding = 1, decoding = 1, iana = 18)
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sangoma_codec.c:377 Sangoma init
> done for codec G729/Sangoma G729, iana = 18
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:2919 Set Codec
> sofia/internal/557100060084 at pxext.redvoiss.net G729/8000 20 ms 160
> samples 8000 bits
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_codec.c:111 sofia/internal/
> 557100060084 at pxext.redvoiss.net Original read codec set to G729:18
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:4919 Set 2833 dtmf
> send/recv payload to 101
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:3171 AUDIO RTP
> [sofia/internal/557100060084 at pxext.redvoiss.net] 64.76.154.148 port 18222
> -> 64.76.154.110 por
>
> t 58994 codec: 18 ms: 20
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_rtp.c:1659 Starting timer [soft]
> 160 bytes per 20ms
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:3435 Set 2833 dtmf send
> payload to 101
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia_glue.c:3441 Set 2833 dtmf receive
> payload to 101
>
> 2013-03-15 10:38:45.122202 [NOTICE] sofia_glue.c:3945 Pre-Answer
> sofia/internal/557100060084 at pxext.redvoiss.net!
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2936 (sofia/internal/
> 557100060084 at pxext.redvoiss.net) Callstate Change RINGING -> EARLY
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sofia.c:2562 Ring SDP:
>
> v=0
>
> o=FreeSWITCH 1363336503 1363336504 IN IP4 64.76.154.148
>
> s=FreeSWITCH
>
> c=IN IP4 64.76.154.148
>
> t=0 0
>
> m=audio 18222 RTP/AVP 18 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:101 telephone-event/8000
>
> a=fmtp:101 0-16
>
> a=silenceSupp:off - - - -
>
> a=ptime:20
>
> a=sendrecv
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:729 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_ivr_originate.c:3266 Originate
> Resulted in Success: [sofia/internal/02670056228235098 at siplcr.redvoiss.net
> ]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [DEBUG] sofia.c:5487 Channel sofia/internal/
> 557100060084 at pxext.redvoiss.net skipping state [early][183]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:729 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:729 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_ivr_bridge.c:1327
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State Change
> CS_CONSUME_MEDIA -> CS_EXCHAN
>
> GE_MEDIA
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Running State
> Change CS_EXCHANGE_ME
>
> DIA
>
> 2013-03-15 10:38:45.122202 [DEBUG] switch_core_state_machine.c:420
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State
> EXCHANGE_MEDIA
>
> 2013-03-15 10:38:45.122202 [DEBUG] mod_sofia.c:578 SOFIA EXCHANGE_MEDIA
>
> 2013-03-15 10:38:45.142217 [DEBUG] mod_sangoma_codec.c:769 Discarding
> decoded frame of 320 bytes from RTP session 2062360, windex = 3, rindex = 3
>
> 2013-03-15 10:38:45.142217 [DEBUG] mod_sangoma_codec.c:769 Discarding
> decoded frame of 320 bytes from RTP session 2062360, windex = 0, rindex = 0
>
> 2013-03-15 10:38:45.142217 [DEBUG] mod_sangoma_codec.c:581 Discarding
> encoded frame of 10 bytes from RTP session 2062359, windex = 0, rindex = 0
>
> 2013-03-15 10:38:45.142217 [DEBUG] mod_sangoma_codec.c:581 Discarding
> encoded frame of 10 bytes from RTP session 2062359, windex = 1, rindex = 1
>
> 2013-03-15 10:38:45.142217 [DEBUG] mod_sangoma_codec.c:581 Discarding
> encoded frame of 10 bytes from RTP session 2062359, windex = 2, rindex = 2
>
> 2013-03-15 10:38:45.142217 [DEBUG] mod_sangoma_codec.c:581 Discarding
> encoded frame of 10 bytes from RTP session 2062359, windex = 3, rindex = 3
>
> 2013-03-15 10:38:45.302184 [DEBUG] switch_rtp.c:3204 Correct ip/port
> confirmed.
>
> 2013-03-15 10:38:51.473603 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.473603 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.473603 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.483587 [DEBUG] sofia.c:5494 Channel sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net entering state [terminated][480]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_channel.c:2852 (sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net) Callstate Change EARLY -> HANGUP
>
> 2013-03-15 10:38:51.483587 [NOTICE] sofia.c:6258 Hangup sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net [CS_EXCHANGE_MEDIA]
> [NO_USER_RESPONSE]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_channel.c:2875 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [KILL]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD
> DONE [sofia/internal/02670056228235098 at siplcr.redvoiss.net]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_ivr_bridge.c:611 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:420
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State
> EXCHANGE_MEDIA going to sleep
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Running State
> Change CS_HANGUP
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:602
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State HANGUP
>
> 2013-03-15 10:38:51.483587 [DEBUG] mod_sofia.c:469 Channel sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net hanging up, cause: NO_USER_RESPONSE
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:47
> sofia/internal/02670056228235098 at siplcr.redvoiss.net Standard HANGUP,
> cause: NO_USER_RESPON
>
> SE
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:602
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State HANGUP going
> to sleep
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:393
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State Change
> CS_HANGUP -> CS_REPORT
>
> ING
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Running State
> Change CS_REPORTING
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:662
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State REPORTING
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:79
> sofia/internal/02670056228235098 at siplcr.redvoiss.net Standard REPORTING,
> cause: NO_USER_RES
>
> PONSE
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:662
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State REPORTING
> going to sleep
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:387
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State Change
> CS_REPORTING -> CS_DES
>
> TROY
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1380 Session
> 1359227 (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Locked,
> Waiting on exter
>
> nal entities
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD
> DONE [sofia/internal/557100060084 at pxext.redvoiss.net]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_ivr_bridge.c:611 Send signal
> sofia/internal/02670056228235098 at siplcr.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:729 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1398 Session
> 1359227 (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Ended
>
> 2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1400 Close
> Channel sofia/internal/02670056228235098 at siplcr.redvoiss.net [CS_DESTROY]
>
> EXECUTE sofia/internal/557100060084 at pxext.redvoiss.net answer()
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:491
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Callstate Change
> HANGUP -> DOWN
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:494
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) Running State
> Change CS_DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:374 sofia/internal/
> 02670056228235098 at siplcr.redvoiss.net SOFIA DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sangoma_codec.c:848 Sangoma destroy
> called.
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sangoma_codec.c:848 Sangoma destroy
> called.
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:86
> sofia/internal/02670056228235098 at siplcr.redvoiss.net Standard DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504
> (sofia/internal/02670056228235098 at siplcr.redvoiss.net) State DESTROY
> going to sleep
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:750 Local SDP
> sofia/internal/557100060084 at pxext.redvoiss.net:
>
> v=0
>
> o=FreeSWITCH 1363336503 1363336505 IN IP4 64.76.154.148
>
> s=FreeSWITCH
>
> c=IN IP4 64.76.154.148
>
> t=0 0
>
> m=audio 18222 RTP/AVP 18 101
>
> a=rtpmap:18 G729/8000
>
> a=rtpmap:101 telephone-event/8000
>
> a=fmtp:101 0-16
>
> a=silenceSupp:off - - - -
>
> a=ptime:20
>
> a=sendrecv
>
>
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:729 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_channel.c:3194 (sofia/internal/
> 557100060084 at pxext.redvoiss.net) Callstate Change EARLY -> ACTIVE
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:875 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.503602 [NOTICE] mod_dptools.c:1135 Channel
> [sofia/internal/557100060084 at pxext.redvoiss.net] has been answered
>
> 2013-03-15 10:38:51.503602 [DEBUG] sofia.c:5494 Channel sofia/internal/
> 557100060084 at pxext.redvoiss.net entering state [completed][200]
>
> 2013-03-15 10:38:51.503602 [NOTICE] switch_core_state_machine.c:226
> sofia/internal/557100060084 at pxext.redvoiss.net has executed the last
> dialplan instruction
>
> , hanging up.
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_channel.c:2852 (sofia/internal/
> 557100060084 at pxext.redvoiss.net) Callstate Change ACTIVE -> HANGUP
>
> 2013-03-15 10:38:51.503602 [NOTICE] switch_core_state_machine.c:228 Hangup
> sofia/internal/557100060084 at pxext.redvoiss.net [CS_EXECUTE]
> [NORMAL_CLEARING]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_channel.c:2875 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [KILL]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:417
> (sofia/internal/557100060084 at pxext.redvoiss.net) State EXECUTE going to
> sleep
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_HANGUP
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:602
> (sofia/internal/557100060084 at pxext.redvoiss.net) State HANGUP
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:463 sofia/internal/
> 557100060084 at pxext.redvoiss.net Overriding SIP cause 480 with 480 from
> the other leg
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:469 Channel sofia/internal/
> 557100060084 at pxext.redvoiss.net hanging up, cause: NORMAL_CLEARING
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:513 Sending BYE to
> sofia/internal/557100060084 at pxext.redvoiss.net
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:47
> sofia/internal/557100060084 at pxext.redvoiss.net Standard HANGUP, cause:
> NORMAL_CLEARING
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:602
> (sofia/internal/557100060084 at pxext.redvoiss.net) State HANGUP going to
> sleep
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:393
> (sofia/internal/557100060084 at pxext.redvoiss.net) State Change CS_HANGUP
> -> CS_REPORTING
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:362
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_REPORTING
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:662
> (sofia/internal/557100060084 at pxext.redvoiss.net) State REPORTING
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:79
> sofia/internal/557100060084 at pxext.redvoiss.net Standard REPORTING, cause:
> NORMAL_CLEARING
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:662
> (sofia/internal/557100060084 at pxext.redvoiss.net) State REPORTING going to
> sleep
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:387
> (sofia/internal/557100060084 at pxext.redvoiss.net) State Change
> CS_REPORTING -> CS_DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1180 Send signal
> sofia/internal/557100060084 at pxext.redvoiss.net [BREAK]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1380 Session
> 1359226 (sofia/internal/557100060084 at pxext.redvoiss.net) Locked, Waiting
> on external en
>
> tities
>
> 2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1398 Session
> 1359226 (sofia/internal/557100060084 at pxext.redvoiss.net) Ended
>
> 2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1400 Close
> Channel sofia/internal/557100060084 at pxext.redvoiss.net [CS_DESTROY]
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:491
> (sofia/internal/557100060084 at pxext.redvoiss.net) Callstate Change HANGUP
> -> DOWN
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:494
> (sofia/internal/557100060084 at pxext.redvoiss.net) Running State Change
> CS_DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504
> (sofia/internal/557100060084 at pxext.redvoiss.net) State DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:374 sofia/internal/
> 557100060084 at pxext.redvoiss.net SOFIA DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sangoma_codec.c:848 Sangoma destroy
> called.
>
> 2013-03-15 10:38:51.503602 [DEBUG] mod_sangoma_codec.c:848 Sangoma destroy
> called.
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:86
> sofia/internal/557100060084 at pxext.redvoiss.net Standard DESTROY
>
> 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504
> (sofia/internal/557100060084 at pxext.redvoiss.net) State DESTROY going to
> sleep
>
>
>
>
>
>
>
> Regards,
>
>
>
> Camila
>
> *From:* freeswitch-users-bounces at lists.freeswitch.org [mailto:
> freeswitch-users-bounces at lists.freeswitch.org] *On Behalf Of *Steven Ayre
> *Sent:* viernes, 15 de marzo de 2013 13:22
> *To:* FreeSWITCH Users Help
> *Subject:* Re: [Freeswitch-users] Freeswitch translates "480 Temporarily
> Unavailable" as 200 OK and then BYE
>
>
>
> FS will send 480 back as 480 - I do it all the time.
>
>
>
> There's probably something in the dialplan that's answering the call after
> the bridge fails.
>
>
>
> Can you share a debug-level log of the call? It will show what the call
> was doing that triggered the 200 OK.
>
>
>
> -Steve
>
>
>
>
>
>
>
> On 15 March 2013 16:04, Camila Troncoso <ctroncoso at redvoiss.net> wrote:
>
> Hi,
>
>
>
> I have a this call :
>
>
>
> * PROXY FS
> DEST*
>
> INVITE
>
> INVITE
>
> 100 Trying
>
> 100 Trying
>
> 183 SDP
>
> 183 Session
> P.SDP
>
> 480 Temporarily Unavailable
>
> 200 Ok DP
>
> ACK
>
> BYE
>
>
>
> When I receive a “ 480 Temporarily Unavailable” after a “183 Session
> Progress with SDP” FreeSWITCH tries to hang-up the call normally by sending
> a 200 ok and then a BYE response. But the call was not effectively
> established. It is generating problems in my proxy server.
>
>
>
> Is there a way to relay the 480 response or to change it to some other
> more accurate response.
>
>
>
> Regards,
>
>
>
> *Camila Troncoso **|* Ingeniero de Desarrollo
>
> RedVoiss *|*ctroncoso at redvoiss.net
>
> Santiago - Chile *|* +56 2 2408535
>
> www.redvoiss.net
>
>
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
>
>
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> 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
>
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
>
>
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> 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/20130315/9a2313d9/attachment-0001.html
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 246 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0008.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 244 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0009.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 297 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0010.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 262 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0011.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 244 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0012.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 242 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0013.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 243 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0014.obj
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/octet-stream
Size: 262 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130315/9a2313d9/attachment-0015.obj
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list