<div dir="ltr">Meanwhile, I'm analyzing the logs myself and here's something that looks suspicious<div><br></div><div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">2013-07-03 11:14:05.026852 [DEBUG] sofia_glue.c:1220 Local SDP:</div>
<div style="font-family:arial,sans-serif;font-size:12.800000190734863px">v=0</div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">o=FreeSWITCH 1372826251 1372826252 IN IP4 <b>192.168.119.50</b></div>
<div style="font-family:arial,sans-serif;font-size:12.800000190734863px">s=FreeSWITCH</div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">c=IN IP4 <b>192.168.119.50</b></div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">
t=0 0</div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">m=audio 16594 RTP/AVP 0 <a href="tel:8%203%20101%2013" value="+488310113" target="_blank">8 3 101 13</a></div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">
a=rtpmap:101 telephone-event/8000</div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">a=fmtp:101 0-16</div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">a=ptime:20</div>
<div style="font-family:arial,sans-serif;font-size:12.800000190734863px">a=sendrecv</div></div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px"><br></div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">
In the SDP message there's a local freeswitch machine IP (192.168.119.50), but this IP is private, invisible to the outside world. The machine is in a DMZ, accessible through a public IP 89.75.189.46. So, maybe this is the problem - SDP information sent to the freeconet gateway contains an incorrect IP address for audio stream? If so, could you tell me how to configure a correct 'public' IP for FS?</div>
<div style="font-family:arial,sans-serif;font-size:12.800000190734863px">Thanks</div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">RG</div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px">
<br></div><div style="font-family:arial,sans-serif;font-size:12.800000190734863px"><br></div></div><div class="gmail_extra"><br><br><div class="gmail_quote">On Wed, Jul 3, 2013 at 11:23 AM, Rafal Gwizdala <span dir="ltr"><<a href="mailto:rafal.gwizdala@gmail.com" target="_blank">rafal.gwizdala@gmail.com</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div><br></div><div>Hi, I'm trying to originate a call to an external endpoint (a mobile phone) through a gateway (<a href="http://freeconet.pl" target="_blank">freeconet.pl</a>) service and I'm getting intermittent 'NORMAL_UNSPECIFIED'</div>
<div>errors. The command will randomly succeed or fail with the following result.</div><div><br></div><div>originate {call_timeout=60,origination_uuid=d399361f-56a1-4c7c-acd8-447a6e1ea742}sofia/gateway/freeconet/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a> 6668</div>
<div>Event: FAILURE, Body: -ERR NORMAL_UNSPECIFIED</div><div><br></div><div><br></div><div>The destination extension (6668) is an IVR service based on mod_httapi, but I don't think this is related to my application as there are no httapi calls made. The 'freeconet' gateway is REGED.</div>
<div>Corresponding freeswitch log is below. Can you please help me figure out what's going on?<br></div><div><br></div><div>Best regards</div><div>Rafal</div><div><br></div><div>2013-07-03 11:14:05.016851 [DEBUG] switch_ivr_originate.c:2050 Parsing global variables</div>
<div>2013-07-03 11:14:05.016851 [DEBUG] switch_event.c:1617 Parsing variable [call_timeout]=[60]</div><div>2013-07-03 11:14:05.016851 [DEBUG] switch_event.c:1617 Parsing variable [origination_uuid]=[d399361f-56a1-4c7c-acd8-447a6e1ea742]</div>
<div>2013-07-03 11:14:05.016851 [NOTICE] switch_channel.c:1030 New Channel sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a> [d399361f-56a1-4c7c-acd8-447a6e1ea742]</div><div>2013-07-03 11:14:05.016851 [DEBUG] mod_sofia.c:4420 (sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a>) State Change CS_NEW -> CS_INIT</div>
<div>2013-07-03 11:14:05.016851 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a> [BREAK]</div><div>2013-07-03 11:14:05.016851 [DEBUG] switch_core_session.c:583 sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a> set UUID=d399361f-56a1-4c7c-acd8-447a6e1ea742</div>
<div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:416 (sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a>) Running State Change CS_INIT</div><div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:455 (sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a>) State INIT</div>
<div>2013-07-03 11:14:05.026852 [DEBUG] mod_sofia.c:87 sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a> SOFIA INIT</div><div>2013-07-03 11:14:05.026852 [DEBUG] sofia_glue.c:1220 Local SDP:</div>
<div>v=0</div><div>o=FreeSWITCH 1372826251 1372826252 IN IP4 192.168.119.50</div>
<div>s=FreeSWITCH</div><div>c=IN IP4 192.168.119.50</div><div>t=0 0</div><div>m=audio 16594 RTP/AVP 0 <a href="tel:8%203%20101%2013" value="+488310113" target="_blank">8 3 101 13</a></div><div>a=rtpmap:101 telephone-event/8000</div>
<div>a=fmtp:101 0-16</div><div>a=ptime:20</div><div>a=sendrecv</div>
<div><br></div><div>2013-07-03 11:14:05.026852 [DEBUG] mod_sofia.c:114 (sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a>) State Change CS_INIT -> CS_ROUTING</div><div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a> [BREAK]</div>
<div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:455 (sofia/external/<a href="tel:602415455" value="+48602415455" target="_blank">602415455</a>) State INIT going to sleep</div><div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:416 (sofia/external/602415455) Running State Change CS_ROUTING</div>
<div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:471 (sofia/external/602415455) State ROUTING</div><div>2013-07-03 11:14:05.026852 [DEBUG] mod_sofia.c:137 sofia/external/602415455 SOFIA ROUTING</div><div>
2013-07-03 11:14:05.026852 [DEBUG] switch_ivr_originate.c:67 (sofia/external/602415455) State Change CS_ROUTING -> CS_CONSUME_MEDIA</div><div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/602415455 [BREAK]</div>
<div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:471 (sofia/external/602415455) State ROUTING going to sleep</div><div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:416 (sofia/external/602415455) Running State Change CS_CONSUME_MEDIA</div>
<div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:490 (sofia/external/602415455) State CONSUME_MEDIA</div><div>2013-07-03 11:14:05.026852 [DEBUG] switch_core_state_machine.c:490 (sofia/external/602415455) State CONSUME_MEDIA going to sleep</div>
<div>2013-07-03 11:14:05.036853 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/602415455 [BREAK]</div><div>2013-07-03 11:14:05.036853 [DEBUG] sofia.c:5764 Channel sofia/external/602415455 entering state [calling][0]</div>
<div>2013-07-03 11:14:05.096861 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/602415455 [BREAK]</div><div>2013-07-03 11:14:05.096861 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/602415455 [BREAK]</div>
<div>2013-07-03 11:14:05.096861 [DEBUG] sofia.c:5764 Channel sofia/external/602415455 entering state [calling][0]</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/602415455 [BREAK]</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/602415455 [BREAK]</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/602415455 [BREAK]</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1006 Send signal sofia/external/602415455 [BREAK]</div><div>2013-07-03 11:14:05.126865 [DEBUG] sofia.c:5764 Channel sofia/external/602415455 entering state [terminated][904]</div>
<div>2013-07-03 11:14:05.126865 [NOTICE] sofia.c:6572 Hangup sofia/external/602415455 [CS_CONSUME_MEDIA] [NORMAL_UNSPECIFIED]</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_channel.c:3135 Send signal sofia/external/602415455 [KILL]</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/602415455 [BREAK]</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:416 (sofia/external/602415455) Running State Change CS_HANGUP</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:679 (sofia/external/602415455) State HANGUP</div><div>2013-07-03 11:14:05.126865 [DEBUG] mod_sofia.c:463 Channel sofia/external/602415455 hanging up, cause: NORMAL_UNSPECIFIED</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:48 sofia/external/602415455 Standard HANGUP, cause: NORMAL_UNSPECIFIED</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:679 (sofia/external/602415455) State HANGUP going to sleep</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:692 (sofia/external/602415455) Callstate Change DOWN -> HANGUP</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:447 (sofia/external/602415455) State Change CS_HANGUP -> CS_REPORTING</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/602415455 [BREAK]</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:416 (sofia/external/602415455) Running State Change CS_REPORTING</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:764 (sofia/external/602415455) State REPORTING</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:92 sofia/external/602415455 Standard REPORTING, cause: NORMAL_UNSPECIFIED</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:764 (sofia/external/602415455) State REPORTING going to sleep</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_state_machine.c:441 (sofia/external/602415455) State Change CS_REPORTING -> CS_DESTROY</div>
<div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1341 Send signal sofia/external/602415455 [BREAK]</div><div>2013-07-03 11:14:05.126865 [DEBUG] switch_core_session.c:1549 Session 85 (sofia/external/602415455) Locked, Waiting on external entities</div>
<div>2013-07-03 11:14:05.146867 [DEBUG] switch_ivr_originate.c:3622 Originate Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED]</div><div>2013-07-03 11:14:05.146867 [NOTICE] switch_core_session.c:1567 Session 85 (sofia/external/602415455) Ended</div>
<div>2013-07-03 11:14:05.146867 [NOTICE] switch_core_session.c:1571 Close Channel sofia/external/602415455 [CS_DESTROY]</div><div>2013-07-03 11:14:05.146867 [DEBUG] switch_core_state_machine.c:566 (sofia/external/602415455) Callstate Change HANGUP -> DOWN</div>
<div>2013-07-03 11:14:05.146867 [DEBUG] switch_core_state_machine.c:569 (sofia/external/602415455) Running State Change CS_DESTROY</div><div>2013-07-03 11:14:05.146867 [DEBUG] switch_core_state_machine.c:579 (sofia/external/602415455) State DESTROY</div>
<div>2013-07-03 11:14:05.146867 [DEBUG] mod_sofia.c:373 sofia/external/602415455 SOFIA DESTROY</div><div>2013-07-03 11:14:05.146867 [DEBUG] switch_core_state_machine.c:99 sofia/external/602415455 Standard DESTROY</div><div>
2013-07-03 11:14:05.146867 [DEBUG] switch_core_state_machine.c:579 (sofia/external/602415455) State DESTROY going to sleep</div><div><br></div></div>
</blockquote></div><br></div>