--
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.
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 KlangPrincipal/Technology Strategist, Mojo LingoMojo Lingo -- Voice applications that work like magicTwitter: @MojoLingo
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>
It would additionally be helpful to provide logs from FreeSWITCH. These will be more informative than Adhearsion logs.
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>
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_NORMAL2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3858 Lock us...@127.0.0.1: ref count = 22014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3054 us...@127.0.0.1, recv iq, availability = ONLINE2014-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 = 22014-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'><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] mod_rayo.c:652 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, looking for CALL::set:urn:xmpp:rayo:output:1:output command2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1806 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, executing command2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:71 Init c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-12014-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 = 12014-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-12014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:498 Got path c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-12014-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 tts2014-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 tts2014-06-15 12:55:59.531623 [INFO] mod_native_file.c:94 Opening File [welcome.G729] 8000hz2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:713 Create audio buffer2014-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 = 12014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1808 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, done executing command2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 22014-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:182014-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 = 02014-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 = 02014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroying c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-12014-06-15 12:56:00.391623 [DEBUG] mod_rayo.c:1410 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 1Thank you,Matt.
It would additionally be helpful to provide logs from FreeSWITCH. These will be more informative than Adhearsion logs.
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>
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_NORMAL2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3858 Lock us...@127.0.0.1: ref count = 22014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:3054 us...@127.0.0.1, recv iq, availability = ONLINE2014-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'><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] mod_rayo.c:652 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, looking for CALL::set:urn:xmpp:rayo:output:1:output command2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1806 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, executing command2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:71 Init c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-12014-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 = 12014-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-12014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:498 Got path c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-12014-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 tts2014-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 tts2014-06-15 12:55:59.531623 [INFO] mod_native_file.c:94 Opening File [welcome.G729] 8000hz2014-06-15 12:55:59.531623 [DEBUG] rayo_output_component.c:713 Create audio buffer2014-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 = 12014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:1808 c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1, done executing command2014-06-15 12:55:59.531623 [DEBUG] mod_rayo.c:778 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 22014-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:182014-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 = 02014-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 = 02014-06-15 12:56:00.391623 [DEBUG] rayo_components.c:118 Destroying c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1/output-12014-06-15 12:56:00.391623 [DEBUG] mod_rayo.c:1410 Unlock c576eaf8-e7a2-48a4-bfe0-f56cfa...@127.0.0.1: ref count = 1Thank you,Matt.
It would additionally be helpful to provide logs from FreeSWITCH. These will be more informative than Adhearsion logs.
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>