Matrioska not receiving commands

28 views
Skip to first unread message

Tyler Gannon

unread,
Sep 19, 2014, 10:26:09 PM9/19/14
to adhea...@googlegroups.com

Hi! Thanks for earlier help pointing me to Matrioska for in-call app functionality. I’ve configured it for the simplest app functionality I can, just in hopes of getting a working demo. No luck yet and I’m hoping I can get some pointers on where to look for the trouble.

The present app simply receives the call, dials out, and then listens for a “3” .
The call connects and Matrioska starts, but nothing happens when I dial numbers on either phone.

I’d very much appreciate your troubleshooting assistance!

Here’s what I’ve got.

My Controller:

require 'matrioska/dial_with_apps'

class BadassCallController < Adhearsion::CallController
  include Matrioska::DialWithApps

  def run
    logger.warn "TESTTEST"
    answer
    dial_with_local_apps 'Sip/5266999985/15199915334' do |runner, dial|
      runner.map_app(3) {
        raise "SOMETHNG HAPPENED"
      }
    end
  end
end

Gemfile

source 'https://rubygems.org'

gem 'activesupport', '>= 4.1'
gem "adhearsion", github: 'adhearsion/adhearsion', branch: 'develop'
gem 'adhearsion-asterisk'
gem 'celluloid', '0.15'
gem 'matrioska', github: 'adhearsion/matrioska', branch: 'develop'

Gemfile.lock

PATH
  remote: plugins/httparty_plugin
  specs:
    httparty_plugin (0.0.1)
      activesupport (>= 3.0.10)
      adhearsion (>= 2.3)
      httparty (>= 0.13)

PATH
  remote: plugins/metadata_plugin
  specs:
    metadata_plugin (0.0.1)
      activesupport (>= 3.0.10)
      adhearsion (>= 2.3)
      hashie (>= 2.0.5)

GEM
  remote: https://rubygems.org/
  specs:
    activesupport (3.2.19)
      i18n (~> 0.6, >= 0.6.4)
      multi_json (~> 1.0)
    adhearsion (2.3.5)
      activesupport (~> 3.0)
      adhearsion-loquacious (~> 1.9)
      bundler (~> 1.0)
      celluloid (~> 0.14)
      countdownlatch
      deep_merge
      ffi (~> 1.0)
      girl_friday
      has-guarded-handlers (~> 1.5)
      logging (~> 1.8)
      pry
      punchblock (~> 1.4)
      rake
      ruby_speech (~> 1.0)
      thor (~> 0.18.0)
    adhearsion-asterisk (1.5.1)
      adhearsion (~> 2.0)
    adhearsion-loquacious (1.9.3)
    blather (1.0.0)
      activesupport (>= 2.3.11)
      eventmachine (>= 1.0.0)
      girl_friday
      niceogiri (~> 1.0)
      nokogiri (~> 1.5, >= 1.5.6)
    celluloid (0.14.0)
      timers (>= 1.0.0)
    celluloid-io (0.14.0)
      celluloid (>= 0.13.0)
      nio4r (>= 0.4.5)
    coderay (1.1.0)
    connection_pool (1.2.0)
    countdownlatch (1.0.0)
    deep_merge (1.0.1)
    eventmachine (1.0.3)
    ffi (1.9.3)
    future-resource (1.1.0)
    girl_friday (0.11.2)
      connection_pool (~> 1.0)
      rubinius-actor
    has-guarded-handlers (1.6.0)
    hashie (3.3.1)
    hitimes (1.2.2)
    httparty (0.13.1)
      json (~> 1.8)
      multi_xml (>= 0.5.2)
    i18n (0.6.11)
    json (1.8.1)
    little-plugger (1.1.3)
    logging (1.8.2)
      little-plugger (>= 1.1.3)
      multi_json (>= 1.8.4)
    matrioska (0.0.2)
      activesupport (>= 3.0.10)
      adhearsion (~> 2.1)
    method_source (0.8.2)
    mini_portile (0.6.0)
    multi_json (1.10.1)
    multi_xml (0.5.5)
    niceogiri (1.1.2)
      nokogiri (~> 1.5)
    nio4r (1.0.1)
    nokogiri (1.6.3.1)
      mini_portile (= 0.6.0)
    pry (0.10.1)
      coderay (~> 1.1.0)
      method_source (~> 0.8.1)
      slop (~> 3.4)
    punchblock (1.9.4)
      activesupport (~> 3.0)
      blather (>= 0.7.0)
      celluloid (~> 0.14)
      future-resource (~> 1.0)
      has-guarded-handlers (~> 1.5)
      niceogiri (~> 1.1)
      nokogiri (~> 1.5, >= 1.5.6)
      ruby_ami (~> 2.0)
      ruby_fs (~> 1.1)
      ruby_speech (~> 1.0)
      state_machine (~> 1.0)
    rake (10.3.2)
    rubinius-actor (0.0.2)
      rubinius-core-api
    rubinius-core-api (0.0.1)
    ruby_ami (2.2.1)
      celluloid-io (~> 0.13)
    ruby_fs (1.1.1)
      celluloid-io (~> 0.13)
    ruby_speech (1.1.0)
      activesupport (>= 3.0.7)
      niceogiri (~> 1.1, >= 1.1.1)
      nokogiri (~> 1.5, >= 1.5.6)
    slop (3.6.0)
    state_machine (1.2.0)
    thor (0.18.1)
    timers (4.0.1)
      hitimes

