This call is expired and is no longer accessible

[Giorgio Bianchi]

Hi,

When I close adhearsion after days of work, I get many of these exceptions.

1. Adhearsion Log (servername:adhearsion.log): ERROR ControllerName: #<Adhearsion::Call::ExpiredError: This call is expired and is no longer accessible. Seehttp://adhearsion.com/docs/calls for further details.>

Seems that after_call method is called only when then main process stops, but not all the calls are affected.

I know that this error concern an after call end timeout managed by adhearsion on call object but I don't get why it's raised only when I close the main process : it should be raised when it happens.

Adhearsion is running with Freeswitch 1.4.20 vanilla and ruby-2.1.5 with these gems :

GEM

  remote: https://rubygems.org/

  specs:

    activesupport (4.2.2)

      i18n (~> 0.7)

      json (~> 1.7, >= 1.7.7)

      minitest (~> 5.1)

      thread_safe (~> 0.3, >= 0.3.4)

      tzinfo (~> 1.1)

    adhearsion (2.6.2)

      activesupport (>= 3.0.0, < 5.0.0)

      adhearsion-loquacious (~> 1.9)

      bundler (~> 1.0)

      celluloid (~> 0.14)

      countdownlatch

      deep_merge

      ffi (~> 1.0)

      girl_friday

      has-guarded-handlers (~> 1.6)

      logging (~> 1.8)

      pry

      punchblock (~> 2.6)

      rake

      ruby_speech (~> 2.0)

      thor (~> 0.18.0)

    adhearsion-asr (1.4.0)

      adhearsion (~> 2.4)

      punchblock (~> 2.0)

      ruby_speech (~> 2.1)

    adhearsion-drb (1.1.1)

      adhearsion (~> 2.0)

      i18n (>= 0.5.0)

    adhearsion-loquacious (1.9.3)

    axiom-types (0.1.1)

      descendants_tracker (~> 0.0.4)

      ice_nine (~> 0.11.0)

      thread_safe (~> 0.3, >= 0.3.1)

    blather (1.1.4)

      activesupport (>= 2.3.11)

      eventmachine (>= 1.0.0)

      girl_friday

      niceogiri (~> 1.0)

      nokogiri (~> 1.5, >= 1.5.6)

    celluloid (0.15.2)

      timers (~> 1.1.0)

    celluloid-io (0.15.0)

      celluloid (>= 0.15.0)

      nio4r (>= 0.5.0)

    coderay (1.1.0)

    coercible (1.0.0)

      descendants_tracker (~> 0.0.1)

    connection_pool (2.2.0)

    countdownlatch (1.0.0)

    deep_merge (1.0.1)

    descendants_tracker (0.0.4)

      thread_safe (~> 0.3, >= 0.3.1)

    diff-lcs (1.2.5)

    domain_name (0.5.24)

      unf (>= 0.0.5, < 1.0.0)

    em-synchrony (1.0.4)

      eventmachine (>= 1.0.0.beta.1)

    equalizer (0.0.11)

    eventmachine (1.0.7)

    ffi (1.9.8)

    future-resource (1.1.0)

    girl_friday (0.9.5)

    has-guarded-handlers (1.6.2)

    hiredis (0.6.0)

    http-cookie (1.0.2)

      domain_name (~> 0.5)

    i18n (0.7.0)

    ice_nine (0.11.1)

    inifile (3.0.0)

    json (1.8.3)

    little-plugger (1.1.3)

    logging (1.8.2)

      little-plugger (>= 1.1.3)

      multi_json (>= 1.8.4)

    method_source (0.8.2)

    mime-types (2.6.1)

    mini_portile (0.6.2)

    minitest (5.7.0)

    mono_logger (1.1.0)

    multi_json (1.11.1)

    netrc (0.10.3)

    niceogiri (1.1.2)

      nokogiri (~> 1.5)

    nio4r (1.1.0)

    nokogiri (1.6.6.2)

      mini_portile (~> 0.6.0)

    pry (0.10.1)

      coderay (~> 1.1.0)

      method_source (~> 0.8.1)

      slop (~> 3.4)

    punchblock (2.7.0)

      activesupport (>= 3.0.0, < 5.0.0)

      blather (~> 1.0)

      celluloid (~> 0.15.2)

      future-resource (~> 1.0)

      has-guarded-handlers (~> 1.5)

      nokogiri (~> 1.5, >= 1.5.6)

      ruby_ami (~> 2.2)

      ruby_fs (~> 1.1)

      ruby_jid (~> 1.0)

      ruby_speech (~> 2.3)

      state_machine (~> 1.0)

      virtus (~> 1.0)

    rake (10.4.2)

    redis (3.2.1)

    redis-namespace (1.5.2)

      redis (~> 3.0, >= 3.0.4)

    rest-client (1.8.0)

      http-cookie (>= 1.0.2, < 2.0)

      mime-types (>= 1.16, < 3.0)

      netrc (~> 0.7)

    rspec (3.3.0)

      rspec-core (~> 3.3.0)

      rspec-expectations (~> 3.3.0)

      rspec-mocks (~> 3.3.0)

    rspec-core (3.3.0)

      rspec-support (~> 3.3.0)

    rspec-expectations (3.3.0)

      diff-lcs (>= 1.2.0, < 2.0)

      rspec-support (~> 3.3.0)

    rspec-mocks (3.3.0)

      diff-lcs (>= 1.2.0, < 2.0)

      rspec-support (~> 3.3.0)

    rspec-support (3.3.0)

    ruby_ami (2.3.0)

      celluloid-io (~> 0.13)

    ruby_fs (1.1.1)

      celluloid-io (~> 0.13)

    ruby_jid (1.0.0)

    ruby_speech (2.3.2)

      activesupport (>= 3.0.7, < 5.0.0)

      nokogiri (~> 1.6)

    sidekiq (3.2.6)

      celluloid (= 0.15.2)

      connection_pool (>= 2.0.0)

      json

      redis (>= 3.0.6)

      redis-namespace (>= 1.3.1)

    slop (3.6.0)

    sqlite3 (1.3.10)

    state_machine (1.2.0)

    thor (0.18.1)

    thread_safe (0.3.5)

    timeout (0.0.1)

      timeout-extensions

    timeout-extensions (0.0.0)

    timers (1.1.0)

    tzinfo (1.2.2)

      thread_safe (~> 0.1)

    unf (0.1.4)

      unf_ext

    unf_ext (0.0.7.1)

    virtus (1.0.5)

      axiom-types (~> 0.1)

      coercible (~> 1.0)

      descendants_tracker (~> 0.0, >= 0.0.3)

      equalizer (~> 0.0, >= 0.0.9)

