[Freeswitch-users] can't hear audio when dialing 9198 (Tetris audio test)

Mon May 5 18:55:00 MSD 2014

There is more than 5060 that needs to be opened, see https://wiki.freeswitch.org/wiki/Firewall

Thanks,

Moishe Grunstein
Tornado Computer Systems, Inc.
212.400.7650 888.IPPBX.US
Service Request Email: support at nysolutions.com<mailto:support at nysolutions.com>
Polycom Certified VAR
Microsoft Small Business Specialist, Cisco SMB Select Certified
[cid:image001.jpg at 01C72F94.9EE45D60]<http://www.nysolutions.com/>
Computer Networking * Managed Services * IP Video Surveillance * Network Assessments * Web Solutions * Voice over IP * Disaster Recovery * Network Security * Site Surveys * CMS

From: freeswitch-users-bounces at lists.freeswitch.org [mailto:freeswitch-users-bounces at lists.freeswitch.org] On Behalf Of Steven Ayre
Sent: Monday, May 05, 2014 10:03 AM
To: FreeSWITCH Users Help
Subject: Re: [Freeswitch-users] can't hear audio when dialing 9198 (Tetris audio test)

Also check the IP in the logged "remote SDP" message (which is before what you're posted). Verify that it's an IP actually reachable from the server. A common problem is having a client that's behind NAT sending the LAN IP which isn't reachable over the internet, rather than it's external IP.

On 5 May 2014 14:55, Michael Jerris <mike at jerris.com<mailto:mike at jerris.com>> wrote:
do you have udp ports open for rtp?

On May 4, 2014, at 9:03 PM, Jeff Stark <jstark888 at gmail.com<mailto:jstark888 at gmail.com>> wrote:

Hi,

I installed FreeSwitch 1.2.23 on an Amazon EC2 instance (running Amazon Linux). I opened port 5060 for UDP to the server. I then registered as user 1005 using SipDroid on my phone via WiFi. At first when I dialed 9198, I could hear the Tetris music. I could also hear the music on hold audio (when I dialed 9664). However, the echo test failed. Therefore I tried changing the config for the server according to this: http://wiki.freeswitch.org/wiki/Amazon_EC2 However, after doing that not only did the echo test not work, but neither did the Tetris or music on hold tests. So I returned the config back to what it was originally. Unfortunately I still can't hear the Tetris or music on hold audio. I have tried using both LinPhone and XLite softphones as alternatives, but I can't hear the audio on those either. Signalling appears to work. I can dial an extension on a registered softphone and have another registered softphone ring, but there is no audio. I would appreciate any help or insight anyone has on what the problem might be. Also, I have tried using G722, PCMA, PCMU and GSM audio codecs, but none have worked. All the softphones I have tried are able to register without any noticeable problems.

Thanks.

Jeff Stark

Below is part of the log for a typical session where I can't hear the Tetris audio. (I have masked the IP addresses with descriptive labels):

84a7c5c8-d251-11e3-9565-7f053ccdf4d9 Dialplan: sofia/internal/1005@<FreeSwitch server Public IP> Regex (PASS) [tone_stream] destination_number(9198) =~ /^9198$/ break=on-false
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 Dialplan: sofia/internal/1005@<FreeSwitch server Public IP> Action answer()
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 Dialplan: sofia/internal/1005@<FreeSwitch server Public IP> Action playback({loops=10}tone_stream://path=${base_dir}/conf/tetris.ttml)
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/1005@<FreeSwitch server Public IP>) State Change CS_ROUTING -> CS_EXECUTE
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_state_machine.c:471 (sofia/internal/1005@<FreeSwitch server Public IP>) State ROUTING going to sleep
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1005@<FreeSwitch server Public IP>) Running State Change CS_EXECUTE
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_state_machine.c:478 (sofia/internal/1005@<FreeSwitch server Public IP>) State EXECUTE
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] mod_sofia.c:243 sofia/internal/1005@<FreeSwitch server Public IP> SOFIA EXECUTE
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_state_machine.c:209 sofia/internal/1005@<FreeSwitch server Public IP> Standard EXECUTE
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 EXECUTE sofia/internal/1005@<FreeSwitch server Public IP> hash(insert/<FreeSwitch server Private IP>-spymap/1005/84a7c5c8-d251-11e3-9565-7f053ccdf4d9)
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 EXECUTE sofia/internal/1005@<FreeSwitch server Public IP> hash(insert/<FreeSwitch server Private IP>-last_dial/1005/9198)
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 EXECUTE sofia/internal/1005@<FreeSwitch server Public IP> hash(insert/<FreeSwitch server Private IP>-last_dial/global/84a7c5c8-d251-11e3-9565-7f053ccdf4d9)
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 EXECUTE sofia/internal/1005@<FreeSwitch server Public IP> export(RFC2822_DATE=Fri, 02 May 2014 23:28:51 +0000)
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_channel.c:1242 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 02 May 2014 23:28:51 +0000]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 EXECUTE sofia/internal/1005@<FreeSwitch server Public IP> answer()
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [G722:9:8000:20:64000]/[G722:9:8000:20:64000]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia_glue.c:3190 Set Codec sofia/internal/1005@<FreeSwitch server Public IP> G722/8000 20 ms 160 samples 64000 bits
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_codec.c:111 sofia/internal/1005@<FreeSwitch server Public IP> Original read codec set to G722:9
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia_glue.c:5451 Set 2833 dtmf send/recv payload to 101
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia_glue.c:3449 AUDIO RTP [sofia/internal/1005@<FreeSwitch server Public IP>] <FreeSwitch server Private IP> port 23728 -> <Softphone Public IP> port 50062 codec: 9 ms: 20
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_rtp.c:2040 Starting timer [soft] 160 bytes per 20ms
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia_glue.c:3716 Set 2833 dtmf send payload to 101
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia_glue.c:3722 Set 2833 dtmf receive payload to 101
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia_glue.c:3749 sofia/internal/1005@<FreeSwitch server Public IP> Set rtp dtmf delay to 40
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [NOTICE] sofia_glue.c:4360 Pre-Answer sofia/internal/1005@<FreeSwitch server Public IP>!
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_channel.c:3368 (sofia/internal/1005@<FreeSwitch server Public IP>) Callstate Change RINGING -> EARLY
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] mod_sofia.c:866 Local SDP sofia/internal/1005@<FreeSwitch server Public IP>:
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 v=0
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 o=FreeSWITCH 1399049603 1399049604 IN IP4 <FreeSwitch server Private IP>
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 s=FreeSWITCH
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 c=IN IP4 <FreeSwitch server Private IP>
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 t=0 0
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 m=audio 23728 RTP/AVP 9 101
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 a=rtpmap:9 G722/8000
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 a=rtpmap:101 telephone-event/8000
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 a=fmtp:101 0-16
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 a=ptime:20
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 a=sendrecv
84a7c5c8-d251-11e3-9565-7f053ccdf4d9
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] sofia.c:5815 Channel sofia/internal/1005@<FreeSwitch server Public IP> entering state [completed][200]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_core_session.c:871 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [NOTICE] mod_dptools.c:1225 Channel [sofia/internal/1005@<FreeSwitch server Public IP>] has been answered
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_channel.c:3647 (sofia/internal/1005@<FreeSwitch server Public IP>) Callstate Change EARLY -> ACTIVE
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 EXECUTE sofia/internal/1005@<FreeSwitch server Public IP> playback({loops=10}tone_stream://path=/usr/local/freeswitch/conf/tetris.ttml)
2014-05-02 23:28:51.111375 [DEBUG] switch_event.c:1661 Parsing variable [loops]=[10]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.111375 [DEBUG] switch_ivr_play_say.c:1314 Codec Activated L16 at 16000hz 1 channels 20ms
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.331419 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.331419 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.331419 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:28:51.331419 [DEBUG] sofia.c:5815 Channel sofia/internal/1005@<FreeSwitch server Public IP> entering state [ready][200]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.331345 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [NOTICE] sofia.c:737 Hangup sofia/internal/1005@<FreeSwitch server Public IP> [CS_EXECUTE] [NORMAL_CLEARING]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_channel.c:3187 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [KILL]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/1005@<FreeSwitch server Public IP> [BREAK]
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_ivr_play_say.c:1717 done playing file {loops=10}tone_stream://path=/usr/local/freeswitch/conf/tetris.ttml
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_core_session.c:2810 sofia/internal/1005@<FreeSwitch server Public IP> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_core_state_machine.c:478 (sofia/internal/1005@<FreeSwitch server Public IP>) State EXECUTE going to sleep
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1005@<FreeSwitch server Public IP>) Running State Change CS_HANGUP
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/1005@<FreeSwitch server Public IP>) Callstate Change ACTIVE -> HANGUP
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_core_state_machine.c:680 (sofia/internal/1005@<FreeSwitch server Public IP>) State HANGUP
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] mod_sofia.c:506 Channel sofia/internal/1005@<FreeSwitch server Public IP> hanging up, cause: NORMAL_CLEARING
84a7c5c8-d251-11e3-9565-7f053ccdf4d9 2014-05-02 23:29:31.351377 [DEBUG] switch_core_state_machine.c:48 sofia/internal/1005@<FreeSwitch server Public IP> Standard HANGUP, cause: NORMAL_CLEARING

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting at freeswitch.org<mailto:consulting at freeswitch.org>
http://www.freeswitchsolutions.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

FreeSWITCH-users mailing list
FreeSWITCH-users at lists.freeswitch.org<mailto:FreeSWITCH-users at lists.freeswitch.org>
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140505/599e26e4/attachment-0001.html 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: image001.jpg
Type: image/jpeg
Size: 2424 bytes
Desc: image001.jpg
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140505/599e26e4/attachment-0001.jpg 