[Freeswitch-users] RTP to caller not received

[Adelia C.]

I have a fresh Freeswitch + FusionPBX install, added extension 1000, the softphone registeres.

My test call to 9664 has no RTP in the "PBX -> softphone"direction (I can't hear the IVR). 
I can see RTP in the reverse direction (the softclient -> PBX).
I opened all ports/protocols to my softclient IP on the PBX server firewall. 

I am looking at pcaps on both servers (softclient and PBX) and they are similar (RTP to softclient is absent in both).

These are my Freeswitch logs, can you tell what I am doing wrong? I am trying to only configure through FusionPBX.
Thank you!
A.C.


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] 
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] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [INFO] mod_dptools.c:3201 Originate Failed. Cause: SUBSCRIBER_ABSENT 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000 at 10.50.1.57 answer() 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3449 AUDIO RTP [sofia/internal/1000 at 10.50.1.57] 10.50.1.57 port 27358 -> 10.50.1.151 port 49168 codec: 3 ms: 20 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_rtp.c:2040 Starting timer [soft] 160 bytes per 20ms 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3716 Set 2833 dtmf send payload to 101 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3722 Set 2833 dtmf receive payload to 101 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia_glue.c:3749 sofia/internal/1000 at 10.50.1.57 Set rtp dtmf delay to 40 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] mod_sofia.c:866 Local SDP sofia/internal/1000 at 10.50.1.57: 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 v=0 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 o=FreeSWITCH 1397556071 1397556072 IN IP4 10.50.1.57 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 s=FreeSWITCH 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 c=IN IP4 10.50.1.57 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 t=0 0 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 m=audio 27358 RTP/AVP 3 101 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=rtpmap:3 GSM/8000 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=rtpmap:101 telephone-event/8000 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=fmtp:101 0-16 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=ptime:20 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 a=sendrecv 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000 at 10.50.1.57 [BREAK] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia.c:5815 Channel sofia/internal/1000 at 10.50.1.57 entering state [completed][200] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:871 Send signal sofia/internal/1000 at 10.50.1.57 [BREAK] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [NOTICE] mod_dptools.c:1225 Channel [sofia/internal/1000 at 10.50.1.57] has been answered 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000 at 10.50.1.57 [BREAK] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000 at 10.50.1.57 [BREAK] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1000 at 10.50.1.57 [BREAK] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] switch_channel.c:3647 (sofia/internal/1000 at 10.50.1.57) Callstate Change RINGING -> ACTIVE 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.803038 [DEBUG] sofia.c:5815 Channel sofia/internal/1000 at 10.50.1.57 entering state [ready][200] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000 at 10.50.1.57 sleep(1000) 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:09.842988 [DEBUG] switch_rtp.c:3757 Correct ip/port confirmed. 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000 at 10.50.1.57 set(voicemail_action=save) 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:10.821249 [DEBUG] mod_dptools.c:1402 sofia/internal/1000 at 10.50.1.57 SET [voicemail_action]=[save] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000 at 10.50.1.57 set(voicemail_id=9664) 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:10.821249 [DEBUG] mod_dptools.c:1402 sofia/internal/1000 at 10.50.1.57 SET [voicemail_id]=[9664] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000 at 10.50.1.57 set(voicemail_profile=default) 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 2014-04-15 10:37:10.821249 [DEBUG] mod_dptools.c:1402 sofia/internal/1000 at 10.50.1.57 SET [voicemail_profile]=[default] 
9235e58e-c4c4-11e3-82c4-d3e7bac25629 EXECUTE sofia/internal/1000 at 10.50.1.57 lua(app.lua voicemail) 
2014-04-15 10:37:10.821249 [DEBUG] freeswitch_lua.cpp:360 DBH handle 0x7f22c40c5e60 Connected. 

 		 	   		  
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140415/c4fe6ffa/attachment-0001.html