Is there any wrong gem version?

Thanks for your attention.

[Ben Langfeld]

Could you provide a full log and whatever code you have related to callbacks on Call objects, please.

--
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.

[Giorgio Bianchi]

Hi Ben,

It's a production server and the log level is info so it's not very usefull as is.

The last log for an affected call is like this :

[2015-09-08 14:48:12.755] INFO  Adhearsion::Call: a5b2ec24-56b2-4fe6...@127.0.0.1: Call sip:[cli]@[ip] -> sip:[number]@[provider] ended due to hungup (code 31)

The first row of the after_call method in the controller is a

class CallController < ::Adhearsion::CallController

    after_call :default_call_end

    def default_call_end

        logger.info "Controller after call method begin"

        ...

    end

end

This info log is not present in the log file for and affected call.

Calls affected are a small number compared to all calls counter during the period.

Should I trap anything else?

Thanks for the support!

[Giorgio Bianchi]

Hi,

After upgrading ruby with all its gems and freeswitch to the latest version, problems are vanished.

So frustrating... :-(

Is there a way to check Adhearsion core features after a gem upgrade?

Thanks for the support!

Il giorno mercoledì 9 settembre 2015 09:44:24 UTC+2, Giorgio Bianchi ha scritto:

Hi Ben,

It's a production server and the log level is info so it's not very usefull as is.

The last log for an affected call is like this :

[2015-09-08 14:48:12.755] INFO  Adhearsion::Call: a5b2ec24-56b2-4fe6-a615-3b4d6e...@127.0.0.1: Call sip:[cli]@[ip] -> sip:[number]@[provider] ended due to hungup (code 31)

[Ben Langfeld]

Enviado do meu iPhone

Em 17 Sep 2015, às 06:33, Giorgio Bianchi <gggg.b...@gmail.com> escreveu:

Hi,

After upgrading ruby with all its gems and freeswitch to the latest version, problems are vanished.

So frustrating... :-(

Is there a way to check Adhearsion core features after a gem upgrade?

Just test your app as you usually would. Any Adhearsion gem dependencies would have been tested upstream.

[Giorgio Bianchi]

Hi,

I was too optimistic.

The problem persist, this is the example :

[2015-11-09 15:01:51.115] INFO  Adhearsion::Call: dd502f47-63fc-480e...@127.0.0.1: Controller start

[2015-11-09 15:01:51.116] INFO  Adhearsion::Call: dd502f47-63fc-480e...@127.0.0.1: Controller run

[2015-11-09 15:02:14.961] INFO  Adhearsion::Call: dd502f47-63fc-480e...@127.0.0.1: Call sip:[from_number]@[from_ip] -> sip:[to_number]@[to_ip] ended due to hungup (code 16)

... I was expecting the log Controller stop here ...

[2015-11-09 16:20:37.476] INFO  Adhearsion::Initializer: Received SIGTERM. Shutting down.

[2015-11-09 16:20:37.528] INFO  CallController: Controller stop (??? why here ???)

[2015-11-09 16:20:38.583] INFO  Adhearsion::Router::Route: Call dd502f47-63fc-480e-9b68-5966c2ac7a16 routing completed. Call was already hung up.

class CallController < ::Adhearsion::CallController

    before_call :default_call_start

    after_call :default_call_end

    def run

        logger.info "Controller run"

        answer

        play "music.wav"

        hangup

    end

    def default_call_start

        logger.info "Controller start"

    end

    def default_call_end

        logger.info "Controller stop"

    end

end

As i said the problem isn't predictable : most of times all works well.

Which log should I add to find what's going on and why after_call sometimes is called when ahn stop?

Thanks for the support

[Ben Langfeld]

On Mon, 23 Nov 2015 at 09:00 Giorgio Bianchi <gggg.b...@gmail.com> wrote:

Hi all,

I've traced additional logs with a simple call controller

The controller ask a digit and play the audio with the digit received.

Adhearsion 2.6.2 with all latest versions of gems on latest version of Freeswitch on Debian Wheezy

The server is a 8-core with 8gb ram and top output data show no difficulties during the test.

Sending 400 calls from another server, max 40 concurrently (each one started with a delay of 0.4 seconds) 11 has the problem.

module LoadTest

  class Inbound < ::Adhearsion::CallController

    

    before_call :default_call_start

    after_call :default_call_end

    

    def initialize(call, metadata = nil, &block)

      @call_id = String.new(call.id)

      super

    end

    

    def run

      answer

      logger.info "Start inbound [#{call.from}] [#{call.to}]"

      i = 0

      tmp_audio_file = "white_noise.wav"

      while i<3

tmp_res = ask tmp_audio_file, :timeout => 5.seconds, :limit => 1

tmp_sel = tmp_res.to_s

if (!tmp_sel.nil?)

 i = 0

 logger.info "Received [#{tmp_sel}]"

 play "white_noise.wav"

 tmp_audio_file = "#{tmp_sel}.wav"

else

 logger.info "Empty"

 i = i + 1

end

      end

      hangup

    end

    

    def default_call_start

      logger.info "[#{@call_id}] !!!Call ringing from #{call.from} to #{call.to} controlled by #{self.class}!!!"

    end

    

    def default_call_end

      begin

        logger.info "[#{@call_id}] Controller after call method begin"

        unless call.alive?

          raise "The call isn't alive"

        end

      rescue => e

        logger.error "[#{@call_id}] #{e.inspect}"

      ensure

        logger.info "[#{@call_id}] !!!Call ended!!!"

      end

    end

    

  end

end

This is the log with also Punchblock events

[2015-11-23 11:43:53.273] INFO  Adhearsion::Events: #<Punchblock::Event::Offer target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"from"=>"<sip:[caller_number]@[remote_server]>;tag=ztesipaN8dlAMfsBKrJptGE*2-1-20481*eaaj.2", "to"=>"<sip:[called_number]@[local_server]>", "via"=>"SIP/2.0/UDP [local_server]:5060;branch=z9hG4bKplh5jj200gb13ojehlc0.1", "P-Early-Media"=>"supported"}, to="sip:[called_number]@[local_server]", from="sip:[caller_number]@[remote_server]">

[2015-11-23 11:43:53.276] INFO  Adhearsion::Router: Call 275fb2eb-6e31-4297-8d29-8bb008da2220 selected route "inbound" (#<Proc:0x000000041cd5c8@/srv/freeswitch/myapp/config/routes.rb:7>)

[2015-11-23 11:43:53.303] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Executing controller #<Adhearsion::CallController call=275fb2eb-6e31-4297-8d29-8bb008da2220, metadata={}>

[2015-11-23 11:43:53.310] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call ringing from sip:[caller_number]@[remote_server] to sip:[called_number]@[local_server] controlled by LoadTest::Inbound!!!

[2015-11-23 11:43:53.332] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call answered!!!

[2015-11-23 11:43:53.333] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Start inbound [sip:[caller_number]@[remote_server]] [sip:[called_number]@[local_server]]

[2015-11-23 11:43:53.346] INFO  Adhearsion::Events: #<Punchblock::Event::Answered target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"P-Early-Media"=>"supported"}>

[2015-11-23 11:43:54.384] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:54 +0000>

[2015-11-23 11:43:57.632] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +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=Mon, 23 Nov 2015 11:43:57 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1e49e6c name="result" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1cef7d8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ceefa4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1ceee78 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1ceee28 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1ce5ef4 "0">]>, #<Nokogiri::XML::Element:0x1ce579c name="instance" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1cdbd64 "0">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.772] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-4", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-4", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:57 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.786] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [0]

[2015-11-23 11:43:58.905] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-5", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-5", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:58 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.027] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-6", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-6", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:59 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.552] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:59 +0000>

