Skip to content

Instantly share code, notes, and snippets.

@ik5
Last active August 29, 2015 14:10
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save ik5/a683099fef5b62b0da67 to your computer and use it in GitHub Desktop.
Save ik5/a683099fef5b62b0da67 to your computer and use it in GitHub Desktop.
[2014-12-07 10:09:41.751] INFO Adhearsion::Initializer: Setting RAILS_ENV to "development"
[2014-12-07 10:09:41.873] INFO Adhearsion::PunchblockPlugin::Initializer: Starting connection to server
[2014-12-07 10:09:41.945] INFO Punchblock::Connection::XMPP: Connected to XMPP as usera@XXX.XXX.31.185/localhost.localdomain-19448
[2014-12-07 10:09:41.947] INFO Adhearsion::PunchblockPlugin::Initializer: Connected to Punchblock server
[2014-12-07 10:09:41.950] INFO Adhearsion::Process: Transitioning from booting to running with 0 active calls due to booted event.
[2014-12-07 10:09:41.952] INFO Adhearsion::Initializer: Adhearsion v2.5.4 initialized in "development"!
[2014-12-07 10:09:42.013] INFO Adhearsion::Console: Launching Adhearsion Console
[2014-12-07 10:09:48.923] DEBUG Adhearsion::Call: @: Receiving message: #<Punchblock::Event::Offer target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id=nil, source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185", domain="XXX.XXX.31.185", transport="xmpp", timestamp=Sun, 07 Dec 2014 10:09:48 +0000, headers={"from"=>"<sip:3000@devpbx2>;tag=668256334", "to"=>"<sip:3001@devpbx2>", "via"=>"SIP/2.0/UDP YYY.YYY.158.35:5060;rport=5060;branch=z9hG4bK669401960"}, to="sip:3001@devpbx2", from="sip:3000@devpbx2">
[2014-12-07 10:09:48.925] INFO Adhearsion::Router: Call 2d4559f4-7df9-11e4-8191-a566d4f640ff selected route "testing" (Forward)
[2014-12-07 10:09:48.943] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Executing command #<Punchblock::Command::Accept target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:09:48 +0000, request_id="5ca5bca7-64c2-4c72-83b5-4da4160cae46", headers={}>
[2014-12-07 10:09:48.975] INFO Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Executing controller #<Forward call=2d4559f4-7df9-11e4-8191-a566d4f640ff, metadata={}>
[2014-12-07 10:09:48.976] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Receiving message: #<Punchblock::Event::Offer target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id=nil, source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185", domain="XXX.XXX.31.185", transport="xmpp", timestamp=Sun, 07 Dec 2014 10:09:48 +0000, headers={"from"=>"<sip:3000@devpbx2>;tag=668256334", "to"=>"<sip:3001@devpbx2>", "via"=>"SIP/2.0/UDP YYY.YYY.158.35:5060;rport=5060;branch=z9hG4bK669401960"}, to="sip:3001@devpbx2", from="sip:3000@devpbx2">
[2014-12-07 10:09:48.978] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Executing command #<Punchblock::Command::Answer target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:09:48 +0000, request_id="53944e17-c26c-45ec-af71-0749eb2cd448", headers={}>
[2014-12-07 10:09:48.985] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Receiving message: #<Punchblock::Event::Answered target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id=nil, source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185", domain="XXX.XXX.31.185", transport="xmpp", timestamp=Sun, 07 Dec 2014 10:09:48 +0000, headers={}>
[2014-12-07 10:09:49.117] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: 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, 07 Dec 2014 10:09:49 +0000, request_id="0be432a5-9482-419c-82c6-a72670a13b76", 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, 07 Dec 2014 10:09:49 +0000, 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="/tmp/test.mp3"/>
</speak>>]>
[2014-12-07 10:09:49.124] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ---------------------------------> res: #<Punchblock::Component::Output target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id="output-1", source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1", domain="XXX.XXX.31.185", transport=nil, timestamp=Sun, 07 Dec 2014 10:09:49 +0000, request_id="0be432a5-9482-419c-82c6-a72670a13b76", 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, 07 Dec 2014 10:09:49 +0000, 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="/tmp/test.mp3"/>
</speak>>]>
[2014-12-07 10:09:49.141] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Executing command #<Punchblock::Component::Input target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:09:49 +0000, request_id="8b7e88f5-fa82-4ef2-88c6-c1ff5e18b8cc", max_silence=50000000, min_confidence=0.5, mode=:dtmf, recognizer=nil, language="en-US", terminator=nil, sensitivity=nil, initial_timeout=50000000, inter_digit_timeout=50000000, grammars=[#<Punchblock::Component::Input::Grammar target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:09:49 +0000, value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="digits">
<rule id="digits" scope="public">
<item repeat="0-1">
<one-of>
<item>0</item>
<item>1</item>
<item>2</item>
<item>3</item>
<item>4</item>
<item>5</item>
<item>6</item>
<item>7</item>
<item>8</item>
<item>9</item>
<item>#</item>
<item>*</item>
</one-of>
</item>
</rule>
</grammar>, content_type="application/srgs+xml", url=nil>]>
[2014-12-07 10:11:06.997] DEBUG AdhearsionASR::PromptBuilder: Ask completed with result #<AdhearsionASR::Result status=:match, confidence=0.0, utterance="6", interpretation="6", nlsml=#<Nokogiri::XML::Element:0x1eb02d4 name="result" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ea044c name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ea01f4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1e84648 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1e84620 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1e7bc8c "6">]>, #<Nokogiri::XML::Element:0x1e97f40 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1e97f04 "6">]>]>]>>
[2014-12-07 10:11:06.999] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Receiving message: #<Punchblock::Event::Complete target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id="input-2", source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2", domain="XXX.XXX.31.185", transport="xmpp", timestamp=Sun, 07 Dec 2014 10:11:06 +0000, reason=#<Punchblock::Component::Input::Complete::Match target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:11:06 +0000, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1eb02d4 name="result" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ea044c name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ea01f4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1e84648 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1e84620 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1e7bc8c "6">]>, #<Nokogiri::XML::Element:0x1e97f40 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1eb02ac href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1e97f04 "6">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>
[2014-12-07 10:11:07.000] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: -------------------------------> dtmf: 6
[2014-12-07 10:11:07.000] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ---------------------------- going forward
[2014-12-07 10:11:07.010] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Executing command #<Punchblock::Component::Input target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:11:07 +0000, request_id="e6d4c2fe-1c82-424d-b801-8850021e17a2", max_silence=50000000, min_confidence=0.5, mode=:dtmf, recognizer=nil, language="en-US", terminator=nil, sensitivity=nil, initial_timeout=50000000, inter_digit_timeout=50000000, grammars=[#<Punchblock::Component::Input::Grammar target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:11:07 +0000, value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="digits">
<rule id="digits" scope="public">
<item repeat="0-1">
<one-of>
<item>0</item>
<item>1</item>
<item>2</item>
<item>3</item>
<item>4</item>
<item>5</item>
<item>6</item>
<item>7</item>
<item>8</item>
<item>9</item>
<item>#</item>
<item>*</item>
</one-of>
</item>
</rule>
</grammar>, content_type="application/srgs+xml", url=nil>]>
[2014-12-07 10:11:12.745] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Receiving message: #<Punchblock::Event::Complete target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id="output-1", source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1", domain="XXX.XXX.31.185", transport="xmpp", timestamp=Sun, 07 Dec 2014 10:11:12 +0000, reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:11:12 +0000, name=:hangup>, recording=nil, fax=nil, fax_metadata={}>
[2014-12-07 10:11:12.749] DEBUG AdhearsionASR::PromptBuilder: Ask completed with result #<AdhearsionASR::Result status=:hangup, confidence=nil, utterance=nil, interpretation=nil, nlsml=nil>
[2014-12-07 10:11:12.750] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Receiving message: #<Punchblock::Event::Complete target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id="input-3", source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3", domain="XXX.XXX.31.185", transport="xmpp", timestamp=Sun, 07 Dec 2014 10:11:12 +0000, reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:11:12 +0000, name=:hangup>, recording=nil, fax=nil, fax_metadata={}>
[2014-12-07 10:11:12.750] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: -------------------------------> dtmf:
[2014-12-07 10:11:12.754] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Executing command #<Punchblock::Component::Input target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:11:12 +0000, request_id="59077bd0-80d2-4616-8785-e79ee10b6809", max_silence=50000000, min_confidence=0.5, mode=:dtmf, recognizer=nil, language="en-US", terminator=nil, sensitivity=nil, initial_timeout=50000000, inter_digit_timeout=50000000, grammars=[#<Punchblock::Component::Input::Grammar target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Sun, 07 Dec 2014 10:11:12 +0000, value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="digits">
<rule id="digits" scope="public">
<item repeat="0-1">
<one-of>
<item>0</item>
<item>1</item>
<item>2</item>
<item>3</item>
<item>4</item>
<item>5</item>
<item>6</item>
<item>7</item>
<item>8</item>
<item>9</item>
<item>#</item>
<item>*</item>
</one-of>
</item>
</rule>
</grammar>, content_type="application/srgs+xml", url=nil>]>
[2014-12-07 10:11:12.759] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Receiving message: #<Punchblock::Event::End target_call_id="2d4559f4-7df9-11e4-8191-a566d4f640ff", target_mixer_name=nil, component_id=nil, source_uri="xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185", domain="XXX.XXX.31.185", transport="xmpp", timestamp=Sun, 07 Dec 2014 10:11:12 +0000, headers={}, reason=:hungup, platform_code="16">
[2014-12-07 10:11:12.760] INFO Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Call ended due to hungup
[2014-12-07 10:11:12.763] INFO Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Call was hung up while executing a controller
[2014-12-07 10:11:12.763] DEBUG Adhearsion::Call: 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: Finished executing controller #<Forward call=2d4559f4-7df9-11e4-8191-a566d4f640ff, metadata={}>
[2014-12-07 10:11:12.764] INFO Adhearsion::Router::Route: Call 2d4559f4-7df9-11e4-8191-a566d4f640ff routing completed. Hanging up now.
[2014-12-07 12:42:44.062] DEBUG Celluloid: Terminating 3 actors...
2014-12-07 10:09:36.418422 [DEBUG] mod_rayo.c:4004 Release usera@XXX.XXX.31.185/localhost.localdomain-19364: ref count = 0
2014-12-07 10:09:36.418422 [DEBUG] mod_rayo.c:4005 Destroy usera@XXX.XXX.31.185/localhost.localdomain-19364 requested: ref_count = 0
2014-12-07 10:09:36.418422 [DEBUG] mod_rayo.c:4005 Destroying usera@XXX.XXX.31.185/localhost.localdomain-19364
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.879428 [DEBUG] xmpp_streams.c:1189 XXX.XXX.31.185:43550, New c2s_in stream
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.879428 [DEBUG] xmpp_streams.c:190 (null), XXX.XXX.31.185:43550, c2s_in RECV <stream:stream to=XXX.XXX.31.185 xmlns=jabber:client xmlns:stream=http://etherx.jabber.org/streams version=1.0 xml:lang=en>
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.879428 [DEBUG] xmpp_streams.c:1058 (null), XXX.XXX.31.185:43550, state = CONNECT, node type = NODE_START
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.879428 [DEBUG] xmpp_streams.c:190 (null), XXX.XXX.31.185:43550, c2s_in SEND <stream:stream xmlns=jabber:client xmlns:db=jabber:server:dialback from=XXX.XXX.31.185 id=2928e7fa-7df9-11e4-818f-a566d4f640ff xml:lang=en version=1.0 xmlns:stream=http://etherx.jabber.org/streams><stream:features><mechanisms xmlns=urn:ietf:params:xml:ns:xmpp-sasl><mechanism>PLAIN</mechanism></mechanisms></stream:features>
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:190 (null), XXX.XXX.31.185:43550, c2s_in RECV <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">dXNlcmFAMzcuMTM5LjMxLjE4NQB1c2VyYQAx</auth>
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:1058 (null), XXX.XXX.31.185:43550, state = SECURE, node type = NODE_NORMAL
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:471 (null), XXX.XXX.31.185:43550, auth, state = SECURE
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:505 (null), XXX.XXX.31.185:43550, auth, state = SECURE, SASL/PLAIN decoded authzid = "usera@XXX.XXX.31.185" authcid = "usera"
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185, XXX.XXX.31.185:43550, c2s_in SEND <success xmlns=urn:ietf:params:xml:ns:xmpp-sasl/>
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185, XXX.XXX.31.185:43550, c2s_in RECV <stream:stream to=XXX.XXX.31.185 xmlns=jabber:client xmlns:stream=http://etherx.jabber.org/streams version=1.0 xml:lang=en>
2928e7fa-7df9-11e4-818f-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185, XXX.XXX.31.185:43550, state = AUTHENTICATED, node type = NODE_START
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.897439 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185, XXX.XXX.31.185:43550, c2s_in SEND <stream:stream xmlns=jabber:client xmlns:db=jabber:server:dialback from=XXX.XXX.31.185 id=292d15e6-7df9-11e4-8190-a566d4f640ff xml:lang=en version=1.0 xmlns:stream=http://etherx.jabber.org/streams><stream:features><bind xmlns=urn:ietf:params:xml:ns:xmpp-bind/><session xmlns=urn:ietf:params:xml:ns:xmpp-session/></stream:features>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" id="blather0002"><bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"><resource>localhost.localdomain-19448</resource></bind></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185, XXX.XXX.31.185:43550, state = AUTHENTICATED, node type = NODE_NORMAL
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq type=result id=blather0002><bind xmlns=urn:ietf:params:xml:ns:xmpp-bind><jid>usera@XXX.XXX.31.185/localhost.localdomain-19448</jid></bind></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" id="blather0004" to="XXX.XXX.31.185"><session xmlns="urn:ietf:params:xml:ns:xmpp-session"/></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = RESOURCE_BOUND, node type = NODE_NORMAL
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:1494 Init usera@XXX.XXX.31.185/localhost.localdomain-19448
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq from=XXX.XXX.31.185 type=result id=blather0004/>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="get" id="blather0006"><query xmlns="jabber:iq:roster"/></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = OFFLINE
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:772 Locate (jid) XXX.XXX.31.185: ref count = 2
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => XXX.XXX.31.185 <iq type='get' id='blather0006' to='XXX.XXX.31.185' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><query xmlns='jabber:iq:roster'/></iq>
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:653 XXX.XXX.31.185, looking for SERVER::get:jabber:iq:roster:query command
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:1738 usera@XXX.XXX.31.185/localhost.localdomain-19448, no handler function for command to XXX.XXX.31.185
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:779 Release XXX.XXX.31.185: ref count = 1
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:1740 Deliver XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq type='error' id='blather0006' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' from='XXX.XXX.31.185'><query xmlns='jabber:iq:roster'/><error type='modify'><feature-not-implemented xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>
2014-12-07 10:09:41.937450 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.937450 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq type=error id=blather0006 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 from=XXX.XXX.31.185><query xmlns=jabber:iq:roster/><error type=modify><feature-not-implemented xmlns=urn:ietf:params:xml:ns:xmpp-stanzas/></error></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.958016 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <presence/><presence to="XXX.XXX.31.185"><show>chat</show></presence>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.958016 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.958016 [DEBUG] xmpp_streams.c:287 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, presence, state = READY
2014-12-07 10:09:41.958016 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:41.958016 [DEBUG] mod_rayo.c:2997 usera@XXX.XXX.31.185/localhost.localdomain-19448 got empty presence
2014-12-07 10:09:41.958016 [DEBUG] mod_rayo.c:3018 usera@XXX.XXX.31.185/localhost.localdomain-19448 is ONLINE
2014-12-07 10:09:41.958016 [DEBUG] mod_rayo.c:3983 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.958016 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:41.958016 [DEBUG] xmpp_streams.c:287 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, presence, state = READY
2014-12-07 10:09:41.958016 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:41.958016 [DEBUG] mod_rayo.c:2989 usera@XXX.XXX.31.185/localhost.localdomain-19448 got chat presence
2014-12-07 10:09:41.958016 [DEBUG] mod_rayo.c:3983 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.777411 [NOTICE] switch_channel.c:1055 New Channel sofia/internal/3000@devpbx2 [2d4559f4-7df9-11e4-8191-a566d4f640ff]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.777411 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.777411 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.777411 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/3000@devpbx2) Running State Change CS_NEW
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.777411 [DEBUG] sofia.c:8812 sofia/internal/3000@devpbx2 receiving invite from YYY.YYY.158.35:5060 version: 1.5.15b git 7ca4ac5 2014-10-31 18:49:39Z 64bit
2014-12-07 10:09:48.777411 [DEBUG] sofia.c:8979 IP YYY.YYY.158.35 Rejected by acl "domains". Falling back to Digest auth.
2014-12-07 10:09:48.777411 [DEBUG] sofia_reg.c:1736 Send challenge for [3001@devpbx2]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.777411 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/3000@devpbx2) State NEW
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.777411 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2014-12-07 10:09:48.777411 [DEBUG] sofia.c:2067 detaching session 2d4559f4-7df9-11e4-8191-a566d4f640ff
2014-12-07 10:09:48.877417 [DEBUG] sofia.c:2175 Re-attaching to session 2d4559f4-7df9-11e4-8191-a566d4f640ff
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.877417 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.877417 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] sofia.c:8812 sofia/internal/3000@devpbx2 receiving invite from YYY.YYY.158.35:5060 version: 1.5.15b git 7ca4ac5 2014-10-31 18:49:39Z 64bit
2014-12-07 10:09:48.897425 [DEBUG] sofia.c:8979 IP YYY.YYY.158.35 Rejected by acl "domains". Falling back to Digest auth.
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'record_stereo' = 'true'
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'default_gateway' = 'example.com'
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'default_areacode' = '918'
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'transfer_fallback_extension' = 'operator'
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'accountcode' = '3000'
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'user_context' = 'testing'
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'effective_caller_id_name' = 'Ido's machine'
2014-12-07 10:09:48.897425 [DEBUG] sofia_reg.c:3090 event_add_header -> 'effective_caller_id_number' = '3000'
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] sofia.c:6606 Channel sofia/internal/3000@devpbx2 entering state [received][100]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] sofia.c:6616 Remote SDP:
2d4559f4-7df9-11e4-8191-a566d4f640ff v=0
2d4559f4-7df9-11e4-8191-a566d4f640ff o=yate 1417946988 1417946988 IN IP4 YYY.YYY.158.35
2d4559f4-7df9-11e4-8191-a566d4f640ff s=SIP Call
2d4559f4-7df9-11e4-8191-a566d4f640ff c=IN IP4 YYY.YYY.158.35
2d4559f4-7df9-11e4-8191-a566d4f640ff t=0 0
2d4559f4-7df9-11e4-8191-a566d4f640ff m=audio 20144 RTP/AVP 0 8 3 11 98 97 102 103 104 105 106 101
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:0 PCMU/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:8 PCMA/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:3 GSM/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:11 L16/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:98 iLBC/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=fmtp:98 mode=20
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:97 iLBC/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=fmtp:97 mode=30
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:102 SPEEX/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:103 SPEEX/16000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:104 SPEEX/32000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:105 iSAC/16000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:106 iSAC/32000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:101 telephone-event/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=ptime:30
2d4559f4-7df9-11e4-8191-a566d4f640ff
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] sofia.c:6868 (sofia/internal/3000@devpbx2) State Change CS_NEW -> CS_INIT
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/3000@devpbx2) Running State Change CS_INIT
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/3000@devpbx2) State INIT
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] mod_sofia.c:87 sofia/internal/3000@devpbx2 SOFIA INIT
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:40 sofia/internal/3000@devpbx2 Standard INIT
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/3000@devpbx2) State Change CS_INIT -> CS_ROUTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/3000@devpbx2) State INIT going to sleep
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/3000@devpbx2) Running State Change CS_ROUTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_channel.c:2184 (sofia/internal/3000@devpbx2) Callstate Change DOWN -> RINGING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/3000@devpbx2) State ROUTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] mod_sofia.c:123 sofia/internal/3000@devpbx2 SOFIA ROUTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:166 sofia/internal/3000@devpbx2 Standard ROUTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [INFO] mod_dialplan_xml.c:558 Processing 3000 <3000>->3001 in context testing
2d4559f4-7df9-11e4-8191-a566d4f640ff Dialplan: sofia/internal/3000@devpbx2 parsing [testing->forward] continue=false
2d4559f4-7df9-11e4-8191-a566d4f640ff Dialplan: sofia/internal/3000@devpbx2 Regex (PASS) [forward] destination_number(3001) =~ /^(300\d)$/ break=on-false
2d4559f4-7df9-11e4-8191-a566d4f640ff Dialplan: sofia/internal/3000@devpbx2 Action rayo()
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/3000@devpbx2) State Change CS_ROUTING -> CS_EXECUTE
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/3000@devpbx2) State ROUTING going to sleep
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/3000@devpbx2) Running State Change CS_EXECUTE
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/3000@devpbx2) State EXECUTE
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] mod_sofia.c:178 sofia/internal/3000@devpbx2 SOFIA EXECUTE
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] switch_core_state_machine.c:258 sofia/internal/3000@devpbx2 Standard EXECUTE
2d4559f4-7df9-11e4-8191-a566d4f640ff EXECUTE sofia/internal/3000@devpbx2 rayo()
2014-12-07 10:09:48.897425 [DEBUG] mod_rayo.c:3818 Init 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] mod_rayo.c:3829 Offering call for Rayo 3PCC
2014-12-07 10:09:48.897425 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.897425 [DEBUG] mod_rayo.c:3852 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <presence from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448'><c xmlns='http://jabber.org/protocol/caps' hash='sha-1' node='urn:xmpp:rayo:call:1' ver='L9rOKMEJl9rssEggArljiapRd9k='/><offer xmlns='urn:xmpp:rayo:1' from='sip:3000@devpbx2' to='sip:3001@devpbx2'><header name='from' value='&lt;sip:3000@devpbx2&gt;;tag=668256334'/><header name='to' value='&lt;sip:3001@devpbx2&gt;'/><header name='via' value='SIP/2.0/UDP YYY.YYY.158.35:5060;rport=5060;branch=z9hG4bK669401960'/></offer><delay xmlns='urn:xmpp:delay' stamp='2014-12-07T10:09:48Z'/></presence>
2014-12-07 10:09:48.897425 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] mod_rayo.c:3596 got event CHANNEL_EXECUTE
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.897425 [DEBUG] mod_rayo.c:3596 got event CHANNEL_PARK
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.917465 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <presence from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448><c xmlns=http://jabber.org/protocol/caps hash=sha-1 node=urn:xmpp:rayo:call:1 ver=L9rOKMEJl9rssEggArljiapRd9k=/><offer xmlns=urn:xmpp:rayo:1 from=sip:3000@devpbx2 to=sip:3001@devpbx2><header name=from value=&lt;sip:3000@devpbx2&gt;;tag=668256334/><header name=to value=&lt;sip:3001@devpbx2&gt;/><header name=via value=SIP/2.0/UDP YYY.YYY.158.35:5060;rport=5060;branch=z9hG4bK669401960/></offer><delay xmlns=urn:xmpp:delay stamp=2014-12-07T10:09:48Z/></presence>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" to="2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185" id="5ca5bca7-64c2-4c72-83b5-4da4160cae46"><accept xmlns="urn:xmpp:rayo:1"/></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:772 Locate (jid) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 <iq type='set' to='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' id='5ca5bca7-64c2-4c72-83b5-4da4160cae46' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><accept xmlns='urn:xmpp:rayo:1'/></iq>
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:653 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, looking for CALL::set:urn:xmpp:rayo:1:accept command
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [INFO] mod_rayo.c:1634 usera@XXX.XXX.31.185/localhost.localdomain-19448 has control of call
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:1814 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, executing command
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [INFO] mod_rayo.c:1951 Sending early media
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3651 Audio Codec Compare [PCMU:0:8000:20:64000:1] is saved as a near-match
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3651 Audio Codec Compare [PCMA:8:8000:20:64000:1] is saved as a near-match
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:30:13200:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:30:13200:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:30:13200:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:30:13200:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:30:13200:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3651 Audio Codec Compare [GSM:3:8000:20:13200:1] is saved as a near-match
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [GSM:3:8000:30:13200:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [L16:11:8000:30:0:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [L16:11:8000:30:0:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [L16:11:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [L16:11:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [L16:11:8000:30:0:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [L16:11:8000:30:0:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:98:8000:30:0:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:98:8000:30:0:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:98:8000:30:0:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:98:8000:30:0:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:97:8000:30:0:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:97:8000:30:0:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iLBC:97:8000:30:0:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:102:8000:30:0:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:102:8000:30:0:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:102:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:102:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:102:8000:30:0:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:102:8000:30:0:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:103:16000:30:0:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:103:16000:30:0:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:103:16000:30:0:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:103:16000:30:0:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:103:16000:30:0:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:103:16000:30:0:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:104:32000:30:0:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:104:32000:30:0:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:104:32000:30:0:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:104:32000:30:0:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:104:32000:30:0:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [SPEEX:104:32000:30:0:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:105:16000:30:32000:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:105:16000:30:32000:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:105:16000:30:32000:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:105:16000:30:32000:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:105:16000:30:32000:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:105:16000:30:32000:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:106:32000:30:32000:1]/[SILK:117:8000:20:20000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:106:32000:30:32000:1]/[G722:9:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:106:32000:30:32000:1]/[PCMU:0:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:106:32000:30:32000:1]/[PCMA:8:8000:20:64000:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:106:32000:30:32000:1]/[GSM:3:8000:20:13200:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3615 Audio Codec Compare [iSAC:106:32000:30:32000:1]/[opus:116:48000:20:0:1]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3531 Set telephone-event payload to 101
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3721 Substituting codec PCMU@30i@8000h@1c
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:2473 Set Codec sofia/internal/3000@devpbx2 PCMU/8000 30 ms 240 samples 64000 bits 1 channels
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_codec.c:111 sofia/internal/3000@devpbx2 Original read codec set to PCMU:0
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:3861 Set 2833 dtmf send/recv payload to 101
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:5099 AUDIO RTP [sofia/internal/3000@devpbx2] XXX.XXX.31.185 port 31696 -> YYY.YYY.158.35 port 20144 codec: 0 ms: 30
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_rtp.c:3521 Starting timer [soft] 240 bytes per 30ms
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:5397 Set 2833 dtmf send payload to 101
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_core_media.c:5403 Set 2833 dtmf receive payload to 101
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/3000@devpbx2!
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3596 got event CHANNEL_PROGRESS_MEDIA
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3601 usera@XXX.XXX.31.185/localhost.localdomain-19448 rayo event CHANNEL_PROGRESS_MEDIA
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3603 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.938416 [DEBUG] mod_rayo.c:3611 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] switch_channel.c:3399 (sofia/internal/3000@devpbx2) Callstate Change RINGING -> EARLY
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.938416 [DEBUG] mod_sofia.c:2244 Ring SDP:
2d4559f4-7df9-11e4-8191-a566d4f640ff v=0
2d4559f4-7df9-11e4-8191-a566d4f640ff o=FreeSWITCH 1417915292 1417915293 IN IP4 XXX.XXX.31.185
2d4559f4-7df9-11e4-8191-a566d4f640ff s=FreeSWITCH
2d4559f4-7df9-11e4-8191-a566d4f640ff c=IN IP4 XXX.XXX.31.185
2d4559f4-7df9-11e4-8191-a566d4f640ff t=0 0
2d4559f4-7df9-11e4-8191-a566d4f640ff m=audio 31696 RTP/AVP 0 101
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:0 PCMU/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:101 telephone-event/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=fmtp:101 0-16
2d4559f4-7df9-11e4-8191-a566d4f640ff a=ptime:30
2d4559f4-7df9-11e4-8191-a566d4f640ff a=sendrecv
2d4559f4-7df9-11e4-8191-a566d4f640ff
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.957418 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.957418 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/3000@devpbx2 [BREAK]
2014-12-07 10:09:48.957418 [DEBUG] mod_rayo.c:1816 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, done executing command
2014-12-07 10:09:48.957418 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.957418 [DEBUG] mod_rayo.c:1822 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='result' id='5ca5bca7-64c2-4c72-83b5-4da4160cae46'/>
2014-12-07 10:09:48.957418 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:48.957418 [DEBUG] mod_rayo.c:779 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.957418 [DEBUG] sofia.c:6606 Channel sofia/internal/3000@devpbx2 entering state [early][183]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.957418 [DEBUG] switch_ivr.c:961 Codec Activated L16@8000hz 1 channels 30ms
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.957418 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=result id=5ca5bca7-64c2-4c72-83b5-4da4160cae46/>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" to="2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185" id="53944e17-c26c-45ec-af71-0749eb2cd448"><answer xmlns="urn:xmpp:rayo:1"/></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:772 Locate (jid) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 <iq type='set' to='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' id='53944e17-c26c-45ec-af71-0749eb2cd448' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><answer xmlns='urn:xmpp:rayo:1'/></iq>
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:653 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, looking for CALL::set:urn:xmpp:rayo:1:answer command
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:1814 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, executing command
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] mod_sofia.c:780 Local SDP sofia/internal/3000@devpbx2:
2d4559f4-7df9-11e4-8191-a566d4f640ff v=0
2d4559f4-7df9-11e4-8191-a566d4f640ff o=FreeSWITCH 1417915292 1417915294 IN IP4 XXX.XXX.31.185
2d4559f4-7df9-11e4-8191-a566d4f640ff s=FreeSWITCH
2d4559f4-7df9-11e4-8191-a566d4f640ff c=IN IP4 XXX.XXX.31.185
2d4559f4-7df9-11e4-8191-a566d4f640ff t=0 0
2d4559f4-7df9-11e4-8191-a566d4f640ff m=audio 31696 RTP/AVP 0 101
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:0 PCMU/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=rtpmap:101 telephone-event/8000
2d4559f4-7df9-11e4-8191-a566d4f640ff a=fmtp:101 0-16
2d4559f4-7df9-11e4-8191-a566d4f640ff a=ptime:30
2d4559f4-7df9-11e4-8191-a566d4f640ff a=sendrecv
2d4559f4-7df9-11e4-8191-a566d4f640ff
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] switch_core_session.c:908 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [NOTICE] mod_rayo.c:1970 Channel [sofia/internal/3000@devpbx2] has been answered
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3596 got event CHANNEL_ANSWER
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3601 usera@XXX.XXX.31.185/localhost.localdomain-19448 rayo event CHANNEL_ANSWER
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3603 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3378 Locate (id) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 3
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3384 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3611 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:3383 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <presence from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448'><answered xmlns='urn:xmpp:rayo:1'/><delay xmlns='urn:xmpp:delay' stamp='2014-12-07T10:09:48Z'/></presence>
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] sofia.c:6606 Channel sofia/internal/3000@devpbx2 entering state [completed][200]
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <presence from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448><answered xmlns=urn:xmpp:rayo:1/><delay xmlns=urn:xmpp:delay stamp=2014-12-07T10:09:48Z/></presence>
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:48.980242 [DEBUG] switch_channel.c:3689 (sofia/internal/3000@devpbx2) Callstate Change EARLY -> ACTIVE
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:1816 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, done executing command
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:1822 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='result' id='53944e17-c26c-45ec-af71-0749eb2cd448'/>
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:48.980242 [DEBUG] mod_rayo.c:779 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:48.997435 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=result id=53944e17-c26c-45ec-af71-0749eb2cd448/>
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.097433 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.097433 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.097433 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.097433 [DEBUG] sofia.c:6606 Channel sofia/internal/3000@devpbx2 entering state [ready][200]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.097433 [DEBUG] switch_rtp.c:5823 Correct ip/port confirmed.
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:49.119295 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" to="2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185" id="0be432a5-9482-419c-82c6-a72670a13b76"><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">
292d15e6-7df9-11e4-8190-a566d4f640ff <audio src="/tmp/test.mp3"/>
292d15e6-7df9-11e4-8190-a566d4f640ff </speak>]]></document></output></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:49.119295 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:772 Locate (jid) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 <iq type='set' to='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' id='0be432a5-9482-419c-82c6-a72670a13b76' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><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;/tmp/test.mp3&quot;/&gt;
&lt;/speak&gt;</document></output></iq>
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:653 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, looking for CALL::set:urn:xmpp:rayo:output:1:output command
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:1814 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, executing command
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:1292 Lock 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 3
2014-12-07 10:09:49.119295 [DEBUG] rayo_output_component.c:71 Init 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1
2014-12-07 10:09:49.119295 [DEBUG] switch_event.c:1688 Parsing variable [id]=[2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1]
2014-12-07 10:09:49.119295 [DEBUG] switch_event.c:1688 Parsing variable [session]=[2d4559f4-7df9-11e4-8191-a566d4f640ff]
2014-12-07 10:09:49.119295 [DEBUG] switch_event.c:1688 Parsing variable [pause]=[false]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.119295 [DEBUG] rayo_output_component.c:689 Got path rayo://2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1
2014-12-07 10:09:49.119295 [DEBUG] rayo_output_component.c:498 Got path 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1
2014-12-07 10:09:49.119295 [DEBUG] rayo_output_component.c:500 Locate (id) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1: ref count = 2
2014-12-07 10:09:49.119295 [DEBUG] mod_ssml.c:812 Open: <speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
<audio src="/tmp/test.mp3"/>
</speak>
2014-12-07 10:09:49.119295 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-12-07 10:09:49.119295 [DEBUG] mod_ssml.c:597 Adding <audio>: "/tmp/test.mp3"
2014-12-07 10:09:49.119295 [DEBUG] mod_ssml.c:738 Skipping empty tts
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:49.119295 [DEBUG] rayo_components.c:62 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='result' id='0be432a5-9482-419c-82c6-a72670a13b76'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1'/></iq>
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:49.119295 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=result id=0be432a5-9482-419c-82c6-a72670a13b76><ref xmlns=urn:xmpp:rayo:1 uri=xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1/></iq>
2014-12-07 10:09:49.119295 [WARNING] switch_core_file.c:230 File has 2 channels, muxing to 1 channel will occur.
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.119295 [DEBUG] rayo_output_component.c:713 Create audio buffer
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.119295 [DEBUG] switch_core_media_bug.c:532 Attaching BUG to sofia/internal/3000@devpbx2
2014-12-07 10:09:49.119295 [DEBUG] rayo_output_component.c:115 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1: ref count = 1
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:1816 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, done executing command
2014-12-07 10:09:49.119295 [DEBUG] mod_rayo.c:779 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:49.138422 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" to="2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185" id="8b7e88f5-fa82-4ef2-88c6-c1ff5e18b8cc"><input xmlns="urn:xmpp:rayo:input:1" max-silence="50000000" min-confidence="0.5" mode="dtmf" language="en-US" initial-timeout="50000000" inter-digit-timeout="50000000"><grammar content-type="application/srgs+xml"><![CDATA[<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="digits">
292d15e6-7df9-11e4-8190-a566d4f640ff <rule id="digits" scope="public">
292d15e6-7df9-11e4-8190-a566d4f640ff <item repeat="0-1">
292d15e6-7df9-11e4-8190-a566d4f640ff <one-of>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>0</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>1</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>2</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>3</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>4</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>5</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>6</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>7</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>8</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>9</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>#</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>*</item>
292d15e6-7df9-11e4-8190-a566d4f640ff </one-of>
292d15e6-7df9-11e4-8190-a566d4f640ff </item>
292d15e6-7df9-11e4-8190-a566d4f640ff </rule>
292d15e6-7df9-11e4-8190-a566d4f640ff </grammar>]]></grammar></input></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:49.138422 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:772 Locate (jid) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 3
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 <iq type='set' to='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' id='8b7e88f5-fa82-4ef2-88c6-c1ff5e18b8cc' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><input xmlns='urn:xmpp:rayo:input:1' max-silence='50000000' min-confidence='0.5' mode='dtmf' language='en-US' initial-timeout='50000000' inter-digit-timeout='50000000'><grammar content-type='application/srgs+xml'>&lt;grammar xmlns=&quot;http://www.w3.org/2001/06/grammar&quot; version=&quot;1.0&quot; xml:lang=&quot;en-US&quot; mode=&quot;dtmf&quot; root=&quot;digits&quot;&gt;
&lt;rule id=&quot;digits&quot; scope=&quot;public&quot;&gt;
&lt;item repeat=&quot;0-1&quot;&gt;
&lt;one-of&gt;
&lt;item&gt;0&lt;/item&gt;
&lt;item&gt;1&lt;/item&gt;
&lt;item&gt;2&lt;/item&gt;
&lt;item&gt;3&lt;/item&gt;
&lt;item&gt;4&lt;/item&gt;
&lt;item&gt;5&lt;/item&gt;
&lt;item&gt;6&lt;/item&gt;
&lt;item&gt;7&lt;/item&gt;
&lt;item&gt;8&lt;/item&gt;
&lt;item&gt;9&lt;/item&gt;
&lt;item&gt;#&lt;/item&gt;
&lt;item&gt;*&lt;/item&gt;
&lt;/one-of&gt;
&lt;/item&gt;
&lt;/rule&gt;
&lt;/grammar&gt;</grammar></input></iq>
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:653 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, looking for CALL::set:urn:xmpp:rayo:input:1:input command
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:1814 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, executing command
2014-12-07 10:09:49.138422 [DEBUG] mod_rayo.c:1292 Lock 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 4
2014-12-07 10:09:49.138422 [DEBUG] rayo_input_component.c:764 Init 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.138422 [DEBUG] rayo_input_component.c:324 Adding DTMF callback
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.138422 [DEBUG] switch_core_media_bug.c:532 Attaching BUG to sofia/internal/3000@devpbx2
2014-12-07 10:09:49.158062 [DEBUG] srgs.c:1219 Using cached grammar
2014-12-07 10:09:49.158062 [DEBUG] mod_rayo.c:1816 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, done executing command
2014-12-07 10:09:49.158062 [DEBUG] mod_rayo.c:779 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 3
2014-12-07 10:09:49.158062 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:09:49.158062 [DEBUG] rayo_components.c:62 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='result' id='8b7e88f5-fa82-4ef2-88c6-c1ff5e18b8cc'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2'/></iq>
2014-12-07 10:09:49.158062 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.158062 [DEBUG] switch_core_media.c:1977 Correcting calculated ptime value from 40 to 20 to compensate for 1 lost packet(s)
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.158062 [DEBUG] switch_core_io.c:528 Setting BUG Codec PCMU:0
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.158062 [DEBUG] switch_core_io.c:865 Engaging Read Buffer at 480 bytes vs 160
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:09:49.158062 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=result id=8b7e88f5-fa82-4ef2-88c6-c1ff5e18b8cc><ref xmlns=urn:xmpp:rayo:1 uri=xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2/></iq>
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.337421 [WARNING] switch_core_media.c:2009 Asynchronous PTIME not supported, changing our end from 30 to 20
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.357410 [DEBUG] switch_core_media.c:2381 Changing Codec from PCMU@30ms@8000hz to PCMU@20ms@8000hz
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.417428 [DEBUG] switch_rtp.c:3403 RE-Starting timer [soft] 160 bytes per 20ms
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.417428 [DEBUG] switch_core_media.c:2473 Set Codec sofia/internal/3000@devpbx2 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.417428 [DEBUG] switch_core_codec.c:123 sofia/internal/3000@devpbx2 Original read codec replaced with PCMU:0
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.417428 [DEBUG] switch_core_codec.c:151 Destroying BUG Codec PCMU:0
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.417428 [DEBUG] switch_core_io.c:1546 Engaging Write Buffer at 320 bytes to accommodate 480->320
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:09:49.437418 [DEBUG] switch_core_io.c:528 Setting BUG Codec PCMU:0
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:10:41.937428 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="get" to="XXX.XXX.31.185" id="blather000c"><ping xmlns="urn:xmpp:ping"/></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:10:41.937428 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:772 Locate (jid) XXX.XXX.31.185: ref count = 2
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => XXX.XXX.31.185 <iq type='get' to='XXX.XXX.31.185' id='blather000c' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><ping xmlns='urn:xmpp:ping'/></iq>
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:653 XXX.XXX.31.185, looking for SERVER::get:urn:xmpp:ping:ping command
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:779 Release XXX.XXX.31.185: ref count = 1
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:1752 Deliver XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq type='result' from='XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' id='blather000c'/>
2014-12-07 10:10:41.937428 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:10:41.937428 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq type=result from=XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 id=blather000c/>
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.957419 [DEBUG] switch_rtp.c:6045 RTP RECV DTMF 6:1600
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.957419 [DEBUG] rayo_input_component.c:216 Collected digits = "6"
2014-12-07 10:11:06.957419 [DEBUG] srgs.c:1303 match = 1
2014-12-07 10:11:06.957419 [DEBUG] srgs.c:1270 is match end
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.957419 [DEBUG] rayo_input_component.c:257 MATCH = 6
2014-12-07 10:11:06.957419 [DEBUG] rayo_components.c:117 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2: ref count = 0
2014-12-07 10:11:06.957419 [DEBUG] rayo_components.c:118 Destroy 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2 requested: ref_count = 0
2014-12-07 10:11:06.957419 [DEBUG] rayo_components.c:118 Destroying 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2
2014-12-07 10:11:06.957419 [DEBUG] mod_rayo.c:958 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.957419 [DEBUG] switch_channel.c:488 RECV DTMF 6:1600
2014-12-07 10:11:06.957419 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:06.957419 [DEBUG] rayo_components.c:116 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <presence from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='unavailable'><complete xmlns='urn:xmpp:rayo:ext:1'><match xmlns='urn:xmpp:rayo:input:complete:1' content-type='application/nlsml+xml'>&lt;result xmlns=&apos;http://www.ietf.org/xml/ns/mrcpv2&apos; xmlns:xf=&apos;http://www.w3.org/2000/xforms&apos;&gt;&lt;interpretation&gt;&lt;input mode=&apos;dtmf&apos; confidence=&apos;100&apos;&gt;6&lt;/input&gt;&lt;instance&gt;6&lt;/instance&gt;&lt;/interpretation&gt;&lt;/result&gt;</match></complete><delay xmlns='urn:xmpp:delay' stamp='2014-12-07T10:11:06Z'/></presence>
2014-12-07 10:11:06.957419 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.957419 [DEBUG] rayo_cpa_detector.c:175 Got Rayo CPA event dtmf
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.957419 [DEBUG] rayo_cpa_component.c:130 Subscriber execute urn:xmpp:rayo:cpa:dtmf:1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.957419 [DEBUG] rayo_cpa_component.c:143 No subscribers for urn:xmpp:rayo:cpa:dtmf:1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:06.977423 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <presence from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-2 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=unavailable><complete xmlns=urn:xmpp:rayo:ext:1><match xmlns=urn:xmpp:rayo:input:complete:1 content-type=application/nlsml+xml>&lt;result xmlns=&apos;http://www.ietf.org/xml/ns/mrcpv2&apos; xmlns:xf=&apos;http://www.w3.org/2000/xforms&apos;&gt;&lt;interpretation&gt;&lt;input mode=&apos;dtmf&apos; confidence=&apos;100&apos;&gt;6&lt;/input&gt;&lt;instance&gt;6&lt;/instance&gt;&lt;/interpretation&gt;&lt;/result&gt;</match></complete><delay xmlns=urn:xmpp:delay stamp=2014-12-07T10:11:06Z/></presence>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:06.998024 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" to="2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1" id="d4923c5c-ffb4-4adf-93a2-7ee2fad1cf91"><seek xmlns="urn:xmpp:rayo:output:1" direction="forward" amount="1500"/></iq><iq type="set" to="2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185" id="e6d4c2fe-1c82-424d-b801-8850021e17a2"><input xmlns="urn:xmpp:rayo:input:1" max-silence="50000000" min-confidence="0.5" mode="dtmf" language="en-US" initial-timeout="50000000" inter-digit-timeout="50000000"><grammar content-type="application/srgs+xml"><![CDATA[<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="digits">
292d15e6-7df9-11e4-8190-a566d4f640ff <rule id="digits" scope="public">
292d15e6-7df9-11e4-8190-a566d4f640ff <item repeat="0-1">
292d15e6-7df9-11e4-8190-a566d4f640ff <one-of>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>0</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>1</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>2</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>3</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>4</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>5</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>6</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>7</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>8</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>9</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>#</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>*</item>
292d15e6-7df9-11e4-8190-a566d4f640ff </one-of>
292d15e6-7df9-11e4-8190-a566d4f640ff </item>
292d15e6-7df9-11e4-8190-a566d4f640ff </rule>
292d15e6-7df9-11e4-8190-a566d4f640ff </grammar>]]></grammar></input></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:06.998024 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:06.998024 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:772 Locate (jid) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1: ref count = 2
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1 <iq type='set' to='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1' id='d4923c5c-ffb4-4adf-93a2-7ee2fad1cf91' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><seek xmlns='urn:xmpp:rayo:output:1' direction='forward' amount='1500'/></iq>
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:653 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1, looking for COMPONENT_CALL:output:set:urn:xmpp:rayo:output:1:seek command
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:1881 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1, executing command
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:06.998024 [DEBUG] rayo_output_component.c:1102 seek to position 12000
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:1883 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1, done executing command
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:779 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1: ref count = 1
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:772 Locate (jid) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 3
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:3057 Deliver usera@XXX.XXX.31.185/localhost.localdomain-19448 => 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 <iq type='set' to='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' id='e6d4c2fe-1c82-424d-b801-8850021e17a2' from='usera@XXX.XXX.31.185/localhost.localdomain-19448'><input xmlns='urn:xmpp:rayo:input:1' max-silence='50000000' min-confidence='0.5' mode='dtmf' language='en-US' initial-timeout='50000000' inter-digit-timeout='50000000'><grammar content-type='application/srgs+xml'>&lt;grammar xmlns=&quot;http://www.w3.org/2001/06/grammar&quot; version=&quot;1.0&quot; xml:lang=&quot;en-US&quot; mode=&quot;dtmf&quot; root=&quot;digits&quot;&gt;
&lt;rule id=&quot;digits&quot; scope=&quot;public&quot;&gt;
&lt;item repeat=&quot;0-1&quot;&gt;
&lt;one-of&gt;
&lt;item&gt;0&lt;/item&gt;
&lt;item&gt;1&lt;/item&gt;
&lt;item&gt;2&lt;/item&gt;
&lt;item&gt;3&lt;/item&gt;
&lt;item&gt;4&lt;/item&gt;
&lt;item&gt;5&lt;/item&gt;
&lt;item&gt;6&lt;/item&gt;
&lt;item&gt;7&lt;/item&gt;
&lt;item&gt;8&lt;/item&gt;
&lt;item&gt;9&lt;/item&gt;
&lt;item&gt;#&lt;/item&gt;
&lt;item&gt;*&lt;/item&gt;
&lt;/one-of&gt;
&lt;/item&gt;
&lt;/rule&gt;
&lt;/grammar&gt;</grammar></input></iq>
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:653 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, looking for CALL::set:urn:xmpp:rayo:input:1:input command
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:1814 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, executing command
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:1292 Lock 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 4
2014-12-07 10:11:06.998024 [DEBUG] rayo_input_component.c:764 Init 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3
2014-12-07 10:11:06.998024 [DEBUG] srgs.c:1219 Using cached grammar
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:1816 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185, done executing command
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:779 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 3
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:1888 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='result' id='d4923c5c-ffb4-4adf-93a2-7ee2fad1cf91'/>
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:06.998024 [DEBUG] rayo_components.c:62 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='result' id='e6d4c2fe-1c82-424d-b801-8850021e17a2'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3'/></iq>
2014-12-07 10:11:06.998024 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:06.998024 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=result id=d4923c5c-ffb4-4adf-93a2-7ee2fad1cf91/>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:06.998024 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=result id=e6d4c2fe-1c82-424d-b801-8850021e17a2><ref xmlns=urn:xmpp:rayo:1 uri=xmpp:2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3/></iq>
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [NOTICE] sofia.c:952 Hangup sofia/internal/3000@devpbx2 [CS_EXECUTE] [NORMAL_CLEARING]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_channel.c:3222 Send signal sofia/internal/3000@devpbx2 [KILL]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_session.c:2888 sofia/internal/3000@devpbx2 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/3000@devpbx2) State EXECUTE going to sleep
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/3000@devpbx2) Running State Change CS_HANGUP
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:117 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3: ref count = 0
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:118 Destroy 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3 requested: ref_count = 0
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:118 Destroying 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:958 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] rayo_input_component.c:365 Removing DTMF callback
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_media_bug.c:780 Removing BUG from sofia/internal/3000@devpbx2
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:117 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1: ref count = 0
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:118 Destroy 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1 requested: ref_count = 0
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:118 Destroying 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:958 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_media_bug.c:780 Removing BUG from sofia/internal/3000@devpbx2
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/3000@devpbx2) Callstate Change ACTIVE -> HANGUP
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/3000@devpbx2) State HANGUP
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] mod_sofia.c:413 Channel sofia/internal/3000@devpbx2 hanging up, cause: NORMAL_CLEARING
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:116 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <presence from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='unavailable'><complete xmlns='urn:xmpp:rayo:ext:1'><hangup xmlns='urn:xmpp:rayo:ext:complete:1'/></complete><delay xmlns='urn:xmpp:delay' stamp='2014-12-07T10:11:12Z'/></presence>
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:12.717421 [DEBUG] rayo_components.c:116 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <presence from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='unavailable'><complete xmlns='urn:xmpp:rayo:ext:1'><hangup xmlns='urn:xmpp:rayo:ext:complete:1'/></complete><delay xmlns='urn:xmpp:delay' stamp='2014-12-07T10:11:12Z'/></presence>
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:3596 got event CHANNEL_EXECUTE_COMPLETE
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:3601 usera@XXX.XXX.31.185/localhost.localdomain-19448 rayo event CHANNEL_EXECUTE_COMPLETE
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:3603 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:3538 Locate (id) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:3541 Application rayo execute complete: _none_
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:3544 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 1
2014-12-07 10:11:12.717421 [DEBUG] mod_rayo.c:3611 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:60 sofia/internal/3000@devpbx2 Standard HANGUP, cause: NORMAL_CLEARING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/3000@devpbx2) State HANGUP going to sleep
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/3000@devpbx2) State Change CS_HANGUP -> CS_REPORTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/3000@devpbx2) Running State Change CS_REPORTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/3000@devpbx2) State REPORTING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:104 sofia/internal/3000@devpbx2 Standard REPORTING, cause: NORMAL_CLEARING
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/3000@devpbx2) State REPORTING going to sleep
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:498 (sofia/internal/3000@devpbx2) State Change CS_REPORTING -> CS_DESTROY
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_session.c:1388 Send signal sofia/internal/3000@devpbx2 [BREAK]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_session.c:1615 Session 25 (sofia/internal/3000@devpbx2) Locked, Waiting on external entities
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [NOTICE] switch_core_session.c:1633 Session 25 (sofia/internal/3000@devpbx2) Ended
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [NOTICE] switch_core_session.c:1637 Close Channel sofia/internal/3000@devpbx2 [CS_DESTROY]
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:626 (sofia/internal/3000@devpbx2) Running State Change CS_DESTROY
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/3000@devpbx2) State DESTROY
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] mod_sofia.c:323 sofia/internal/3000@devpbx2 SOFIA DESTROY
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:111 sofia/internal/3000@devpbx2 Standard DESTROY
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.717421 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/3000@devpbx2) State DESTROY going to sleep
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.737976 [DEBUG] rayo_cpa_component.c:130 Subscriber execute hangup
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.737976 [DEBUG] rayo_cpa_component.c:143 No subscribers for hangup
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:3348 Locate (id) 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 2
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:3358 Got channel destroy event
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:3363 Destroy 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 requested: ref_count = 2
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:3364 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 1
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:3367 Release 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185: ref count = 0
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:3367 Destroying 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185
2d4559f4-7df9-11e4-8191-a566d4f640ff 2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:1108 Sending <end> to offered client usera@XXX.XXX.31.185/localhost.localdomain-19448
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:1109 Deliver 2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <presence from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' type='unavailable'><end xmlns='urn:xmpp:rayo:1'><hungup platform-code='16'/></end><delay xmlns='urn:xmpp:delay' stamp='2014-12-07T10:11:12Z'/></presence>
2014-12-07 10:11:12.737976 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:12.737976 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <presence from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/output-1 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=unavailable><complete xmlns=urn:xmpp:rayo:ext:1><hangup xmlns=urn:xmpp:rayo:ext:complete:1/></complete><delay xmlns=urn:xmpp:delay stamp=2014-12-07T10:11:12Z/></presence>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:12.737976 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <presence from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185/input-3 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=unavailable><complete xmlns=urn:xmpp:rayo:ext:1><hangup xmlns=urn:xmpp:rayo:ext:complete:1/></complete><delay xmlns=urn:xmpp:delay stamp=2014-12-07T10:11:12Z/></presence>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:12.757808 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <presence from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185 to=usera@XXX.XXX.31.185/localhost.localdomain-19448 type=unavailable><end xmlns=urn:xmpp:rayo:1><hungup platform-code=16/></end><delay xmlns=urn:xmpp:delay stamp=2014-12-07T10:11:12Z/></presence>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:12.757808 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in RECV <iq type="set" to="2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185" id="59077bd0-80d2-4616-8785-e79ee10b6809"><input xmlns="urn:xmpp:rayo:input:1" max-silence="50000000" min-confidence="0.5" mode="dtmf" language="en-US" initial-timeout="50000000" inter-digit-timeout="50000000"><grammar content-type="application/srgs+xml"><![CDATA[<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="digits">
292d15e6-7df9-11e4-8190-a566d4f640ff <rule id="digits" scope="public">
292d15e6-7df9-11e4-8190-a566d4f640ff <item repeat="0-1">
292d15e6-7df9-11e4-8190-a566d4f640ff <one-of>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>0</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>1</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>2</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>3</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>4</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>5</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>6</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>7</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>8</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>9</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>#</item>
292d15e6-7df9-11e4-8190-a566d4f640ff <item>*</item>
292d15e6-7df9-11e4-8190-a566d4f640ff </one-of>
292d15e6-7df9-11e4-8190-a566d4f640ff </item>
292d15e6-7df9-11e4-8190-a566d4f640ff </rule>
292d15e6-7df9-11e4-8190-a566d4f640ff </grammar>]]></grammar></input></iq>
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:12.757808 [DEBUG] xmpp_streams.c:1058 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, state = READY, node type = NODE_NORMAL
2014-12-07 10:11:12.757808 [DEBUG] mod_rayo.c:3905 Lock usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:12.757808 [DEBUG] mod_rayo.c:3054 usera@XXX.XXX.31.185/localhost.localdomain-19448, recv iq, availability = ONLINE
2014-12-07 10:11:12.757808 [DEBUG] mod_rayo.c:3976 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
2014-12-07 10:11:12.757808 [DEBUG] mod_rayo.c:772 Locate (jid) usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 2
2014-12-07 10:11:12.757808 [DEBUG] mod_rayo.c:782 Deliver XXX.XXX.31.185 => usera@XXX.XXX.31.185/localhost.localdomain-19448 <iq type='error' to='usera@XXX.XXX.31.185/localhost.localdomain-19448' id='59077bd0-80d2-4616-8785-e79ee10b6809' from='2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185'><input xmlns='urn:xmpp:rayo:input:1' max-silence='50000000' min-confidence='0.5' mode='dtmf' language='en-US' initial-timeout='50000000' inter-digit-timeout='50000000'><grammar content-type='application/srgs+xml'>&lt;grammar xmlns=&quot;http://www.w3.org/2001/06/grammar&quot; version=&quot;1.0&quot; xml:lang=&quot;en-US&quot; mode=&quot;dtmf&quot; root=&quot;digits&quot;&gt;
&lt;rule id=&quot;digits&quot; scope=&quot;public&quot;&gt;
&lt;item repeat=&quot;0-1&quot;&gt;
&lt;one-of&gt;
&lt;item&gt;0&lt;/item&gt;
&lt;item&gt;1&lt;/item&gt;
&lt;item&gt;2&lt;/item&gt;
&lt;item&gt;3&lt;/item&gt;
&lt;item&gt;4&lt;/item&gt;
&lt;item&gt;5&lt;/item&gt;
&lt;item&gt;6&lt;/item&gt;
&lt;item&gt;7&lt;/item&gt;
&lt;item&gt;8&lt;/item&gt;
&lt;item&gt;9&lt;/item&gt;
&lt;item&gt;#&lt;/item&gt;
&lt;item&gt;*&lt;/item&gt;
&lt;/one-of&gt;
&lt;/item&gt;
&lt;/rule&gt;
&lt;/grammar&gt;</grammar></input><error type='cancel'><item-not-found xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>
2014-12-07 10:11:12.757808 [DEBUG] mod_rayo.c:779 Release usera@XXX.XXX.31.185/localhost.localdomain-19448: ref count = 1
292d15e6-7df9-11e4-8190-a566d4f640ff 2014-12-07 10:11:12.757808 [DEBUG] xmpp_streams.c:190 usera@XXX.XXX.31.185/localhost.localdomain-19448, XXX.XXX.31.185:43550, c2s_in SEND <iq type=error to=usera@XXX.XXX.31.185/localhost.localdomain-19448 id=59077bd0-80d2-4616-8785-e79ee10b6809 from=2d4559f4-7df9-11e4-8191-a566d4f640ff@XXX.XXX.31.185><input xmlns=urn:xmpp:rayo:input:1 max-silence=50000000 min-confidence=0.5 mode=dtmf language=en-US initial-timeout=50000000 inter-digit-timeout=50000000><grammar content-type=application/srgs+xml>&lt;grammar xmlns=&quot;http://www.w3.org/2001/06/grammar&quot; version=&quot;1.0&quot; xml:lang=&quot;en-US&quot; mode=&quot;dtmf&quot; root=&quot;digits&quot;&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;rule id=&quot;digits&quot; scope=&quot;public&quot;&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item repeat=&quot;0-1&quot;&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;one-of&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;0&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;1&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;2&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;3&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;4&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;5&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;6&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;7&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;8&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;9&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;#&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;item&gt;*&lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;/one-of&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;/item&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;/rule&gt;
292d15e6-7df9-11e4-8190-a566d4f640ff &lt;/grammar&gt;</grammar></input><error type=cancel><item-not-found xmlns=urn:ietf:params:xml:ns:xmpp-stanzas/></error></iq>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment