Freeswitch translates "480 Temporarily Unavailable" as 200 OK and then BYE
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@redvoiss.net Santiago - Chile *|* +56 2 2408535 www.redvoiss.net
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@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@redvoiss.net
Santiago - Chile *|* +56 2 2408535
www.redvoiss.net
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
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@pxext.redvoiss.net[86723e49-20c7-4cc0-b61e-af9010df4095] 2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5494 Channel sofia/internal/ 557100060084@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@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/557100060084@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@pxext.redvoiss.net) State INIT 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:85 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA INIT 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:125 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/557100060084@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@pxext.redvoiss.net) Running State Change CS_ROUTING 2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1890 (sofia/internal/ 557100060084@pxext.redvoiss.net) Callstate Change DOWN -> RINGING 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/557100060084@pxext.redvoiss.net) State ROUTING 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA ROUTING 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:104 sofia/internal/557100060084@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@pxext.redvoiss.net parsing [public->from_LCR] continue=false Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Regex (FAIL) [from_LCR] network_addr(64.76.154.110) =~ /^64\.76\.154\.36$/ break=on-false Dialplan: sofia/internal/557100060084@pxext.redvoiss.net parsing [public->from_LCR_INC] continue=false Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net parsing [public->from_PROXY_Borde] continue=false Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net Regex (PASS) [from_PROXY_Borde] destination_number(02670056228235098) =~ /^(.+)$/ break=on-false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State EXECUTE 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:241 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA EXECUTE 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:192 sofia/internal/557100060084@pxext.redvoiss.net Standard EXECUTE EXECUTE sofia/internal/557100060084@pxext.redvoiss.nettransfer(02670056228235098 XML default) 2013-03-15 10:38:45.052210 [DEBUG] switch_ivr.c:1711 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:729 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [NOTICE] switch_ivr.c:1717 Transfer sofia/internal/557100060084@pxext.redvoiss.net to XML[02670056228235098@default] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State ROUTING 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA ROUTING 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:104 sofia/internal/557100060084@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@pxext.redvoiss.net parsing [default->header_test_extension] continue=true Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net parsing [default->to_LCR_condition1] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition2] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition3] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition4] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition5] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition6] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition7] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition8] continue=false Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition9] continue=false Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net Action set(call_timeout=50) Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Action set(hangup_after_bridge=true) Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Action export(nolocal:absolute_codec_string=G729,G723) Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Action set(sip_invite_domain=siplcr.redvoiss.net) Dialplan: sofia/internal/557100060084@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) Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Action answer() 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/557100060084@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State EXECUTE 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:241 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA EXECUTE 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:192 sofia/internal/557100060084@pxext.redvoiss.net Standard EXECUTE EXECUTE sofia/internal/557100060084@pxext.redvoiss.net set(call_timeout=50) 2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/ 557100060084@pxext.redvoiss.net SET [call_timeout]=[50] EXECUTE sofia/internal/557100060084@pxext.redvoiss.netset(hangup_after_bridge=true) 2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/ 557100060084@pxext.redvoiss.net SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/557100060084@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@pxext.redvoiss.netset(sip_invite_domain= siplcr.redvoiss.net) 2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/ 557100060084@pxext.redvoiss.net SET [sip_invite_domain]=[siplcr.redvoiss.net ] EXECUTE sofia/internal/557100060084@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@siplcr.redvoiss.net) 2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1051 sofia/internal/ 557100060084@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@siplcr.redvoiss.net[0faa4f45-6c78-485b-b496-f93aa011f0 e6] 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:4659 (sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) State INIT 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:85 sofia/internal/ 02670056228235098@siplcr.redvoiss.net SOFIA INIT 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:125 (sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) Running State Change CS_ROUTING 2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1890 (sofia/internal/ 02670056228235098@siplcr.redvoiss.net) Callstate Change DOWN -> RINGING 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/02670056228235098@siplcr.redvoiss.net) State ROUTING 2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/ 02670056228235098@siplcr.redvoiss.net SOFIA ROUTING 2013-03-15 10:38:45.052210 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/02670056228235098@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@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@siplcr.redvoiss.net) State CONSUME_MEDIA 2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:429 (sofia/internal/02670056228235098@siplcr.redvoiss.net) State CONSUME_MEDIA going to sleep 2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5494 Channel sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.122202 [DEBUG] sofia.c:5494 Channel sofia/internal/ 02670056228235098@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@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@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@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@siplcr.redvoiss.net! 2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2936 (sofia/internal/ 02670056228235098@siplcr.redvoiss.net) Callstate Change RINGING -> EARLY 2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2978 Send signal sofia/internal/557100060084@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@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@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@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@pxext.redvoiss.net! 2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2936 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.122202 [DEBUG] switch_ivr_originate.c:3266 Originate Resulted in Success: [sofia/internal/02670056228235098@siplcr.redvoiss.net] 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.122202 [DEBUG] sofia.c:5487 Channel sofia/internal/ 557100060084@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:729 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:45.122202 [DEBUG] switch_ivr_bridge.c:1327 (sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:45.122202 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/02670056228235098@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@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:51.473603 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:51.473603 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:51.483587 [DEBUG] sofia.c:5494 Channel sofia/internal/ 02670056228235098@siplcr.redvoiss.net entering state [terminated][480] 2013-03-15 10:38:51.483587 [DEBUG] switch_channel.c:2852 (sofia/internal/ 02670056228235098@siplcr.redvoiss.net) Callstate Change EARLY -> HANGUP 2013-03-15 10:38:51.483587 [NOTICE] sofia.c:6258 Hangup sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [KILL] 2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:51.483587 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD DONE [sofia/internal/02670056228235098@siplcr.redvoiss.net] 2013-03-15 10:38:51.483587 [DEBUG] switch_ivr_bridge.c:611 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:420 (sofia/internal/02670056228235098@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@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@siplcr.redvoiss.net) State HANGUP 2013-03-15 10:38:51.483587 [DEBUG] mod_sofia.c:469 Channel sofia/internal/ 02670056228235098@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@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@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@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) State REPORTING 2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:79 sofia/internal/02670056228235098@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@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@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@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1380 Session 1359227 (sofia/internal/02670056228235098@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@pxext.redvoiss.net] 2013-03-15 10:38:51.503602 [DEBUG] switch_ivr_bridge.c:611 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK] 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:729 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1398 Session 1359227 (sofia/internal/02670056228235098@siplcr.redvoiss.net) Ended 2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/02670056228235098@siplcr.redvoiss.net [CS_DESTROY] EXECUTE sofia/internal/557100060084@pxext.redvoiss.net answer() 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/02670056228235098@siplcr.redvoiss.net) Callstate Change HANGUP -> DOWN 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) State DESTROY 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:374 sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net Standard DESTROY 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/02670056228235098@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@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:51.503602 [DEBUG] switch_channel.c:3194 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:51.503602 [NOTICE] mod_dptools.c:1135 Channel [sofia/internal/557100060084@pxext.redvoiss.net] has been answered 2013-03-15 10:38:51.503602 [DEBUG] sofia.c:5494 Channel sofia/internal/ 557100060084@pxext.redvoiss.net entering state [completed][200] 2013-03-15 10:38:51.503602 [NOTICE] switch_core_state_machine.c:226 sofia/internal/557100060084@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@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@pxext.redvoiss.net [CS_EXECUTE] [NORMAL_CLEARING] 2013-03-15 10:38:51.503602 [DEBUG] switch_channel.c:2875 Send signal sofia/internal/557100060084@pxext.redvoiss.net [KILL] 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State HANGUP 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:463 sofia/internal/ 557100060084@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@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@pxext.redvoiss.net 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:47 sofia/internal/557100060084@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@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@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/557100060084@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@pxext.redvoiss.net) State REPORTING 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:79 sofia/internal/557100060084@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@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@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@pxext.redvoiss.net [BREAK] 2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1380 Session 1359226 (sofia/internal/557100060084@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@pxext.redvoiss.net) Ended 2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/557100060084@pxext.redvoiss.net [CS_DESTROY] 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/557100060084@pxext.redvoiss.net) Callstate Change HANGUP -> DOWN 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/557100060084@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@pxext.redvoiss.net) State DESTROY 2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:374 sofia/internal/ 557100060084@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@pxext.redvoiss.net Standard DESTROY 2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/557100060084@pxext.redvoiss.net) State DESTROY going to sleep Regards, Camila *From:* freeswitch-users-bounces@lists.freeswitch.org [mailto: freeswitch-users-bounces@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@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@redvoiss.net Santiago - Chile *|* +56 2 2408535 www.redvoiss.net _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
As I suspected, you're answering the call after the bridge. EXECUTE sofia/internal/557100060084@pxext.redvoiss.net answer() -Steve On 15 March 2013 18:55, Camila Troncoso <ctroncoso@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@pxext.redvoiss.net[86723e49-20c7-4cc0-b61e-af9010df4095]
2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5494 Channel sofia/internal/ 557100060084@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@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/557100060084@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@pxext.redvoiss.net) State INIT
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:85 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA INIT
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:125 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/557100060084@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@pxext.redvoiss.net) Running State Change CS_ROUTING
2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1890 (sofia/internal/ 557100060084@pxext.redvoiss.net) Callstate Change DOWN -> RINGING
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/557100060084@pxext.redvoiss.net) State ROUTING
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA ROUTING
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:104 sofia/internal/557100060084@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@pxext.redvoiss.net parsing [public->from_LCR] continue=false
Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Regex (FAIL) [from_LCR] network_addr(64.76.154.110) =~ /^64\.76\.154\.36$/ break=on-false
Dialplan: sofia/internal/557100060084@pxext.redvoiss.net parsing [public->from_LCR_INC] continue=false
Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net parsing [public->from_PROXY_Borde] continue=false
Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net Regex (PASS) [from_PROXY_Borde] destination_number(02670056228235098) =~ /^(.+)$/ break=on-false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State EXECUTE
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:241 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA EXECUTE
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:192 sofia/internal/557100060084@pxext.redvoiss.net Standard EXECUTE
EXECUTE sofia/internal/557100060084@pxext.redvoiss.nettransfer(02670056228235098 XML default)
2013-03-15 10:38:45.052210 [DEBUG] switch_ivr.c:1711 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:729 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [NOTICE] switch_ivr.c:1717 Transfer sofia/internal/557100060084@pxext.redvoiss.net to XML[02670056228235098@default]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State ROUTING
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA ROUTING
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:104 sofia/internal/557100060084@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@pxext.redvoiss.net parsing [default->header_test_extension] continue=true
Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net parsing [default->to_LCR_condition1] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition2] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition3] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition4] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition5] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition6] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition7] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition8] continue=false
Dialplan: sofia/internal/557100060084@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@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@pxext.redvoiss.net parsing [default->to_LCR_condition9] continue=false
Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net Action set(call_timeout=50)
Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Action export(nolocal:absolute_codec_string=G729,G723)
Dialplan: sofia/internal/557100060084@pxext.redvoiss.net Action set(sip_invite_domain=siplcr.redvoiss.net)
Dialplan: sofia/internal/557100060084@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@pxext.redvoiss.net Action answer()
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/557100060084@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State EXECUTE
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:241 sofia/internal/ 557100060084@pxext.redvoiss.net SOFIA EXECUTE
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:192 sofia/internal/557100060084@pxext.redvoiss.net Standard EXECUTE
EXECUTE sofia/internal/557100060084@pxext.redvoiss.netset(call_timeout=50)
2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/ 557100060084@pxext.redvoiss.net SET [call_timeout]=[50]
EXECUTE sofia/internal/557100060084@pxext.redvoiss.netset(hangup_after_bridge=true)
2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/ 557100060084@pxext.redvoiss.net SET [hangup_after_bridge]=[true]
EXECUTE sofia/internal/557100060084@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@pxext.redvoiss.netset(sip_invite_domain= siplcr.redvoiss.net)
2013-03-15 10:38:45.052210 [DEBUG] mod_dptools.c:1281 sofia/internal/ 557100060084@pxext.redvoiss.net SET [sip_invite_domain]=[ siplcr.redvoiss.net]
EXECUTE sofia/internal/557100060084@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@siplcr.redvoiss.net)
2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1051 sofia/internal/ 557100060084@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@siplcr.redvoiss.net[0faa4f45-6c78-485b-b496-f93aa011f0
e6]
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:4659 (sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) State INIT
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:85 sofia/internal/ 02670056228235098@siplcr.redvoiss.net SOFIA INIT
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:125 (sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) Running State Change CS_ROUTING
2013-03-15 10:38:45.052210 [DEBUG] switch_channel.c:1890 (sofia/internal/ 02670056228235098@siplcr.redvoiss.net) Callstate Change DOWN -> RINGING
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/02670056228235098@siplcr.redvoiss.net) State ROUTING
2013-03-15 10:38:45.052210 [DEBUG] mod_sofia.c:148 sofia/internal/ 02670056228235098@siplcr.redvoiss.net SOFIA ROUTING
2013-03-15 10:38:45.052210 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/02670056228235098@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@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@siplcr.redvoiss.net) State CONSUME_MEDIA
2013-03-15 10:38:45.052210 [DEBUG] switch_core_state_machine.c:429 (sofia/internal/02670056228235098@siplcr.redvoiss.net) State CONSUME_MEDIA going to sleep
2013-03-15 10:38:45.052210 [DEBUG] sofia.c:5494 Channel sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.122202 [DEBUG] sofia.c:5494 Channel sofia/internal/ 02670056228235098@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@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@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@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@siplcr.redvoiss.net!
2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2936 (sofia/internal/ 02670056228235098@siplcr.redvoiss.net) Callstate Change RINGING -> EARLY
2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2978 Send signal sofia/internal/557100060084@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@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@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@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@pxext.redvoiss.net!
2013-03-15 10:38:45.122202 [DEBUG] switch_channel.c:2936 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.122202 [DEBUG] switch_ivr_originate.c:3266 Originate Resulted in Success: [sofia/internal/02670056228235098@siplcr.redvoiss.net ]
2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.122202 [DEBUG] sofia.c:5487 Channel sofia/internal/ 557100060084@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.122202 [DEBUG] switch_core_session.c:729 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:45.122202 [DEBUG] switch_ivr_bridge.c:1327 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:45.122202 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/02670056228235098@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@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:51.473603 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:51.473603 [DEBUG] switch_core_session.c:875 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:51.483587 [DEBUG] sofia.c:5494 Channel sofia/internal/ 02670056228235098@siplcr.redvoiss.net entering state [terminated][480]
2013-03-15 10:38:51.483587 [DEBUG] switch_channel.c:2852 (sofia/internal/ 02670056228235098@siplcr.redvoiss.net) Callstate Change EARLY -> HANGUP
2013-03-15 10:38:51.483587 [NOTICE] sofia.c:6258 Hangup sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net [KILL]
2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:51.483587 [DEBUG] switch_ivr_bridge.c:586 BRIDGE THREAD DONE [sofia/internal/02670056228235098@siplcr.redvoiss.net]
2013-03-15 10:38:51.483587 [DEBUG] switch_ivr_bridge.c:611 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:420 (sofia/internal/02670056228235098@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@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@siplcr.redvoiss.net) State HANGUP
2013-03-15 10:38:51.483587 [DEBUG] mod_sofia.c:469 Channel sofia/internal/ 02670056228235098@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@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@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@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) State REPORTING
2013-03-15 10:38:51.483587 [DEBUG] switch_core_state_machine.c:79 sofia/internal/02670056228235098@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@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@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@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:51.483587 [DEBUG] switch_core_session.c:1380 Session 1359227 (sofia/internal/02670056228235098@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@pxext.redvoiss.net]
2013-03-15 10:38:51.503602 [DEBUG] switch_ivr_bridge.c:611 Send signal sofia/internal/02670056228235098@siplcr.redvoiss.net [BREAK]
2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:729 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1398 Session 1359227 (sofia/internal/02670056228235098@siplcr.redvoiss.net) Ended
2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/02670056228235098@siplcr.redvoiss.net [CS_DESTROY]
EXECUTE sofia/internal/557100060084@pxext.redvoiss.net answer()
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/02670056228235098@siplcr.redvoiss.net) Callstate Change HANGUP -> DOWN
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/02670056228235098@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@siplcr.redvoiss.net) State DESTROY
2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:374 sofia/internal/ 02670056228235098@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@siplcr.redvoiss.net Standard DESTROY
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/02670056228235098@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@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:51.503602 [DEBUG] switch_channel.c:3194 (sofia/internal/ 557100060084@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:51.503602 [NOTICE] mod_dptools.c:1135 Channel [sofia/internal/557100060084@pxext.redvoiss.net] has been answered
2013-03-15 10:38:51.503602 [DEBUG] sofia.c:5494 Channel sofia/internal/ 557100060084@pxext.redvoiss.net entering state [completed][200]
2013-03-15 10:38:51.503602 [NOTICE] switch_core_state_machine.c:226 sofia/internal/557100060084@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@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@pxext.redvoiss.net [CS_EXECUTE] [NORMAL_CLEARING]
2013-03-15 10:38:51.503602 [DEBUG] switch_channel.c:2875 Send signal sofia/internal/557100060084@pxext.redvoiss.net [KILL]
2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1180 Send signal sofia/internal/557100060084@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/557100060084@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@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@pxext.redvoiss.net) State HANGUP
2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:463 sofia/internal/ 557100060084@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@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@pxext.redvoiss.net
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:47 sofia/internal/557100060084@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@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@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/557100060084@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@pxext.redvoiss.net) State REPORTING
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:79 sofia/internal/557100060084@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@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@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@pxext.redvoiss.net [BREAK]
2013-03-15 10:38:51.503602 [DEBUG] switch_core_session.c:1380 Session 1359226 (sofia/internal/557100060084@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@pxext.redvoiss.net) Ended
2013-03-15 10:38:51.503602 [NOTICE] switch_core_session.c:1400 Close Channel sofia/internal/557100060084@pxext.redvoiss.net [CS_DESTROY]
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/557100060084@pxext.redvoiss.net) Callstate Change HANGUP -> DOWN
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/557100060084@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@pxext.redvoiss.net) State DESTROY
2013-03-15 10:38:51.503602 [DEBUG] mod_sofia.c:374 sofia/internal/ 557100060084@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@pxext.redvoiss.net Standard DESTROY
2013-03-15 10:38:51.503602 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/557100060084@pxext.redvoiss.net) State DESTROY going to sleep
Regards,
Camila
*From:* freeswitch-users-bounces@lists.freeswitch.org [mailto: freeswitch-users-bounces@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@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@redvoiss.net
Santiago - Chile *|* +56 2 2408535
www.redvoiss.net
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
participants (2)
-
Camila Troncoso
-
Steven Ayre