[2015-11-23 11:44:01.979] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:01 +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=Mon, 23 Nov 2015 11:44:01 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21bd594 name="result" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2199518 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2198d84 name="input" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2198820 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21987e4 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2196fd4 "1">]>, #<Nokogiri::XML::Element:0x2196a98 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x219605c "1">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.118] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-10", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-10", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:02 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:02 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.135] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [1]

[2015-11-23 11:44:03.253] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-11", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-11", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.349] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-12", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-12", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.945] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000>

[2015-11-23 11:44:06.314] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44: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=Mon, 23 Nov 2015 11:44:06 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x220ff9c name="result" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x22007b8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2200510 name="input" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x21fbba0 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21fbb8c name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x21fab88 "2">]>, #<Nokogiri::XML::Element:0x22001f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x22001a0 "2">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-16", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-16", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:06 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:06 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [2]

[2015-11-23 11:44:07.432] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-17", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-17", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:07.569] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-18", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-18", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:08.106] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:08 +0000>

[2015-11-23 11:44:10.498] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:10 +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=Mon, 23 Nov 2015 11:44:10 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21c0c6c name="result" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x21b8f08 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x21b897c name="input" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x21b88dc name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21b88c8 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x21b7680 "3">]>, #<Nokogiri::XML::Element:0x21b72c0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x21b6a64 "3">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:10.712] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-22", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-22", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:10 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:10 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:10.723] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [3]