PLATFORMS
  ruby

DEPENDENCIES
  adhearsion (= 2.3.5)
  adhearsion-asterisk
  celluloid (= 0.14)
  httparty_plugin!
  matrioska
  metadata_plugin!
  punchblock (= 1.9.4)

AHN Log


[2014-09-19 19:16:22.069] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Unjoined target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, call_uri="323ad83e-ac51-4fcf-9a0d-6729947b30de", mixer_name=nil> 
[2014-09-19 19:16:22.070] DEBUG Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="323ad83e-ac51-4fcf-9a0d-6729947b30de", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="HangupRequest", headers={"Privilege"=>"call,all", "Channel"=>"SIP/5266283985-000000a1", "Uniqueid"=>"1411179316.185"}> 
[2014-09-19 19:16:22.069] INFO  Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Unjoined from call 323ad83e-ac51-4fcf-9a0d-6729947b30de 
[2014-09-19 19:16:22.071] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="Bridge", headers={"Privilege"=>"call,all", "Bridgestate"=>"Unlink", "Bridgetype"=>"core", "Channel1"=>"SIP/5266283985-000000a2", "Channel2"=>"SIP/5266283985-000000a1", "Uniqueid1"=>"1411179308.184", "Uniqueid2"=>"1411179316.185", "CallerID1"=>"SIP/6617480240", "CallerID2"=>"6617480240"}> 
[2014-09-19 19:16:22.072] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/5266283985-000000a2", "CommandId"=>"143305576", "Command"=>"EXEC Bridge \"SIP/5266283985-000000a1,F(adhearsion-redirect,1,1)\"", "ResultCode"=>"200", "Result"=>"Success"}> 
[2014-09-19 19:16:22.073] DEBUG Adhearsion::CallController::Dial::ParallelConfirmationDial: 5f44f215-7348-47df-ae4b-a3ff075cb189: Main calls were completed, waiting for any added calls: [#<CountDownLatch:0x00000002544ff0 @count=0, @mutex=#<Mutex:0x00000002544fc8>, @conditional=#<Thread::ConditionVariable:0x00000002544fa0>>] 
[2014-09-19 19:16:22.073] DEBUG Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="323ad83e-ac51-4fcf-9a0d-6729947b30de", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="Bridge", headers={"Privilege"=>"call,all", "Bridgestate"=>"Unlink", "Bridgetype"=>"core", "Channel1"=>"SIP/5266283985-000000a2", "Channel2"=>"SIP/5266283985-000000a1", "Uniqueid1"=>"1411179308.184", "Uniqueid2"=>"1411179316.185", "CallerID1"=>"SIP/6617480240", "CallerID2"=>"6617480240"}> 
[2014-09-19 19:16:22.074] DEBUG Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Receiving message: #<Punchblock::Event::Unjoined target_call_id="323ad83e-ac51-4fcf-9a0d-6729947b30de", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, call_uri="341b2e0a-74c3-4f91-835d-c37a1759c2dc", mixer_name=nil> 
[2014-09-19 19:16:22.074] DEBUG Adhearsion::CallController::Dial::ParallelConfirmationDial: 5f44f215-7348-47df-ae4b-a3ff075cb189: All calls were completed, unblocking. 
[2014-09-19 19:16:22.074] DEBUG Matrioska::AppRunner: MATRIOSKA HANDLING INPUT 
[2014-09-19 19:16:22.075] DEBUG Matrioska::AppRunner: MATRIOSKA STARTING LISTENER 
[2014-09-19 19:16:22.073] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/5266283985-000000a2", "Variable"=>"BRIDGERESULT", "Value"=>"SUCCESS", "Uniqueid"=>"1411179308.184"}> 
[2014-09-19 19:16:22.076] INFO  Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Unjoined from call 341b2e0a-74c3-4f91-835d-c37a1759c2dc 
[2014-09-19 19:16:22.077] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"SIP/5266283985-000000a2", "CommandID"=>"2d70a5ba-ab96-4b95-8fe9-5489f2fec117", "Result"=>"200%20result%3D0%0A"}> 
[2014-09-19 19:16:22.077] DEBUG Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="323ad83e-ac51-4fcf-9a0d-6729947b30de", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"SIP/5266283985-000000a1", "Uniqueid"=>"1411179316.185", "CallerIDNum"=>"6617480240", "CallerIDName"=>"SKYPE CALLER ", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "AccountCode"=>"", "Cause"=>"16", "Cause-txt"=>"Normal Clearing"}> 
[2014-09-19 19:16:22.078] DEBUG Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Receiving message: #<Punchblock::Event::End target_call_id="323ad83e-ac51-4fcf-9a0d-6729947b30de", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, headers={}, reason=:hungup, platform_code="16"> 
[2014-09-19 19:16:22.079] INFO  Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Call ended due to hungup 
[2014-09-19 19:16:22.079] DEBUG Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Executing command #<Punchblock::Component::Input target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, request_id="5c71d0b7-c8ea-4a51-bada-4bf2c8d21b2d", max_silence=nil, min_confidence=nil, mode=:dtmf, recognizer=nil, language=nil, terminator=nil, sensitivity=nil, initial_timeout=nil, inter_digit_timeout=2000, grammars=[#<Punchblock::Component::Input::Grammar target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="options"> 
  <rule id="options" scope="public"> 
    <one-of> 
      <item>3</item> 
    </one-of> 
  </rule> 
