Play of already encoded G729 audio files

193 views
Skip to first unread message

Tronch

unread,
Jun 15, 2014, 1:06:23 PM6/15/14
to adhea...@googlegroups.com
Hi.

I'm using Adhearsion 2.5.3 with Freeswitch (latest master).

In order to avoid transcoding (and cpu load), I would like to pre-encode my wav files to G729 codec.

I encoded the files using the tool provided with freeswitch (fs_encode).

Using a Freeswitch dial plan application I hear the file correctly (it has a G729 extension).

In Adhearsion, when I call the controller play method, I hear a corrupted audio file.

It seems that Adhearsion, via ssml, is saying to Freeswitch that the audio file has a different encoding (I suspect wav) than G729.

Note: if I call the play method with the wav file (not the encoded one), the file is correctly encoded at runtime (which is what I want to avoid).

What am I doing wrong? Or what I want to do is not possible?

Thank you for your help,
Matt.

Ben Klang

unread,
Jun 15, 2014, 1:08:35 PM6/15/14
to adhea...@googlegroups.com
Adhearsion doesn’t tell FreeSWITCH anything about the encoding of the audio file, it simply passes a path to that file.

Can you provide TRACE level logs where the problem occurs?

/BAK/
-- 
Ben Klang
Principal/Technology Strategist, Mojo Lingo

Mojo Lingo -- Voice applications that work like magic
Twitter: @MojoLingo

--
You received this message because you are subscribed to the Google Groups "Adhearsion" group.
To unsubscribe from this group and stop receiving emails from it, send an email to adhearsion+...@googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

signature.asc

Tronch

unread,
Jun 15, 2014, 1:36:46 PM6/15/14
to adhea...@googlegroups.com
Hi Ben.

Here it's the relevant log part:

[2014-06-15 12:20:32.417] DEBUG Adhearsion::Call: e2625604-edcd-4da0...@127.0.0.1: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, request_id="10d4e90a-86cd-461e-acf0-c90696fa11f0", voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Punchblock::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>>]>
[2014-06-15 12:20:32.421] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-1.9.3-p547/gems/blather-1.0.0/lib/blather/client/client.rb:149:in `write') <iq type="set" to="e2625604-edcd-4da0...@127.0.0.1" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <output xmlns="urn:xmpp:rayo:output:1">
    <document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document>
  </output>
</iq>
[2014-06-15 12:20:32.443] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="e2625604-edcd-4da0...@127.0.0.1" to="us...@127.0.0.1" type="result" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <ref xmlns="urn:xmpp:rayo:1" uri="xmpp:e2625604-edcd-4da0...@127.0.0.1/output-1"/>
</iq>

As you can see from the last two rows, the play lasts for only few milliseconds but the audio file is several seconds long.
Could it be a bug of Freeswitch mod_ssml?

Thank you,
Matt.



Adhearsion doesn’t tell FreeSWITCH anything about the encoding of the audio file, it simply passes a path to that file.

Can you provide TRACE level logs where the problem occurs?

/BAK/
-- 
Ben Klang
Principal/Technology Strategist, Mojo Lingo

Mojo Lingo -- Voice applications that work like magic
Twitter: @MojoLingo

James Le Cuirot

unread,
Jun 15, 2014, 1:48:01 PM6/15/14
to adhea...@googlegroups.com
On Sun, 15 Jun 2014 10:36:46 -0700 (PDT)
Tronch <matteo.t...@gmail.com> wrote:

>   <output xmlns="urn:xmpp:rayo:output:1">
>     <document content-type="application/ssml+xml"><![CDATA[<speak
> xmlns="http://www.w3.org/2001/10/synthesis" version="1.0"
> xml:lang="en-US"> <audio src="welcome.G729"/> </speak>]]></document>
>   </output>

Could it simply be that the file is actually named welcome.g729 and not
welcome.G729? Most filesystems are case-sensitive.

Ben Langfeld

unread,
Jun 15, 2014, 2:38:55 PM6/15/14
to adhea...@googlegroups.com
It would additionally be helpful to provide logs from FreeSWITCH. These will be more informative than Adhearsion logs.

Em 15/06/2014, às 14:36, Tronch <matteo.t...@gmail.com> escreveu:

Hi Ben.

Here it's the relevant log part:

[2014-06-15 12:20:32.417] DEBUG Adhearsion::Call: e2625604-edcd-4da0...@127.0.0.1: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, request_id="10d4e90a-86cd-461e-acf0-c90696fa11f0", voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Punchblock::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>>]>
[2014-06-15 12:20:32.421] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-1.9.3-p547/gems/blather-1.0.0/lib/blather/client/client.rb:149:in `write') <iq type="set" to="e2625604-edcd-4da0...@127.0.0.1" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <output xmlns="urn:xmpp:rayo:output:1">
    <document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document>
  </output>
