<html>
<head>
<style><!--
.hmmessage P
{
margin:0px;
padding:0px
}
body.hmmessage
{
font-size: 12pt;
font-family:Calibri
}
--></style></head>
<body class='hmmessage'><div dir='ltr'>I have a fresh Freeswitch + FusionPBX install, added extension 1000, the softphone registeres.<br><br>My test call to 9664 has no RTP in the "PBX -> softphone"direction (I can't hear the IVR). <br>I can see RTP in the reverse direction (the softclient -> PBX).<br>I opened all ports/protocols to my softclient IP on the PBX server firewall. <br><br>I am looking at pcaps on both servers (softclient and PBX) and they are similar (RTP to softclient is absent in both).<br><br>These are my Freeswitch logs, can you tell what I am doing wrong? I am trying to only configure through FusionPBX.<br>Thank you!<br>A.C.<br><br><br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [NOTICE] switch_ivr_originate.c:2670 Cannot create outgoing channel of type [user] cause: [SUBSCRIBER_ABSENT] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_ivr_originate.c:3657 Originate Resulted in Error Cause: 20 [SUBSCRIBER_ABSENT] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [INFO] mod_dptools.c:3201 Originate Failed. Cause: SUBSCRIBER_ABSENT <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000@10.50.1.57 answer() <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3449 AUDIO RTP [sofia/internal/1000@10.50.1.57] 10.50.1.57 port 27358 -> 10.50.1.151 port 49168 codec: 3 ms: 20 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_rtp.c:2040 Starting timer [soft] 160 bytes per 20ms <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3716 Set 2833 dtmf send payload to 101 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3722 Set 2833 dtmf receive payload to 101 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3749 sofia/internal/1000@10.50.1.57 Set rtp dtmf delay to 40 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] mod_sofia.c:866 Local SDP sofia/internal/1000@10.50.1.57: <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 v=0 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 o=FreeSWITCH 1397556071 1397556072 IN IP4 10.50.1.57 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 s=FreeSWITCH <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 c=IN IP4 10.50.1.57 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 t=0 0 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 m=audio 27358 RTP/AVP 3 101 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=rtpmap:3 GSM/8000 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=rtpmap:101 telephone-event/8000 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=fmtp:101 0-16 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=ptime:20 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=sendrecv <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000@10.50.1.57 [BREAK] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia.c:5815 Channel sofia/internal/1000@10.50.1.57 entering state [completed][200] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:871 Send signal sofia/internal/1000@10.50.1.57 [BREAK] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [NOTICE] mod_dptools.c:1225 Channel [sofia/internal/1000@10.50.1.57] has been answered <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000@10.50.1.57 [BREAK] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000@10.50.1.57 [BREAK] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000@10.50.1.57 [BREAK] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_channel.c:3647 (sofia/internal/1000@10.50.1.57) Callstate Change RINGING -> ACTIVE <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia.c:5815 Channel sofia/internal/1000@10.50.1.57 entering state [ready][200] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000@10.50.1.57 sleep(1000) <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.842988 [DEBUG] switch_rtp.c:3757 Correct ip/port confirmed. <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000@10.50.1.57 set(voicemail_action=save) <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:10.821249 [DEBUG] mod_dptools.c:1402 sofia/internal/1000@10.50.1.57 SET [voicemail_action]=[save] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000@10.50.1.57 set(voicemail_id=9664) <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:10.821249 [DEBUG] mod_dptools.c:1402 sofia/internal/1000@10.50.1.57 SET [voicemail_id]=[9664] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000@10.50.1.57 set(voicemail_profile=default) <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:10.821249 [DEBUG] mod_dptools.c:1402 sofia/internal/1000@10.50.1.57 SET [voicemail_profile]=[default] <br>9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000@10.50.1.57 lua(app.lua voicemail) <br>2014-04-15 10:37:10.821249 [DEBUG] freeswitch_lua.cpp:360 DBH handle 0x7f22c40c5e60 Connected. <br><div><br></div> </div></body>
</html>