[2015-11-23 11:44:11.824] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-23", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-23", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:11 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:11 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:11.972] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-24", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-24", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:11 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:11 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:12.520] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-25", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-25", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:12 +0000>

[2015-11-23 11:44:14.934] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-25", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-25", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:14 +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=Mon, 23 Nov 2015 11:44:14 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1224b8c name="result" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1197610 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1196b70 name="input" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x115d398 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x115d370 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1156584 "4">]>, #<Nokogiri::XML::Element:0x1196468 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x11961e8 "4">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:15.055] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-28", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-28", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:15 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:15 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:15.055] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [4]

[2015-11-23 11:44:16.184] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-29", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-29", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:16 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:16 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:16.258] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-30", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-30", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:16 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:16 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:16.925] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-31", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-31", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:16 +0000>

[2015-11-23 11:44:19.127] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-31", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-31", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:19 +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=Mon, 23 Nov 2015 11:44:19 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21552f0 name="result" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x214f210 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x214ee28 name="input" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2147858 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x2147844 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2146d90 "5">]>, #<Nokogiri::XML::Element:0x214e9f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x214e9b4 "5">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:19.217] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [5]

[2015-11-23 11:44:19.219] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-34", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-34", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:19 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:19 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:20.289] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-35", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-35", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:20 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:20 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:20.446] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-36", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-36", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:20 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:20 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:21.014] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-37", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-37", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:20 +0000>

[2015-11-23 11:44:23.145] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-37", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-37", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:23 +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=Mon, 23 Nov 2015 11:44:23 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1ea440c name="result" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1e7b4f8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1e7aea4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1e64974 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1e64960 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1e5f53c "6">]>, #<Nokogiri::XML::Element:0x1e7a800 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1e7a760 "6">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:23.243] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-40", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-40", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:23 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:23 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:23.243] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [6]

[2015-11-23 11:44:24.308] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-41", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-41", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:24 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:24 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:24.421] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-42", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-42", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:24 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:24 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:25.019] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-43", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-43", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:24 +0000>

[2015-11-23 11:44:27.415] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-43", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-43", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:27 +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=Mon, 23 Nov 2015 11:44:27 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21626f8 name="result" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x213c50c name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x213bc9c name="input" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2136cc4 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x2136cb0 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2135f18 "7">]>, #<Nokogiri::XML::Element:0x213b8f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x213b850 "7">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:27.594] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-46", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-46", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:27 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:27 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:44.923] INFO  Adhearsion::Events: #<Punchblock::Event::End target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:44 +0000, headers={"P-Early-Media"=>"supported"}, reason=:hungup, platform_code="16">

[2015-11-23 11:44:44.924] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Call sip:[caller_number]@[remote_server] -> sip:[called_number]@[local_server] ended due to hungup (code 16)

[2015-11-23 11:59:17.725] INFO  Adhearsion::Initializer: Received SIGINT. Shutting down.

[2015-11-23 11:59:17.726] INFO  Adhearsion::Process: Transitioning from running to stopping with 0 active calls due to shutdown event.

[2015-11-23 11:59:17.727] INFO  Adhearsion::PunchblockPlugin::Initializer: Shutting down while connecting. Breaking the connection block.

[2015-11-23 11:59:17.728] INFO  Adhearsion::Process: Adhearsion shut down

[2015-11-23 11:59:17.737] INFO  LoadTest::Inbound: [275fb2eb-6e31-4297-8d29-8bb008da2220] Controller after call method begin

[2015-11-23 11:59:17.738] ERROR LoadTest::Inbound: [275fb2eb-6e31-4297-8d29-8bb008da2220] #<RuntimeError: The call isn't alive>

[2015-11-23 11:59:17.748] INFO  LoadTest::Inbound: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call ended!!!

[2015-11-23 11:59:17.749] INFO  Adhearsion::Router::Route: Call 275fb2eb-6e31-4297-8d29-8bb008da2220 routing completed. Call was already hung up.

To trace punchblock events :

Adhearsion::Events.draw do

  punchblock do |event|
    logger.info event.inspect
  end

end

A question : what should trigger the after call method? I thought it was the punchblock event.

This would be at https://github.com/adhearsion/adhearsion/blob/develop/lib/adhearsion/call_controller.rb#L124, when the controller execution terminates. If your controller hangs, then this would not happen until something otherwise terminates it.

 

Which information should i track down?

You've provided good info; I think what is missing right now is for me to reproduce and debug it. Unfortunately that won't be until the end of this week (I've been on vacation) but I'll do my best :)

 

Please help me.

Giorgio

Thanks for the support!

[2015-09-08 14:48:12.755] INFO  Adhearsion::Call: a5b2ec24-56b2-4fe6...@127.0.0.1: Call sip:[cli]@[ip] -> sip:[number]@[provider] ended due to hungup (code 31)

[Giorgio Bianchi]

Hi all,

I've been able to track down a more complete log with this call controller.

The controller ask a digit and play a wave (e.g. digit 1 pressed, 1.wav played) and then loop : while caller send digits it loop.

Attached to this message there's the log with Punchblock events tracked.

I thought after call method was connected to punchblock call end event but I was wrong.

Which data should I track down to go deeper into the problem?

I'm using Adhearsion 2.6.2 with all gems at latest version, Freeswitch at latest version and the server is a 8-core with 8gb ram running Debian Wheezy at latest version.

With 400 inbound calls, max 40 calls simultaneously, each call delayed by 0.4 seconds, 11 calls had this behavior.

[Giorgio Bianchi]

Hi Ben,

Have you been able to reproduce this?

Do you need further information?

Thanks for the support.

Giorgio

[2015-11-23 11:43:53.273] INFO  Adhearsion::Events: #<Punchblock::Event::Offer target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"from"=>"<sip:[caller_number]@[remote_server]>;tag=ztesipaN8dlAMfsBKrJptGE*2-1-20481*eaaj.2", "to"=>"<sip:[called_number]@[local_server]>", "via"=>"SIP/2.0/UDP [local_server]:5060;branch=z9hG4bKplh5jj200gb13ojehlc0.1", "P-Early-Media"=>"supported"}, to="sip:[called_number]@[local_server]", from="sip:[caller_number]@[remote_server]">

[2015-11-23 11:43:53.276] INFO  Adhearsion::Router: Call 275fb2eb-6e31-4297-8d29-8bb008da2220 selected route "inbound" (#<Proc:0x000000041cd5c8@/srv/freeswitch/myapp/config/routes.rb:7>)

[2015-11-23 11:43:53.303] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Executing controller #<Adhearsion::CallController call=275fb2eb-6e31-4297-8d29-8bb008da2220, metadata={}>

[2015-11-23 11:43:53.310] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call ringing from sip:[caller_number]@[remote_server] to sip:[called_number]@[local_server] controlled by LoadTest::Inbound!!!

[2015-11-23 11:43:53.332] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call answered!!!

[2015-11-23 11:43:53.333] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Start inbound [sip:[caller_number]@[remote_server]] [sip:[called_number]@[local_server]]

[2015-11-23 11:43:53.346] INFO  Adhearsion::Events: #<Punchblock::Event::Answered target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"P-Early-Media"=>"supported"}>

[2015-11-23 11:43:54.384] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:54 +0000>

[2015-11-23 11:43:57.632] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +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=Mon, 23 Nov 2015 11:43:57 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1e49e6c name="result" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1cef7d8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ceefa4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1ceee78 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1ceee28 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1ce5ef4 "0">]>, #<Nokogiri::XML::Element:0x1ce579c name="instance" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1cdbd64 "0">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.772] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-4", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-4", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:57 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.786] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [0]

[2015-11-23 11:43:58.905] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-5", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-5", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:58 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.027] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-6", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-6", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:59 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.552] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:59 +0000>

[2015-11-23 11:44:01.979] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:01 +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=Mon, 23 Nov 2015 11:44:01 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21bd594 name="result" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2199518 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2198d84 name="input" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2198820 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21987e4 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2196fd4 "1">]>, #<Nokogiri::XML::Element:0x2196a98 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x219605c "1">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.118] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-10", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-10", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:02 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:02 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.135] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [1]

[2015-11-23 11:44:03.253] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-11", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-11", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.349] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-12", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-12", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.945] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000>

[2015-11-23 11:44:06.314] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44: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=Mon, 23 Nov 2015 11:44:06 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x220ff9c name="result" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x22007b8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2200510 name="input" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x21fbba0 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21fbb8c name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x21fab88 "2">]>, #<Nokogiri::XML::Element:0x22001f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x22001a0 "2">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-16", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-16", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:06 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:06 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [2]

[2015-11-23 11:44:07.432] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-17", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-17", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:07.569] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-18", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-18", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:08.106] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:08 +0000>

[2015-11-23 11:44:10.498] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:10 +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=Mon, 23 Nov 2015 11:44:10 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21c0c6c name="result" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x21b8f08 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x21b897c name="input" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x21b88dc name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21b88c8 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x21b7680 "3">]>, #<Nokogiri::XML::Element:0x21b72c0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21c0c44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x21b6a64 "3">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:10.712] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-22", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-22", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:10 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:10 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:10.723] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [3]

[2015-11-23 11:44:11.824] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-23", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-23", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:11 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:11 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:11.972] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-24", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-24", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:11 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:11 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:12.520] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-25", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-25", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:12 +0000>

[2015-11-23 11:44:14.934] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-25", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-25", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:14 +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=Mon, 23 Nov 2015 11:44:14 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1224b8c name="result" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1197610 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1196b70 name="input" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x115d398 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x115d370 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1156584 "4">]>, #<Nokogiri::XML::Element:0x1196468 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1224b28 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x11961e8 "4">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:15.055] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-28", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-28", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:15 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:15 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:15.055] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [4]

[2015-11-23 11:44:16.184] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-29", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-29", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:16 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:16 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:16.258] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-30", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-30", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:16 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:16 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:16.925] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-31", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-31", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:16 +0000>

[2015-11-23 11:44:19.127] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-31", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-31", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:19 +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=Mon, 23 Nov 2015 11:44:19 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21552f0 name="result" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x214f210 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x214ee28 name="input" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2147858 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x2147844 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2146d90 "5">]>, #<Nokogiri::XML::Element:0x214e9f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21552c8 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x214e9b4 "5">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:19.217] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [5]

[2015-11-23 11:44:19.219] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-34", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-34", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:19 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:19 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:20.289] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-35", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-35", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:20 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:20 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:20.446] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-36", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-36", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:20 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:20 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:21.014] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-37", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-37", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:20 +0000>

[2015-11-23 11:44:23.145] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-37", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-37", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:23 +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=Mon, 23 Nov 2015 11:44:23 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1ea440c name="result" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1e7b4f8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1e7aea4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1e64974 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1e64960 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1e5f53c "6">]>, #<Nokogiri::XML::Element:0x1e7a800 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1ea43d0 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1e7a760 "6">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:23.243] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-40", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-40", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:23 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:23 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:23.243] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [6]

[2015-11-23 11:44:24.308] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-41", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-41", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:24 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:24 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:24.421] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-42", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-42", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:24 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:24 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:25.019] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-43", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-43", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:24 +0000>

[2015-11-23 11:44:27.415] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-43", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-43", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:27 +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=Mon, 23 Nov 2015 11:44:27 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21626f8 name="result" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x213c50c name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x213bc9c name="input" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2136cc4 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x2136cb0 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2135f18 "7">]>, #<Nokogiri::XML::Element:0x213b8f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21626bc href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x213b850 "7">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:27.594] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-46", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-46", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:27 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:27 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:44.923] INFO  Adhearsion::Events: #<Punchblock::Event::End target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:44 +0000, headers={"P-Early-Media"=>"supported"}, reason=:hungup, platform_code="16">

[2015-11-23 11:44:44.924] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Call sip:[caller_number]@[remote_server] -> sip:[called_number]@[local_server] ended due to hungup (code 16)

[2015-11-23 11:59:17.725] INFO  Adhearsion::Initializer: Received SIGINT. Shutting down.

[2015-11-23 11:59:17.726] INFO  Adhearsion::Process: Transitioning from running to stopping with 0 active calls due to shutdown event.

[2015-11-23 11:59:17.727] INFO  Adhearsion::PunchblockPlugin::Initializer: Shutting down while connecting. Breaking the connection block.

[2015-11-23 11:59:17.728] INFO  Adhearsion::Process: Adhearsion shut down

[2015-11-23 11:59:17.737] INFO  LoadTest::Inbound: [275fb2eb-6e31-4297-8d29-8bb008da2220] Controller after call method begin

[2015-11-23 11:59:17.738] ERROR LoadTest::Inbound: [275fb2eb-6e31-4297-8d29-8bb008da2220] #<RuntimeError: The call isn't alive>

[2015-11-23 11:59:17.748] INFO  LoadTest::Inbound: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call ended!!!

[2015-11-23 11:59:17.749] INFO  Adhearsion::Router::Route: Call 275fb2eb-6e31-4297-8d29-8bb008da2220 routing completed. Call was already hung up.

To trace punchblock events :

Adhearsion::Events.draw do

  punchblock do |event|
    logger.info event.inspect
  end

end

A question : what should trigger the after call method? I thought it was the punchblock event.

This would be at https://github.com/adhearsion/adhearsion/blob/develop/lib/adhearsion/call_controller.rb#L124, when the controller execution terminates. If your controller hangs, then this would not happen until something otherwise terminates it.

 

Which information should i track down?

You've provided good info; I think what is missing right now is for me to reproduce and debug it. Unfortunately that won't be until the end of this week (I've been on vacation) but I'll do my best :)

 

Please help me.

Giorgio

Il giorno martedì 10 novembre 2015 15:54:05 UTC+1, Giorgio Bianchi ha scritto:

Hi,

I was too optimistic.

The problem persist, this is the example :

[2015-11-09 15:01:51.115] INFO  Adhearsion::Call: dd502f47-63fc-480e-9b68-5966c2...@127.0.0.1: Controller start

[2015-11-09 15:01:51.116] INFO  Adhearsion::Call: dd502f47-63fc-480e-9b68-5966c2...@127.0.0.1: Controller run