</iq>
[2014-06-15 12:20:32.443] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="e2625604-edcd-4da0...@127.0.0.1" to="us...@127.0.0.1" type="result" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <ref xmlns="urn:xmpp:rayo:1" uri="xmpp:e2625604-edcd-4da0-b1b...@127.0.0.1/output-1"/>
</iq>

Tronch

unread,
Jun 16, 2014, 4:48:24 AM6/16/14
to adhea...@googlegroups.com
Hi Ben.

Here are the freeswitch logs:

2014-06-15 12:55:59.531623 [DEBUG] xmpp_streams.c:190 us...@127.0.0.1, 127.0.0.1:52319, c2s_in RECV <iq type="set" to="c576eaf8-e7a2-48a4...@127.0.0.1" id="40fc0650-562e-4ac6-9a3f-ae509b5c187a"><output xmlns="urn:xmpp:rayo:output:1"><document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document></output></iq>
2014-06-15 12:55:59.531623 [DEBUG] xmpp_streams.c:1058 us...@127.0.0.1, 127.0.0.1:52319, state = READY, node type = NODE_NORMAL
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3858 Lock us...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3054 us...@127.0.0.1, recv iq, availability = ONLINE
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3929 Unlock us...@127.0.0.1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:771 Locate (jid) c576eaf8-e7a2-48a4...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3057 Deliver us...@127.0.0.1 => c576eaf8-e7a2-48a4...@127.0.0.1 <iq type='set' to='c576eaf8-e7a2-48a4...@127.0.0.1' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a' from='us...@127.0.0.1'><output xmlns='urn:xmpp:rayo:output:1'><document content-type='application/ssml+xml'>&lt;speak xmlns=&quot;http://www.w3.org/2001/10/synthesis&quot; version=&quot;1.0&quot; xml:lang=&quot;en-US&quot;&gt;
  &lt;audio src=&quot;welcome.G729&quot;/&gt;
&lt;/speak&gt;</document></output></iq>
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:652 c576eaf8-e7a2-48a4...@127.0.0.1, looking for CALL::set:urn:xmpp:rayo:output:1:output command
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1806 c576eaf8-e7a2-48a4...@127.0.0.1, executing command
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:71 Init c576eaf8-e7a2-48a4...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1435 Lock c576eaf8-e7a2-48a4...@127.0.0.1: ref count = 3
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:771 Locate (jid) us...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] rayo_components.c:62 Deliver c576eaf8-e7a2-48a4...@127.0.0.1/output-1 => us...@127.0.0.1 <iq from='c576eaf8-e7a2-48a4...@127.0.0.1' to='us...@127.0.0.1' type='result' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:c576eaf8-e7a2-48a4...@127.0.0.1/output-1'/></iq>
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [id]=[c576eaf8-e7a2-48a4...@127.0.0.1/output-1]
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [session]=[c576eaf8-e7a2-48a4-bfe0-f56cfa3f3244]
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock us...@127.0.0.1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [pause]=[false]
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:689 Got path rayo://c576eaf8-e7a2-48a4...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:498 Got path c576eaf8-e7a2-48a4...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:500 Locate (id) c576eaf8-e7a2-48a4...@127.0.0.1/output-1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:812 Open: <speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:597 Adding <audio>: "welcome.G729"
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-06-15 12:55:59.531623 [INFO] mod_native_file.c:94 Opening File [welcome.G729] 8000hz
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:713 Create audio buffer
2014-06-15 12:55:59.531623 [DEBUG] switch_core_media_bug.c:532 Attaching BUG to sofia/internal/1122334455
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:115 Unlock c576eaf8-e7a2-48a4...@127.0.0.1/output-1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1808 c576eaf8-e7a2-48a4...@127.0.0.1, done executing command
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock c576eaf8-e7a2-48a4...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.551666 [DEBUG] xmpp_streams.c:190 us...@127.0.0.1, 127.0.0.1:52319, c2s_in SEND <iq from='c576eaf8-e7a2-48a4...@127.0.0.1' to='us...@127.0.0.1' type='result' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:c576eaf8-e7a2-48a4...@127.0.0.1/output-1'/></iq>
2014-06-15 12:55:59.591630 [DEBUG] switch_rtp.c:5574 Correct ip/port confirmed.
2014-06-15 12:55:59.591630 [DEBUG] switch_core_io.c:526 Setting BUG Codec G729:18
2014-06-15 12:56:00.391623 [INFO] rayo_output_component.c:414 Done playing
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:117 Unlock c576eaf8-e7a2-48a4...@127.0.0.1/output-1: ref count = 0
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroy c576eaf8-e7a2-48a4...@127.0.0.1/output-1 requested: ref_count = 0
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroying c576eaf8-e7a2-48a4...@127.0.0.1/output-1
2014-06-15 12:56:00.391623 [DEBUG] mod_rayo.c:1410 Unlock c576eaf8-e7a2-48a4...@127.0.0.1: ref count = 1