</grammar>, content_type="application/srgs+xml", url=nil>]> 
[2014-09-19 19:16:22.080] INFO  Adhearsion::CallController::Dial::ParallelConfirmationDial: 5f44f215-7348-47df-ae4b-a3ff075cb189: #dial finished. Hanging up 1 outbound calls which are still active: 341b2e0a-74c3-4f91-835d-c37a1759c2dc. 
[2014-09-19 19:16:22.081] DEBUG Adhearsion::CallController::Dial::ParallelConfirmationDial: 5f44f215-7348-47df-ae4b-a3ff075cb189: Root call ended, unblocking connected calls 
[2014-09-19 19:16:22.080] INFO  Adhearsion::CallController::Dial::ParallelConfirmationDial: 5f44f215-7348-47df-ae4b-a3ff075cb189: #dial hanging up 341b2e0a-74c3-4f91-835d-c37a1759c2dc 
[2014-09-19 19:16:22.081] INFO  Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Hanging up 
[2014-09-19 19:16:22.083] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, request_id="af76fbc6-2fd7-4657-965e-b7a9828e7146", headers={}> 
[2014-09-19 19:16:22.085] DEBUG Matrioska::AppRunner: Matrioska terminated because the call was disconnected 
[2014-09-19 19:16:22.086] WARN  Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 323ad83e-ac51-4fcf-9a0d-6729947b30de: #<Punchblock::Event::Complete target_call_id="323ad83e-ac51-4fcf-9a0d-6729947b30de", target_mixer_name=nil, component_id="a0aad2c6-dde1-4402-89a8-765bd4c8bff2", source_uri="a0aad2c6-dde1-4402-89a8-765bd4c8bff2", domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name=:hangup>, recording=nil, fax=nil, fax_metadata={}> 
[2014-09-19 19:16:22.090] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/5266283985-000000a2"}> 
[2014-09-19 19:16:22.092] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/5266283985-000000a2", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1411179308.184"}> 
[2014-09-19 19:16:22.093] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="SoftHangupRequest", headers={"Privilege"=>"call,all", "Channel"=>"SIP/5266283985-000000a2", "Uniqueid"=>"1411179308.184", "Cause"=>"16"}> 
[2014-09-19 19:16:22.093] DEBUG Adhearsion::Call: 323ad83e-ac51-4fcf-9a0d-6729947b30de@: Finished executing controller #<BadassCallController call=323ad83e-ac51-4fcf-9a0d-6729947b30de, metadata={}> 
[2014-09-19 19:16:22.094] INFO  Adhearsion::Router::Route: Call 323ad83e-ac51-4fcf-9a0d-6729947b30de routing completed. Call was already hung up. 
[2014-09-19 19:16:22.093] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::End target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, headers={}, reason=:hangup_command, platform_code="16"> 
[2014-09-19 19:16:22.095] INFO  Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Call ended due to hangup_command 
[2014-09-19 19:16:22.095] DEBUG Adhearsion::OutboundCall: 341b2e0a-74c3-4f91-835d-c37a1759c2dc@: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event target_call_id="341b2e0a-74c3-4f91-835d-c37a1759c2dc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Fri, 19 Sep 2014 19:16:22 -0700, name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"SIP/5266283985-000000a2", "Uniqueid"=>"1411179308.184", "CallerIDNum"=>"SIP/6617480240", "CallerIDName"=>"SKYPE CALLER", "ConnectedLineNum"=>"SIP/6617480240", "ConnectedLineName"=>"SKYPE CALLER", "AccountCode"=>"", "Cause"=>"16", "Cause-txt"=>"Normal Clearing"}>

Ben Langfeld

unread,
Sep 19, 2014, 10:43:50 PM9/19/14
to adhea...@googlegroups.com
Matrioska cannot work on Asterisk (at least =< 11) because Asterisk fails to emit DTMF events during a bridged call. This is a known Asterisk bug. It may be fixed in Asterisk 12, but I can't be sure.


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

Tyler Gannon

unread,
Sep 19, 2014, 10:45:29 PM9/19/14
to adhea...@googlegroups.com, Colin Cook
Thanks!!

--
You received this message because you are subscribed to a topic in the Google Groups "Adhearsion" group.
To unsubscribe from this topic, visit https://groups.google.com/d/topic/adhearsion/Zi4jql6ThTQ/unsubscribe.
To unsubscribe from this group and all its topics, send an email to adhearsion+...@googlegroups.com.

Tyler Gannon

unread,
Sep 19, 2014, 10:48:49 PM9/19/14
to adhea...@googlegroups.com
Can you recommend an alternative to asterisk, which would handle this functionality?

Ben Langfeld

unread,
Sep 20, 2014, 12:51:53 AM9/20/14
to adhea...@googlegroups.com
FreeSWITCH, our other supported platform. Check Adhearsion.com/docs 


Reply all
Reply to author
Forward
0 new messages