[2015-11-09 15:02:14.961] INFO  Adhearsion::Call: dd502f47-63fc-480e-9b68-5966c2...@127.0.0.1: Call sip:[from_number]@[from_ip] -> sip:[to_number]@[to_ip] ended due to hungup (code 16)

Thanks for the support!

[2015-09-08 14:48:12.755] INFO  Adhearsion::Call: a5b2ec24-56b2-4fe6-a615-3b4d6e...@127.0.0.1: Call sip:[cli]@[ip] -> sip:[number]@[provider] ended due to hungup (code 31)

[Ben Langfeld]

I had lost track of this one. Did you open a GitHub issue for it? If not, would you please do so, so that I can track it?

Enviado do meu iPhone

[Giorgio Bianchi]

Hi!

Issue opened : https://github.com/adhearsion/adhearsion/issues/597

Thanks!

Giorgio

...

[Giorgio Bianchi]

Hi Ben,

Is there a scheduling to resolve this issue?

Is there anything I can test on my own to solve the problem?

Thanks!

Giorgio.

[Ben Langfeld]

I haven’t had a chance to look into this, nor have I ever experienced it myself. At this point it’s waiting on a community contribution or bounty.

[Giorgio Bianchi]

This problem has been solved by this patch https://github.com/adhearsion/adhearsion/pull/614
I'm currently using this branch in production environment and the problem has disappeared.

Seems that with high traffic load a command sent while call is terminating could lead to lock the callcontroller.

Will you tag a new version in adhearsion repository with this fix?

Giorgio

[Ben Langfeld]

2.6.3 was released.

Giorgio.

[2015-11-23 11:43:53.273] INFO  Adhearsion::Events: #<Punchblock::Event::Offer target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"from"=>"<sip:[caller_number]@[remote_server]>;tag=ztesipaN8dlAMfsBKrJptGE*2-1-20481*eaaj.2", "to"=>"<sip:[called_number]@[local_server]>", "via"=>"SIP/2.0/UDP [local_server]:5060;branch=z9hG4bKplh5jj200gb13ojehlc0.1", "P-Early-Media"=>"supported"}, to="sip:[called_number]@[local_server]", from="sip:[caller_number]@[remote_server]">

[2015-11-23 11:43:53.276] INFO  Adhearsion::Router: Call 275fb2eb-6e31-4297-8d29-8bb008da2220 selected route "inbound" (#<Proc:0x000000041cd5c8@/srv/freeswitch/myapp/config/routes.rb:7>)

[2015-11-23 11:43:53.303] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Executing controller #<Adhearsion::CallController call=275fb2eb-6e31-4297-8d29-8bb008da2220, metadata={}>

[2015-11-23 11:43:53.310] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call ringing from sip:[caller_number]@[remote_server] to sip:[called_number]@[local_server] controlled by LoadTest::Inbound!!!

[2015-11-23 11:43:53.332] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call answered!!!

[2015-11-23 11:43:53.333] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Start inbound [sip:[caller_number]@[remote_server]] [sip:[called_number]@[local_server]]

[2015-11-23 11:43:53.346] INFO  Adhearsion::Events: #<Punchblock::Event::Answered target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"P-Early-Media"=>"supported"}>

[2015-11-23 11:43:54.384] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:54 +0000>

[2015-11-23 11:43:57.632] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +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=Mon, 23 Nov 2015 11:43:57 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1e49e6c name="result" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1cef7d8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ceefa4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1ceee78 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1ceee28 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1ce5ef4 "0">]>, #<Nokogiri::XML::Element:0x1ce579c name="instance" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1cdbd64 "0">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.772] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-4", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-4", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:57 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.786] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [0]

[2015-11-23 11:43:58.905] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-5", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-5", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:58 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.027] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-6", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-6", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:59 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.552] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:59 +0000>

[2015-11-23 11:44:01.979] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:01 +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=Mon, 23 Nov 2015 11:44:01 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21bd594 name="result" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2199518 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2198d84 name="input" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2198820 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21987e4 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2196fd4 "1">]>, #<Nokogiri::XML::Element:0x2196a98 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x219605c "1">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.118] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-10", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-10", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:02 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:02 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.135] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [1]

[2015-11-23 11:44:03.253] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-11", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-11", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.349] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-12", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-12", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.945] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000>

[2015-11-23 11:44:06.314] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44: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=Mon, 23 Nov 2015 11:44:06 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x220ff9c name="result" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x22007b8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2200510 name="input" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x21fbba0 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21fbb8c name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x21fab88 "2">]>, #<Nokogiri::XML::Element:0x22001f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x22001a0 "2">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-16", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-16", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:06 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:06 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Call: 275fb2eb-6e31-4297...@127.0.0.1: Received [2]

[2015-11-23 11:44:07.432] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-17", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-17", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:07.569] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-18", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/output-18", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:08.106] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:08 +0000>

[2015-11-23 11:44:10.498] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297...@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:10 +0000, reason=#<Punchblock::Component::Input::Complete::Match target_call_id=nil, target_mixer_name=nil, component_id=nil, so

...

--
You received this message because you are subscribed to the Google Groups "Adhearsion" group.

[Giorgio Bianchi]