Thank you,
Matt.



It would additionally be helpful to provide logs from FreeSWITCH. These will be more informative than Adhearsion logs.

Em 15/06/2014, às 14:36, Tronch <matteo.t...@gmail.com> escreveu:

Hi Ben.

Here it's the relevant log part:

[2014-06-15 12:20:32.417] DEBUG Adhearsion::Call: e2625604-edcd-4da0-b1bf-9d2f97...@127.0.0.1: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, request_id="10d4e90a-86cd-461e-acf0-c90696fa11f0", voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Punchblock::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>>]>
[2014-06-15 12:20:32.421] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-1.9.3-p547/gems/blather-1.0.0/lib/blather/client/client.rb:149:in `write') <iq type="set" to="e2625604-edcd-4da0-b1bf-9d2f97...@127.0.0.1" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <output xmlns="urn:xmpp:rayo:output:1">
    <document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document>
  </output>
</iq>
[2014-06-15 12:20:32.443] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="e2625604-edcd-4da0-b1bf-9d2f97...@127.0.0.1" to="us...@127.0.0.1" type="result" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <ref xmlns="urn:xmpp:rayo:1" uri="xmpp:e2625604-edcd-4da0-b1bf-9d2...@127.0.0.1/output-1"/>
</iq>

Ben Langfeld

unread,
Jun 16, 2014, 6:41:05 AM6/16/14
to adhea...@googlegroups.com
Nothing looks particularly wrong in that log. I'd suggest you file a ticket against FreeSWITCH mod_rayo to query it: http://jira.freeswitch.org

Christopher Rienzo

unread,
Jun 16, 2014, 9:17:56 AM6/16/14
to adhea...@googlegroups.com
There's probably a bug in mod_rayo / native file playback since it's something I've never used.  Should be simple enough to fix, just open a ticket as Ben recommended so I don't forget.

Tronch

unread,
Jun 16, 2014, 11:48:24 AM6/16/14
to adhea...@googlegroups.com

Hi Christopher.

I opened ticket number FS-6598.

Thank you
Matt.


There's probably a bug in mod_rayo / native file playback since it's something I've never used.  Should be simple enough to fix, just open a ticket as Ben recommended so I don't forget.

On Mon, Jun 16, 2014 at 6:41 AM, Ben Langfeld <b...@langfeld.me> wrote:
Nothing looks particularly wrong in that log. I'd suggest you file a ticket against FreeSWITCH mod_rayo to query it: http://jira.freeswitch.org
On 16 June 2014 05:48, Tronch <matteo.t...@gmail.com> wrote:
Hi Ben.

Here are the freeswitch logs:

2014-06-15 12:55:59.531623 [DEBUG] xmpp_streams.c:190 us...@127.0.0.1, 127.0.0.1:52319, c2s_in RECV <iq type="set" to="c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1" id="40fc0650-562e-4ac6-9a3f-ae509b5c187a"><output xmlns="urn:xmpp:rayo:output:1"><document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document></output></iq>
2014-06-15 12:55:59.531623 [DEBUG] xmpp_streams.c:1058 us...@127.0.0.1, 127.0.0.1:52319, state = READY, node type = NODE_NORMAL
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3858 Lock us...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3054 us...@127.0.0.1, recv iq, availability = ONLINE
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3929 Unlock us...@127.0.0.1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:771 Locate (jid) c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3057 Deliver us...@127.0.0.1 => c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1 <iq type='set' to='c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a' from='us...@127.0.0.1'><output xmlns='urn:xmpp:rayo:output:1'><document content-type='application/ssml+xml'>&lt;speak xmlns=&quot;http://www.w3.org/2001/10/synthesis&quot; version=&quot;1.0&quot; xml:lang=&quot;en-US&quot;&gt;
  &lt;audio src=&quot;welcome.G729&quot;/&gt;
&lt;/speak&gt;</document></output></iq>
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:652 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, looking for CALL::set:urn:xmpp:rayo:output:1:output command
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1806 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, executing command
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:71 Init c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1435 Lock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 3
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:771 Locate (jid) us...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] rayo_components.c:62 Deliver c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1 => us...@127.0.0.1 <iq from='c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1' to='us...@127.0.0.1' type='result' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:c576eaf8-e7a2-48a4-bfe0-f56...@127.0.0.1/output-1'/></iq>
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [id]=[c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1]
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [session]=[c576eaf8-e7a2-48a4-bfe0-f56cfa3f3244]
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock us...@127.0.0.1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [pause]=[false]
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:689 Got path rayo://c576eaf8-e7a2-48a4-bfe0-f56...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:498 Got path c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:500 Locate (id) c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:812 Open: <speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:597 Adding <audio>: "welcome.G729"
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-06-15 12:55:59.531623 [INFO] mod_native_file.c:94 Opening File [welcome.G729] 8000hz
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:713 Create audio buffer
2014-06-15 12:55:59.531623 [DEBUG] switch_core_media_bug.c:532 Attaching BUG to sofia/internal/1122334455
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:115 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1808 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, done executing command
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.551666 [DEBUG] xmpp_streams.c:190 us...@127.0.0.1, 127.0.0.1:52319, c2s_in SEND <iq from='c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1' to='us...@127.0.0.1' type='result' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:c576eaf8-e7a2-48a4-bfe0-f56...@127.0.0.1/output-1'/></iq>
2014-06-15 12:55:59.591630 [DEBUG] switch_rtp.c:5574 Correct ip/port confirmed.
2014-06-15 12:55:59.591630 [DEBUG] switch_core_io.c:526 Setting BUG Codec G729:18
2014-06-15 12:56:00.391623 [INFO] rayo_output_component.c:414 Done playing
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:117 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1: ref count = 0
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroy c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1 requested: ref_count = 0
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroying c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1
2014-06-15 12:56:00.391623 [DEBUG] mod_rayo.c:1410 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 1

Thank you,
Matt.



It would additionally be helpful to provide logs from FreeSWITCH. These will be more informative than Adhearsion logs.

Em 15/06/2014, às 14:36, Tronch <matteo.t...@gmail.com> escreveu:

Hi Ben.

Here it's the relevant log part:

[2014-06-15 12:20:32.417] DEBUG Adhearsion::Call: e2625604-edcd-4da0-b1bf-9d2f97293...@127.0.0.1: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, request_id="10d4e90a-86cd-461e-acf0-c90696fa11f0", voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Punchblock::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>>]>
[2014-06-15 12:20:32.421] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-1.9.3-p547/gems/blather-1.0.0/lib/blather/client/client.rb:149:in `write') <iq type="set" to="e2625604-edcd-4da0-b1bf-9d2f972...@127.0.0.1" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <output xmlns="urn:xmpp:rayo:output:1">
    <document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document>
  </output>
</iq>
[2014-06-15 12:20:32.443] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="e2625604-edcd-4da0-b1bf-9d2f97...@127.0.0.1" to="us...@127.0.0.1" type="result" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <ref xmlns="urn:xmpp:rayo:1" uri="xmpp:e2625604-edcd-4da0-b1bf-9d2f...@127.0.0.1/output-1"/>
</iq>

Giorgio Bianchi

unread,
Mar 20, 2017, 11:23:52 AM3/20/17
to Adhearsion
Hi all,
I'm trying this solution too but the problem seems not solved yet.
Is there something I could do to solve it and use native G729 files?
Thanks for your support!
Giorgio.


Il giorno lunedì 16 giugno 2014 15:17:56 UTC+2, Christopher Rienzo ha scritto:
There's probably a bug in mod_rayo / native file playback since it's something I've never used.  Should be simple enough to fix, just open a ticket as Ben recommended so I don't forget.

On Mon, Jun 16, 2014 at 6:41 AM, Ben Langfeld <b...@langfeld.me> wrote:
Nothing looks particularly wrong in that log. I'd suggest you file a ticket against FreeSWITCH mod_rayo to query it: http://jira.freeswitch.org
On 16 June 2014 05:48, Tronch <matteo.t...@gmail.com> wrote:
Hi Ben.

Here are the freeswitch logs:

2014-06-15 12:55:59.531623 [DEBUG] xmpp_streams.c:190 us...@127.0.0.1, 127.0.0.1:52319, c2s_in RECV <iq type="set" to="c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1" id="40fc0650-562e-4ac6-9a3f-ae509b5c187a"><output xmlns="urn:xmpp:rayo:output:1"><document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document></output></iq>
2014-06-15 12:55:59.531623 [DEBUG] xmpp_streams.c:1058 us...@127.0.0.1, 127.0.0.1:52319, state = READY, node type = NODE_NORMAL
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3858 Lock us...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3054 us...@127.0.0.1, recv iq, availability = ONLINE
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3929 Unlock us...@127.0.0.1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:771 Locate (jid) c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3057 Deliver us...@127.0.0.1 => c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1 <iq type='set' to='c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a' from='us...@127.0.0.1'><output xmlns='urn:xmpp:rayo:output:1'><document content-type='application/ssml+xml'>&lt;speak xmlns=&quot;http://www.w3.org/2001/10/synthesis&quot; version=&quot;1.0&quot; xml:lang=&quot;en-US&quot;&gt;
  &lt;audio src=&quot;welcome.G729&quot;/&gt;
&lt;/speak&gt;</document></output></iq>
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:652 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, looking for CALL::set:urn:xmpp:rayo:output:1:output command
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1806 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, executing command
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:71 Init c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1435 Lock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 3
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:771 Locate (jid) us...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] rayo_components.c:62 Deliver c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1 => us...@127.0.0.1 <iq from='c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1' to='us...@127.0.0.1' type='result' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:c576eaf8-e7a2-48a4-bfe0-f56...@127.0.0.1/output-1'/></iq>
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [id]=[c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1]
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [session]=[c576eaf8-e7a2-48a4-bfe0-f56cfa3f3244]
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock us...@127.0.0.1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] switch_event.c:1687 Parsing variable [pause]=[false]
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:689 Got path rayo://c576eaf8-e7a2-48a4-bfe0-f56...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:498 Got path c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:500 Locate (id) c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1: ref count = 2
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:812 Open: <speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:597 Adding <audio>: "welcome.G729"
2014-06-15 12:55:59.531623 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-06-15 12:55:59.531623 [INFO] mod_native_file.c:94 Opening File [welcome.G729] 8000hz
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:713 Create audio buffer
2014-06-15 12:55:59.531623 [DEBUG] switch_core_media_bug.c:532 Attaching BUG to sofia/internal/1122334455
2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:115 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1: ref count = 1
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1808 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, done executing command
2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 2
2014-06-15 12:55:59.551666 [DEBUG] xmpp_streams.c:190 us...@127.0.0.1, 127.0.0.1:52319, c2s_in SEND <iq from='c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1' to='us...@127.0.0.1' type='result' id='40fc0650-562e-4ac6-9a3f-ae509b5c187a'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:c576eaf8-e7a2-48a4-bfe0-f56...@127.0.0.1/output-1'/></iq>
2014-06-15 12:55:59.591630 [DEBUG] switch_rtp.c:5574 Correct ip/port confirmed.
2014-06-15 12:55:59.591630 [DEBUG] switch_core_io.c:526 Setting BUG Codec G729:18
2014-06-15 12:56:00.391623 [INFO] rayo_output_component.c:414 Done playing
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:117 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1: ref count = 0
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroy c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1 requested: ref_count = 0
2014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroying c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-1
2014-06-15 12:56:00.391623 [DEBUG] mod_rayo.c:1410 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 1

Thank you,
Matt.



It would additionally be helpful to provide logs from FreeSWITCH. These will be more informative than Adhearsion logs.

Em 15/06/2014, às 14:36, Tronch <matteo.t...@gmail.com> escreveu:

Hi Ben.

Here it's the relevant log part:

[2014-06-15 12:20:32.417] DEBUG Adhearsion::Call: e2625604-edcd-4da0-b1bf-9d2f97293...@127.0.0.1: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, request_id="10d4e90a-86cd-461e-acf0-c90696fa11f0", voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Punchblock::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 15 Jun 2014 12:20:32 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>>]>
[2014-06-15 12:20:32.421] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-1.9.3-p547/gems/blather-1.0.0/lib/blather/client/client.rb:149:in `write') <iq type="set" to="e2625604-edcd-4da0-b1bf-9d2f972...@127.0.0.1" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <output xmlns="urn:xmpp:rayo:output:1">
    <document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
  <audio src="welcome.G729"/>
</speak>]]></document>
  </output>
</iq>
[2014-06-15 12:20:32.443] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="e2625604-edcd-4da0-b1bf-9d2f97...@127.0.0.1" to="us...@127.0.0.1" type="result" id="10d4e90a-86cd-461e-acf0-c90696fa11f0">
  <ref xmlns="urn:xmpp:rayo:1" uri="xmpp:e2625604-edcd-4da0-b1bf-9d2f...@127.0.0.1/output-1"/>
</iq>
Reply all
Reply to author
Forward
0 new messages