Thank you so much, now all works perfectly.

2.6.3 was released.

Giorgio.

[2015-11-23 11:43:53.273] INFO  Adhearsion::Events: #<Punchblock::Event::Offer target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"from"=>"<sip:[caller_number]@[remote_server]>;tag=ztesipaN8dlAMfsBKrJptGE*2-1-20481*eaaj.2", "to"=>"<sip:[called_number]@[local_server]>", "via"=>"SIP/2.0/UDP [local_server]:5060;branch=z9hG4bKplh5jj200gb13ojehlc0.1", "P-Early-Media"=>"supported"}, to="sip:[called_number]@[local_server]", from="sip:[caller_number]@[remote_server]">

[2015-11-23 11:43:53.276] INFO  Adhearsion::Router: Call 275fb2eb-6e31-4297-8d29-8bb008da2220 selected route "inbound" (#<Proc:0x000000041cd5c8@/srv/freeswitch/myapp/config/routes.rb:7>)

[2015-11-23 11:43:53.303] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Executing controller #<Adhearsion::CallController call=275fb2eb-6e31-4297-8d29-8bb008da2220, metadata={}>

[2015-11-23 11:43:53.310] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call ringing from sip:[caller_number]@[remote_server] to sip:[called_number]@[local_server] controlled by LoadTest::Inbound!!!

[2015-11-23 11:43:53.332] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: [275fb2eb-6e31-4297-8d29-8bb008da2220] !!!Call answered!!!

[2015-11-23 11:43:53.333] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Start inbound [sip:[caller_number]@[remote_server]] [sip:[called_number]@[local_server]]

[2015-11-23 11:43:53.346] INFO  Adhearsion::Events: #<Punchblock::Event::Answered target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id=nil, source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:53 +0000, headers={"P-Early-Media"=>"supported"}>

[2015-11-23 11:43:54.384] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:54 +0000>

[2015-11-23 11:43:57.632] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-1", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-1", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +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=Mon, 23 Nov 2015 11:43:57 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1e49e6c name="result" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1cef7d8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1ceefa4 name="input" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1ceee78 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x1ceee28 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x1ce5ef4 "0">]>, #<Nokogiri::XML::Element:0x1ce579c name="instance" namespace=#<Nokogiri::XML::Namespace:0x1e49e44 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1cdbd64 "0">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.772] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-4", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-4", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:57 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:57 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:57.786] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [0]

[2015-11-23 11:43:58.905] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-5", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-5", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:58 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.027] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-6", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-6", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:58 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:43:59 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:43:59.552] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:43:59 +0000>

[2015-11-23 11:44:01.979] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-7", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-7", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:01 +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=Mon, 23 Nov 2015 11:44:01 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x21bd594 name="result" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2199518 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2198d84 name="input" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x2198820 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21987e4 name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x2196fd4 "1">]>, #<Nokogiri::XML::Element:0x2196a98 name="instance" namespace=#<Nokogiri::XML::Namespace:0x21bd508 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x219605c "1">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.118] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-10", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-10", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:02 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:02 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:02.135] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [1]

[2015-11-23 11:44:03.253] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-11", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-11", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.349] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-12", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-12", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:03 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:03.945] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:03 +0000>

[2015-11-23 11:44:06.314] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-13", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-13", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44: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=Mon, 23 Nov 2015 11:44:06 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x220ff9c name="result" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x22007b8 name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x2200510 name="input" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x21fbba0 name="mode" value="dtmf">, #<Nokogiri::XML::Attr:0x21fbb8c name="confidence" value="100">] children=[#<Nokogiri::XML::Text:0x21fab88 "2">]>, #<Nokogiri::XML::Element:0x22001f0 name="instance" namespace=#<Nokogiri::XML::Namespace:0x220ff60 href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x22001a0 "2">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-16", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-16", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:06 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:06 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:06.391] INFO  Adhearsion::Call: 275fb2eb-6e31-4297-8d29-8bb008...@127.0.0.1: Received [2]

[2015-11-23 11:44:07.432] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-17", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-17", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:07.569] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="output-18", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/output-18", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:07 +0000, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Mon, 23 Nov 2015 11:44:07 +0100, name=:finish>, recording=nil, fax=nil, fax_metadata={}>

[2015-11-23 11:44:08.106] INFO  Adhearsion::Events: #<Punchblock::Event::InputTimersStarted target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:08 +0000>

[2015-11-23 11:44:10.498] INFO  Adhearsion::Events: #<Punchblock::Event::Complete target_call_id="275fb2eb-6e31-4297-8d29-8bb008da2220", target_mixer_name=nil, component_id="prompt-19", source_uri="xmpp:275fb2eb-6e31-4297-8d29-8bb008da2220@127.0.0.1/prompt-19", domain="127.0.0.1", transport="xmpp", timestamp=Mon, 23 Nov 2015 10:44:10 +0000, reason=#<Punchblock::Component::Input::Complete::Match target_call_id=nil, target_mixer_name=nil, component_id=nil, so

...

--
You received this message because you are subscribed to the Google Groups "Adhearsion" group.