Skip to content

Instantly share code, notes, and snippets.

@neerajnagi
Created August 26, 2013 17:15
Show Gist options
  • Save neerajnagi/6344007 to your computer and use it in GitHub Desktop.
Save neerajnagi/6344007 to your computer and use it in GitHub Desktop.
async recording failing
3-08-26 22:31:45] INFO Adhearsion::OutboundCall: bb75df2c-63f1-4536-bce4-f4a7e684197d: call back url is http://vendor.360invite.com/reportscallback.aspx
[2013-08-26 22:31:45] ERROR Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call bb75df2c-63f1-4536-bce4-f4a7e684197d: #<Punchblock::Event::Complete target_call_id="bb75df2c-63f1-4536-bce4-f4a7e684197d", target_mixer_name=nil, component_id="0b35f663-d500-45ff-820e-b7b94c743fb9", reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, name=nil>, recording=#<Punchblock::Component::Record::Recording target_call_id=nil, target_mixer_name=nil, component_id=nil, uri="file:///var/punchblock/record/0b35f663-d500-45ff-820e-b7b94c743fb9.wav", duration=nil, size=nil>>
[2013-08-26 22:31:45] INFO InvitationManager: hangup event generation
[2013-08-26 22:31:46] INFO Adhearsion::OutboundCall: bb75df2c-63f1-4536-bce4-f4a7e684197d: immediate response <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:31:46] INFO Adhearsion::OutboundCall: bb75df2c-63f1-4536-bce4-f4a7e684197d: XML recieved in new-call response : <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:31:46] INFO Adhearsion::CallController: process xml data ========================== <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:31:46] INFO Adhearsion::CallController: speak.....................................................
[2013-08-26 22:31:46] INFO Adhearsion::CallController: internal tts job done cb27de73ed455ec1966f44c0f1ef254b39122d41.mp3
[2013-08-26 22:31:46] INFO Adhearsion::CallController: EXCEPTION raised during OBD task This call is expired and is no longer accessible
[2013-08-26 22:31:46] DEBUG Adhearsion::CallController: Finished executing controller #<Adhearsion::CallController call=, metadata={}>
[2013-08-26 22:32:43] INFO InvitationManager: outgoing call to 1007 from 04039363936
[2013-08-26 22:32:43] INFO InvitationManager: missed call diagnostic
[2013-08-26 22:32:43] INFO InvitationManager: remaining is <dial action="http://127.0.0.1/callback" duration="30" from="XXXXX" method="post" record="true" timeout="30">8954103919</dial>
[2013-08-26 22:32:43] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="user/1007", from="04039363936", timeout=nil, join=nil>
[2013-08-26 22:32:43] INFO InvitationManager: outbound_call data is #<Adhearsion::OutboundCall:185edbba-d11b-4346-a818-38dd4eecf569 offer=nil, end_reason=nil, commands=[], variables={}, controllers=[], to="user/1007", from="04039363936">
[2013-08-26 22:32:44] DEBUG Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Receiving message: #<Punchblock::Event::Ringing target_call_id="185edbba-d11b-4346-a818-38dd4eecf569", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:32:48] DEBUG Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Receiving message: #<Punchblock::Event::Answered target_call_id="185edbba-d11b-4346-a818-38dd4eecf569", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:32:48] INFO Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Executing controller #<Adhearsion::CallController call=185edbba-d11b-4346-a818-38dd4eecf569, metadata={}>
[2013-08-26 22:32:48] INFO Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: #######################################################<request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:32:48] INFO Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: process xml data ========================== <request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:32:48] INFO Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: dial......................................................
[2013-08-26 22:32:48] INFO Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Dial is recording enabled
[2013-08-26 22:32:48] DEBUG Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Executing command #<Punchblock::Component::Record target_call_id=nil, target_mixer_name=nil, component_id=nil, format=nil, initial_timeout=nil, final_timeout=nil, max_duration=nil, start_beep=nil, stop_beep=nil, start_paused=nil, direction=nil, mix=nil>
[2013-08-26 22:32:48] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="sofia/gateway/trunk/08954103919", from="04039363936", timeout=30000, join=nil>
[2013-08-26 22:32:49] DEBUG Adhearsion::OutboundCall: 7de1a547-a5a0-467d-a51e-ef12de56c017: Receiving message: #<Punchblock::Event::Ringing target_call_id="7de1a547-a5a0-467d-a51e-ef12de56c017", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:33:02] DEBUG Adhearsion::OutboundCall: 7de1a547-a5a0-467d-a51e-ef12de56c017: Receiving message: #<Punchblock::Event::Answered target_call_id="7de1a547-a5a0-467d-a51e-ef12de56c017", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:33:02] INFO Adhearsion::CallController::Dial::Dial: #dial joining call 7de1a547-a5a0-467d-a51e-ef12de56c017 to 185edbba-d11b-4346-a818-38dd4eecf569
[2013-08-26 22:33:02] DEBUG Adhearsion::OutboundCall: 7de1a547-a5a0-467d-a51e-ef12de56c017: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, call_uri="185edbba-d11b-4346-a818-38dd4eecf569", mixer_name=nil, direction=nil, media=nil>
[2013-08-26 22:33:02] DEBUG Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Receiving message: #<Punchblock::Event::Joined target_call_id="185edbba-d11b-4346-a818-38dd4eecf569", target_mixer_name=nil, component_id=nil, call_uri="7de1a547-a5a0-467d-a51e-ef12de56c017", mixer_name=nil>
[2013-08-26 22:33:02] DEBUG Adhearsion::OutboundCall: 7de1a547-a5a0-467d-a51e-ef12de56c017: Receiving message: #<Punchblock::Event::Joined target_call_id="7de1a547-a5a0-467d-a51e-ef12de56c017", target_mixer_name=nil, component_id=nil, call_uri="185edbba-d11b-4346-a818-38dd4eecf569", mixer_name=nil>
[2013-08-26 22:33:04] DEBUG Adhearsion::OutboundCall: 7de1a547-a5a0-467d-a51e-ef12de56c017: Receiving message: #<Punchblock::Event::End target_call_id="7de1a547-a5a0-467d-a51e-ef12de56c017", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:33:04] INFO Adhearsion::OutboundCall: 7de1a547-a5a0-467d-a51e-ef12de56c017: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:33:04] ERROR Adhearsion::OutboundCall: 7de1a547-a5a0-467d-a51e-ef12de56c017: <Celluloid::Task::TerminatedError> task was terminated
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:61:in `suspend'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:19:in `suspend'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid.rb:60:in `suspend'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:95:in `value'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/proxies/sync_proxy.rb:28:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/legacy.rb:14:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:26:in `new'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call_controller/dial.rb:114:in `prep_calls'
org/jruby/RubyProc.java:255:in `call'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:215:in `on_end'
org/jruby/RubyProc.java:255:in `call'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:105:in `call_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:91:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:87:in `trigger_handler'
org/jruby/RubyEnumerable.java:556:in `find'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:85:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:84:in `trigger_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:122:in `deliver_message'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/foundation/exception_handler.rb:5:in `catching_standard_errors'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:122:in `deliver_message'
org/jruby/RubyKernel.java:1932:in `public_send'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:25:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:125:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/actor.rb:326:in `handle_message'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:42:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:35:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks/task_fiber.rb:11:in `create'
[2013-08-26 22:33:04] INFO Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: posting for async recording {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"async_record", "recordurl"=>"http://202.62.85.170/media/2dd687ac-91b4-4450-bc37-37dc34ea8c54.wav", "start_time"=>1377536568, "end_time"=>1377536584, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:33:04] DEBUG Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Receiving message: #<Punchblock::Event::End target_call_id="185edbba-d11b-4346-a818-38dd4eecf569", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:33:04] INFO Adhearsion::OutboundCall: 185edbba-d11b-4346-a818-38dd4eecf569: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:33:04] ERROR Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 185edbba-d11b-4346-a818-38dd4eecf569: #<Punchblock::Event::Complete target_call_id="185edbba-d11b-4346-a818-38dd4eecf569", target_mixer_name=nil, component_id="2dd687ac-91b4-4450-bc37-37dc34ea8c54", reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, name=nil>, recording=#<Punchblock::Component::Record::Recording target_call_id=nil, target_mixer_name=nil, component_id=nil, uri="file:///var/punchblock/record/2dd687ac-91b4-4450-bc37-37dc34ea8c54.wav", duration=nil, size=nil>>
[2013-08-26 22:33:04] INFO InvitationManager: call has ended and end reason was hangup
[2013-08-26 22:33:04] ERROR Celluloid: Punchblock::Translator::Freeswitch::Call crashed!
Celluloid::DeadActorError: attempted to call a dead actor
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/proxies/sync_proxy.rb:25:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/proxies/sync_proxy.rb:23:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/legacy.rb:14:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/punchblock-1.9.4/lib/punchblock/translator/freeswitch/component/record.rb:27:in `execute'
org/jruby/RubyProc.java:255:in `call'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:105:in `call_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:91:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:87:in `trigger_handler'
org/jruby/RubyEnumerable.java:556:in `find'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:85:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:84:in `trigger_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/punchblock-1.9.4/lib/punchblock/translator/freeswitch/call.rb:116:in `handle_es_event'
org/jruby/RubyKernel.java:1932:in `public_send'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:25:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:125:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/actor.rb:326:in `handle_message'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:42:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:35:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks/task_fiber.rb:11:in `create'
[2013-08-26 22:33:04] ERROR Adhearsion::Initializer: <Celluloid::DeadActorError> attempted to call a dead actor
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/proxies/sync_proxy.rb:25:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/proxies/sync_proxy.rb:23:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/legacy.rb:14:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/punchblock-1.9.4/lib/punchblock/translator/freeswitch/component/record.rb:27:in `execute'
org/jruby/RubyProc.java:255:in `call'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:105:in `call_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:91:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:87:in `trigger_handler'
org/jruby/RubyEnumerable.java:556:in `find'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:85:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:84:in `trigger_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/punchblock-1.9.4/lib/punchblock/translator/freeswitch/call.rb:116:in `handle_es_event'
org/jruby/RubyKernel.java:1932:in `public_send'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:25:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:125:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/actor.rb:326:in `handle_message'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:42:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:35:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks/task_fiber.rb:11:in `create'
[2013-08-26 22:33:04] ERROR Punchblock::Translator::Freeswitch: A linked actor (#<Celluloid::ActorProxy(Punchblock::Translator::Freeswitch::Call) dead>) died due to #<Celluloid::DeadActorError: attempted to call a dead actor>
[2013-08-26 22:33:04] ERROR Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 185edbba-d11b-4346-a818-38dd4eecf569: #<Punchblock::Event::End target_call_id="185edbba-d11b-4346-a818-38dd4eecf569", target_mixer_name=nil, component_id=nil, headers={}, reason=:error, platform_code=nil>
[2013-08-26 22:33:04] INFO InvitationManager: hangup event generation
[2013-08-26 22:33:06] INFO Adhearsion::CallController::Dial::Dial: #dial finished with no remaining outbound calls
[2013-08-26 22:33:06] INFO Adhearsion::CallController: posting for dial {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"dial", "status"=>"answer", "async_record"=>"true", "start_time"=>1377536568, "end_time"=>1377536586, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:33:06] INFO Adhearsion::CallController: response for dial
[2013-08-26 22:33:06] INFO Adhearsion::CallController: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ {"callback_url"=>"http://vendor.360invite.com/reportscallback.aspx", "from"=>"04039363936", "sound"=>nil, "to"=>"1007", "calluid"=>"test_call_id_from_170", "xml"=>"<request action=\"http://vendhhhhor.360invite.com/reportscallback.aspx\" method=\"post\"><to>8954103919</to><dial duration=\"30\" timeout=\"30\" action=\"http://127.0.0.1/callback\" method=\"post\" from=\"XXXXX\" record=\"true\">8954103919</dial> </request>", "user_id"=>0, "missed_call"=>false, "enclosure"=>"request"}
[2013-08-26 22:33:06] INFO Adhearsion::CallController: call back url is http://vendor.360invite.com/reportscallback.aspx
[2013-08-26 22:33:06] INFO Adhearsion::CallController: immediate response <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:33:06] INFO Adhearsion::CallController: XML recieved in new-call response : <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:33:06] INFO Adhearsion::CallController: process xml data ========================== <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:33:06] INFO Adhearsion::CallController: speak.....................................................
[2013-08-26 22:33:06] INFO Adhearsion::CallController: internal tts job done cb27de73ed455ec1966f44c0f1ef254b39122d41.mp3
[2013-08-26 22:33:06] INFO Adhearsion::CallController: EXCEPTION raised during OBD task This call is expired and is no longer accessible
[2013-08-26 22:33:07] DEBUG Adhearsion::CallController: Finished executing controller #<Adhearsion::CallController call=, metadata={}>
[2013-08-26 22:33:44] INFO InvitationManager: outgoing call to 1007 from 04039363936
[2013-08-26 22:33:44] INFO InvitationManager: missed call diagnostic
[2013-08-26 22:33:44] INFO InvitationManager: remaining is <dial action="http://127.0.0.1/callback" duration="30" from="XXXXX" method="post" record="true" timeout="30">8954103919</dial>
[2013-08-26 22:33:45] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="user/1007", from="04039363936", timeout=nil, join=nil>
[2013-08-26 22:33:45] INFO InvitationManager: outbound_call data is #<Adhearsion::OutboundCall:f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4 offer=nil, end_reason=nil, commands=[], variables={}, controllers=[], to="user/1007", from="04039363936">
[2013-08-26 22:33:45] DEBUG Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Receiving message: #<Punchblock::Event::Ringing target_call_id="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:33:49] DEBUG Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Receiving message: #<Punchblock::Event::Answered target_call_id="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:33:49] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Executing controller #<Adhearsion::CallController call=f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4, metadata={}>
[2013-08-26 22:33:49] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: #######################################################<request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:33:49] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: process xml data ========================== <request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:33:49] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: dial......................................................
[2013-08-26 22:33:49] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Dial is recording enabled
[2013-08-26 22:33:49] DEBUG Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Executing command #<Punchblock::Component::Record target_call_id=nil, target_mixer_name=nil, component_id=nil, format=nil, initial_timeout=nil, final_timeout=nil, max_duration=nil, start_beep=nil, stop_beep=nil, start_paused=nil, direction=nil, mix=nil>
[2013-08-26 22:33:49] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="sofia/gateway/trunk/08954103919", from="04039363936", timeout=30000, join=nil>
[2013-08-26 22:33:51] DEBUG Adhearsion::OutboundCall: 2e71ba03-e37e-43f6-84ec-733898e682dc: Receiving message: #<Punchblock::Event::Ringing target_call_id="2e71ba03-e37e-43f6-84ec-733898e682dc", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:33:56] DEBUG Adhearsion::OutboundCall: 2e71ba03-e37e-43f6-84ec-733898e682dc: Receiving message: #<Punchblock::Event::Answered target_call_id="2e71ba03-e37e-43f6-84ec-733898e682dc", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:33:56] INFO Adhearsion::CallController::Dial::Dial: #dial joining call 2e71ba03-e37e-43f6-84ec-733898e682dc to f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4
[2013-08-26 22:33:56] DEBUG Adhearsion::OutboundCall: 2e71ba03-e37e-43f6-84ec-733898e682dc: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, call_uri="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", mixer_name=nil, direction=nil, media=nil>
[2013-08-26 22:33:56] DEBUG Adhearsion::OutboundCall: 2e71ba03-e37e-43f6-84ec-733898e682dc: Receiving message: #<Punchblock::Event::Joined target_call_id="2e71ba03-e37e-43f6-84ec-733898e682dc", target_mixer_name=nil, component_id=nil, call_uri="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", mixer_name=nil>
[2013-08-26 22:33:56] DEBUG Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Receiving message: #<Punchblock::Event::Joined target_call_id="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", target_mixer_name=nil, component_id=nil, call_uri="2e71ba03-e37e-43f6-84ec-733898e682dc", mixer_name=nil>
[2013-08-26 22:34:21] INFO InvitationManager: outgoing call to 1007 from 04039363936
[2013-08-26 22:34:21] INFO InvitationManager: missed call diagnostic
[2013-08-26 22:34:21] INFO InvitationManager: remaining is <dial action="http://127.0.0.1/callback" duration="30" from="XXXXX" method="post" record="true" timeout="30">8954103919</dial>
[2013-08-26 22:34:21] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="user/1007", from="04039363936", timeout=nil, join=nil>
[2013-08-26 22:34:21] INFO InvitationManager: outbound_call data is #<Adhearsion::OutboundCall:0655bed2-2332-49f3-8799-d2669857fe0b offer=nil, end_reason=nil, commands=[], variables={}, controllers=[], to="user/1007", from="04039363936">
[2013-08-26 22:34:22] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Receiving message: #<Punchblock::Event::Ringing target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:34:25] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Receiving message: #<Punchblock::Event::Answered target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:34:25] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Executing controller #<Adhearsion::CallController call=0655bed2-2332-49f3-8799-d2669857fe0b, metadata={}>
[2013-08-26 22:34:25] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: #######################################################<request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:34:25] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: process xml data ========================== <request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:34:25] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: dial......................................................
[2013-08-26 22:34:25] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Dial is recording enabled
[2013-08-26 22:34:25] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Executing command #<Punchblock::Component::Record target_call_id=nil, target_mixer_name=nil, component_id=nil, format=nil, initial_timeout=nil, final_timeout=nil, max_duration=nil, start_beep=nil, stop_beep=nil, start_paused=nil, direction=nil, mix=nil>
[2013-08-26 22:34:25] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="sofia/gateway/trunk/08954103919", from="04039363936", timeout=30000, join=nil>
[2013-08-26 22:34:26] DEBUG Adhearsion::OutboundCall: 782feac9-6af7-4362-8565-ca1fbe7c00ad: Receiving message: #<Punchblock::Event::Ringing target_call_id="782feac9-6af7-4362-8565-ca1fbe7c00ad", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:34:26] DEBUG Adhearsion::OutboundCall: 782feac9-6af7-4362-8565-ca1fbe7c00ad: Receiving message: #<Punchblock::Event::End target_call_id="782feac9-6af7-4362-8565-ca1fbe7c00ad", target_mixer_name=nil, component_id=nil, headers={}, reason=:busy__USER_BUSY, platform_code=nil>
[2013-08-26 22:34:26] INFO Adhearsion::OutboundCall: 782feac9-6af7-4362-8565-ca1fbe7c00ad: Call ended due to busy__USER_BUSY
[2013-08-26 22:34:26] ERROR Adhearsion::OutboundCall: 782feac9-6af7-4362-8565-ca1fbe7c00ad: <Celluloid::Task::TerminatedError> task was terminated
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:61:in `suspend'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:19:in `suspend'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid.rb:60:in `suspend'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:95:in `value'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/proxies/sync_proxy.rb:28:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/legacy.rb:14:in `method_missing'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:26:in `new'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call_controller/dial.rb:114:in `prep_calls'
org/jruby/RubyProc.java:255:in `call'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:215:in `on_end'
org/jruby/RubyProc.java:255:in `call'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:105:in `call_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:91:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:87:in `trigger_handler'
org/jruby/RubyEnumerable.java:556:in `find'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:85:in `trigger_handler'
org/jruby/RubyKernel.java:1254:in `catch'
/usr/local/rvm/gems/jruby-1.7.4/gems/has-guarded-handlers-1.5.0/lib/has_guarded_handlers.rb:84:in `trigger_handler'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:122:in `deliver_message'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/foundation/exception_handler.rb:5:in `catching_standard_errors'
/usr/local/rvm/gems/jruby-1.7.4/gems/adhearsion-2.3.5/lib/adhearsion/call.rb:122:in `deliver_message'
org/jruby/RubyKernel.java:1932:in `public_send'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:25:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/calls.rb:125:in `dispatch'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/actor.rb:326:in `handle_message'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:42:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks.rb:35:in `initialize'
/usr/local/rvm/gems/jruby-1.7.4/gems/celluloid-0.14.1/lib/celluloid/tasks/task_fiber.rb:11:in `create'
[2013-08-26 22:34:41] DEBUG Adhearsion::OutboundCall: 2e71ba03-e37e-43f6-84ec-733898e682dc: Receiving message: #<Punchblock::Event::End target_call_id="2e71ba03-e37e-43f6-84ec-733898e682dc", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:34:41] INFO Adhearsion::OutboundCall: 2e71ba03-e37e-43f6-84ec-733898e682dc: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:34:41] INFO Adhearsion::CallController::Dial::Dial: #dial finished with no remaining outbound calls
[2013-08-26 22:34:41] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: posting for dial {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"dial", "status"=>"answer", "async_record"=>"true", "start_time"=>1377536629, "end_time"=>1377536681, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:34:41] DEBUG Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Receiving message: #<Punchblock::Event::Unjoined target_call_id="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", target_mixer_name=nil, component_id=nil, call_uri="2e71ba03-e37e-43f6-84ec-733898e682dc", mixer_name=nil>
[2013-08-26 22:34:41] DEBUG Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Receiving message: #<Punchblock::Event::End target_call_id="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:34:41] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:34:41] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: posting for async recording {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"async_record", "recordurl"=>"http://202.62.85.170/media/01a2b4e4-5196-4a05-932a-7008f22d4660.wav", "start_time"=>1377536665, "end_time"=>1377536681, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:34:41] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: response for dial
[2013-08-26 22:34:41] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ {"callback_url"=>"http://vendor.360invite.com/reportscallback.aspx", "from"=>"04039363936", "sound"=>nil, "to"=>"1007", "calluid"=>"test_call_id_from_170", "xml"=>"<request action=\"http://vendhhhhor.360invite.com/reportscallback.aspx\" method=\"post\"><to>8954103919</to><dial duration=\"30\" timeout=\"30\" action=\"http://127.0.0.1/callback\" method=\"post\" from=\"XXXXX\" record=\"true\">8954103919</dial> </request>", "user_id"=>0, "missed_call"=>false, "enclosure"=>"request"}
[2013-08-26 22:34:41] INFO InvitationManager: call has ended and end reason was hangup
[2013-08-26 22:34:41] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: call back url is http://vendor.360invite.com/reportscallback.aspx
[2013-08-26 22:34:41] ERROR Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: #<Punchblock::Event::Complete target_call_id="f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4", target_mixer_name=nil, component_id="01a2b4e4-5196-4a05-932a-7008f22d4660", reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, name=nil>, recording=#<Punchblock::Component::Record::Recording target_call_id=nil, target_mixer_name=nil, component_id=nil, uri="file:///var/punchblock/record/01a2b4e4-5196-4a05-932a-7008f22d4660.wav", duration=nil, size=nil>>
[2013-08-26 22:34:41] INFO InvitationManager: hangup event generation
[2013-08-26 22:34:42] INFO Adhearsion::OutboundCall: f15d4b38-39f2-48c9-a3d9-d5b9d9cd34a4: immediate response <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:34:42] INFO Adhearsion::CallController: XML recieved in new-call response : <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:34:42] INFO Adhearsion::CallController: process xml data ========================== <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:34:42] INFO Adhearsion::CallController: speak.....................................................
[2013-08-26 22:34:42] INFO Adhearsion::CallController: internal tts job done cb27de73ed455ec1966f44c0f1ef254b39122d41.mp3
[2013-08-26 22:34:42] INFO Adhearsion::CallController: EXCEPTION raised during OBD task This call is expired and is no longer accessible
[2013-08-26 22:34:42] DEBUG Adhearsion::CallController: Finished executing controller #<Adhearsion::CallController call=, metadata={}>
[2013-08-26 22:34:55] INFO Adhearsion::CallController::Dial::Dial: #dial finished with no remaining outbound calls
[2013-08-26 22:34:55] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: posting for dial {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"dial", "status"=>"timeout", "async_record"=>"true", "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:34:55] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: response for dial
[2013-08-26 22:34:55] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ {"callback_url"=>"http://vendor.360invite.com/reportscallback.aspx", "from"=>"04039363936", "sound"=>nil, "to"=>"1007", "calluid"=>"test_call_id_from_170", "xml"=>"<request action=\"http://vendhhhhor.360invite.com/reportscallback.aspx\" method=\"post\"><to>8954103919</to><dial duration=\"30\" timeout=\"30\" action=\"http://127.0.0.1/callback\" method=\"post\" from=\"XXXXX\" record=\"true\">8954103919</dial> </request>", "user_id"=>0, "missed_call"=>false, "enclosure"=>"request"}
[2013-08-26 22:34:55] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: call back url is http://vendor.360invite.com/reportscallback.aspx
[2013-08-26 22:34:56] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: immediate response <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:34:56] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: XML recieved in new-call response : <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:34:56] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: process xml data ========================== <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:34:56] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: speak.....................................................
[2013-08-26 22:34:56] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: internal tts job done cb27de73ed455ec1966f44c0f1ef254b39122d41.mp3
[2013-08-26 22:34:56] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, voice=nil, interrupt_on=nil, start_offset=nil, start_paused=false, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Punchblock::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, url=nil, content_type="application/ssml+xml", value=<speak version="1.0" xml:lang="en-US" xmlns="http://www.w3.org/2001/10/synthesis"><audio src="/var/punchblock/record/cb27de73ed455ec1966f44c0f1ef254b39122d41.mp3"/></speak>>]>
[2013-08-26 22:34:58] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Receiving message: #<Punchblock::Event::Complete target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id="b062078c-2f39-43a8-806d-1e85cd035fbe", reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, name=nil>, recording=nil>
[2013-08-26 22:34:58] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: dial......................................................
[2013-08-26 22:34:58] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Dial is recording enabled
[2013-08-26 22:34:58] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Executing command #<Punchblock::Component::Record target_call_id=nil, target_mixer_name=nil, component_id=nil, format=nil, initial_timeout=nil, final_timeout=nil, max_duration=nil, start_beep=nil, stop_beep=nil, start_paused=nil, direction=nil, mix=nil>
[2013-08-26 22:34:58] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="sofia/gateway/trunk/09989820911", from="04039363936", timeout=30000, join=nil>
[2013-08-26 22:35:01] DEBUG Adhearsion::OutboundCall: 31fb796b-a238-4256-aa27-f1134355ea24: Receiving message: #<Punchblock::Event::Ringing target_call_id="31fb796b-a238-4256-aa27-f1134355ea24", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:35:03] INFO InvitationManager: outgoing call to 1007 from 04039363936
[2013-08-26 22:35:03] INFO InvitationManager: missed call diagnostic
[2013-08-26 22:35:03] INFO InvitationManager: remaining is <dial action="http://127.0.0.1/callback" duration="30" from="XXXXX" method="post" record="true" timeout="30">8954103919</dial>
[2013-08-26 22:35:03] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="user/1007", from="04039363936", timeout=nil, join=nil>
[2013-08-26 22:35:03] INFO InvitationManager: outbound_call data is #<Adhearsion::OutboundCall:24aec2f0-27b7-4148-9fee-d454fb21f91a offer=nil, end_reason=nil, commands=[], variables={}, controllers=[], to="user/1007", from="04039363936">
[2013-08-26 22:35:04] DEBUG Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Receiving message: #<Punchblock::Event::Ringing target_call_id="24aec2f0-27b7-4148-9fee-d454fb21f91a", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:35:06] DEBUG Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Receiving message: #<Punchblock::Event::Answered target_call_id="24aec2f0-27b7-4148-9fee-d454fb21f91a", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:35:06] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Executing controller #<Adhearsion::CallController call=24aec2f0-27b7-4148-9fee-d454fb21f91a, metadata={}>
[2013-08-26 22:35:06] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: #######################################################<request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:35:06] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: process xml data ========================== <request action="http://vendhhhhor.360invite.com/reportscallback.aspx" method="post"><to>8954103919</to><dial duration="30" timeout="30" action="http://127.0.0.1/callback" method="post" from="XXXXX" record="true">8954103919</dial> </request>
[2013-08-26 22:35:06] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: dial......................................................
[2013-08-26 22:35:06] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Dial is recording enabled
[2013-08-26 22:35:06] DEBUG Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Executing command #<Punchblock::Component::Record target_call_id=nil, target_mixer_name=nil, component_id=nil, format=nil, initial_timeout=nil, final_timeout=nil, max_duration=nil, start_beep=nil, stop_beep=nil, start_paused=nil, direction=nil, mix=nil>
[2013-08-26 22:35:06] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, headers={}, to="sofia/gateway/trunk/08954103919", from="04039363936", timeout=30000, join=nil>
[2013-08-26 22:35:07] DEBUG Adhearsion::OutboundCall: 4f6f30f6-64a6-411b-8cd3-998ca03bb57d: Receiving message: #<Punchblock::Event::Ringing target_call_id="4f6f30f6-64a6-411b-8cd3-998ca03bb57d", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:35:09] DEBUG Adhearsion::OutboundCall: 31fb796b-a238-4256-aa27-f1134355ea24: Receiving message: #<Punchblock::Event::Answered target_call_id="31fb796b-a238-4256-aa27-f1134355ea24", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:35:09] INFO Adhearsion::CallController::Dial::Dial: #dial joining call 31fb796b-a238-4256-aa27-f1134355ea24 to 0655bed2-2332-49f3-8799-d2669857fe0b
[2013-08-26 22:35:09] DEBUG Adhearsion::OutboundCall: 31fb796b-a238-4256-aa27-f1134355ea24: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, call_uri="0655bed2-2332-49f3-8799-d2669857fe0b", mixer_name=nil, direction=nil, media=nil>
[2013-08-26 22:35:09] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Receiving message: #<Punchblock::Event::Joined target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id=nil, call_uri="31fb796b-a238-4256-aa27-f1134355ea24", mixer_name=nil>
[2013-08-26 22:35:09] DEBUG Adhearsion::OutboundCall: 31fb796b-a238-4256-aa27-f1134355ea24: Receiving message: #<Punchblock::Event::Joined target_call_id="31fb796b-a238-4256-aa27-f1134355ea24", target_mixer_name=nil, component_id=nil, call_uri="0655bed2-2332-49f3-8799-d2669857fe0b", mixer_name=nil>
[2013-08-26 22:35:16] DEBUG Adhearsion::OutboundCall: 4f6f30f6-64a6-411b-8cd3-998ca03bb57d: Receiving message: #<Punchblock::Event::Answered target_call_id="4f6f30f6-64a6-411b-8cd3-998ca03bb57d", target_mixer_name=nil, component_id=nil, headers={}>
[2013-08-26 22:35:16] INFO Adhearsion::CallController::Dial::Dial: #dial joining call 4f6f30f6-64a6-411b-8cd3-998ca03bb57d to 24aec2f0-27b7-4148-9fee-d454fb21f91a
[2013-08-26 22:35:16] DEBUG Adhearsion::OutboundCall: 4f6f30f6-64a6-411b-8cd3-998ca03bb57d: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, call_uri="24aec2f0-27b7-4148-9fee-d454fb21f91a", mixer_name=nil, direction=nil, media=nil>
[2013-08-26 22:35:16] DEBUG Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Receiving message: #<Punchblock::Event::Joined target_call_id="24aec2f0-27b7-4148-9fee-d454fb21f91a", target_mixer_name=nil, component_id=nil, call_uri="4f6f30f6-64a6-411b-8cd3-998ca03bb57d", mixer_name=nil>
[2013-08-26 22:35:16] DEBUG Adhearsion::OutboundCall: 4f6f30f6-64a6-411b-8cd3-998ca03bb57d: Receiving message: #<Punchblock::Event::Joined target_call_id="4f6f30f6-64a6-411b-8cd3-998ca03bb57d", target_mixer_name=nil, component_id=nil, call_uri="24aec2f0-27b7-4148-9fee-d454fb21f91a", mixer_name=nil>
[2013-08-26 22:35:23] DEBUG Adhearsion::OutboundCall: 31fb796b-a238-4256-aa27-f1134355ea24: Receiving message: #<Punchblock::Event::End target_call_id="31fb796b-a238-4256-aa27-f1134355ea24", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:35:23] INFO Adhearsion::OutboundCall: 31fb796b-a238-4256-aa27-f1134355ea24: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:35:23] INFO Adhearsion::CallController::Dial::Dial: #dial finished with no remaining outbound calls
[2013-08-26 22:35:23] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: posting for dial {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"dial", "status"=>"answer", "async_record"=>"true", "start_time"=>1377536698, "end_time"=>1377536723, "callstatus"=>"inprogress", "callee"=>"9989820911", "caller"=>"04039363901", "direction"=>"inbound"}
[2013-08-26 22:35:23] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: posting for async recording {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"async_record", "recordurl"=>"http://202.62.85.170/media/9cb6f550-28cb-412e-bbe4-d6dfdc19b5f8.wav", "start_time"=>1377536706, "end_time"=>1377536723, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:35:23] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Receiving message: #<Punchblock::Event::End target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:35:23] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:35:23] DEBUG Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: Receiving message: #<Punchblock::Event::Unjoined target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id=nil, call_uri="31fb796b-a238-4256-aa27-f1134355ea24", mixer_name=nil>
[2013-08-26 22:35:23] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: posting for async recording {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"async_record", "recordurl"=>"http://202.62.85.170/media/676fa880-38e8-4606-8484-babe5aec2c1f.wav", "start_time"=>1377536706, "end_time"=>1377536723, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:35:23] ERROR Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 0655bed2-2332-49f3-8799-d2669857fe0b: #<Punchblock::Event::Complete target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id="9cb6f550-28cb-412e-bbe4-d6dfdc19b5f8", reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, name=nil>, recording=#<Punchblock::Component::Record::Recording target_call_id=nil, target_mixer_name=nil, component_id=nil, uri="file:///var/punchblock/record/9cb6f550-28cb-412e-bbe4-d6dfdc19b5f8.wav", duration=nil, size=nil>>
[2013-08-26 22:35:23] INFO InvitationManager: call has ended and end reason was hangup
[2013-08-26 22:35:23] INFO InvitationManager: hangup event generation
[2013-08-26 22:35:23] ERROR Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 0655bed2-2332-49f3-8799-d2669857fe0b: #<Punchblock::Event::Complete target_call_id="0655bed2-2332-49f3-8799-d2669857fe0b", target_mixer_name=nil, component_id="676fa880-38e8-4606-8484-babe5aec2c1f", reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, name=nil>, recording=#<Punchblock::Component::Record::Recording target_call_id=nil, target_mixer_name=nil, component_id=nil, uri="file:///var/punchblock/record/676fa880-38e8-4606-8484-babe5aec2c1f.wav", duration=nil, size=nil>>
[2013-08-26 22:35:24] INFO Adhearsion::OutboundCall: 0655bed2-2332-49f3-8799-d2669857fe0b: response for dial OK
[2013-08-26 22:35:24] INFO Adhearsion::CallController: DIAL RESPONSE IS NOT NIL OR BLANK
[2013-08-26 22:35:24] INFO Adhearsion::CallController: process xml data ========================== OK
[2013-08-26 22:35:24] INFO Adhearsion::CallController: EXCEPTION raised during OBD task This call is expired and is no longer accessible
[2013-08-26 22:35:24] DEBUG Adhearsion::CallController: Finished executing controller #<Adhearsion::CallController call=, metadata={}>
[2013-08-26 22:36:02] DEBUG Adhearsion::OutboundCall: 4f6f30f6-64a6-411b-8cd3-998ca03bb57d: Receiving message: #<Punchblock::Event::End target_call_id="4f6f30f6-64a6-411b-8cd3-998ca03bb57d", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:36:02] INFO Adhearsion::OutboundCall: 4f6f30f6-64a6-411b-8cd3-998ca03bb57d: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:36:02] INFO Adhearsion::CallController::Dial::Dial: #dial finished with no remaining outbound calls
[2013-08-26 22:36:02] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: posting for dial {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"dial", "status"=>"answer", "async_record"=>"true", "start_time"=>1377536706, "end_time"=>1377536762, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:36:02] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: posting for async recording {"calluid"=>"test_call_id_from_170", "from"=>"04039363936", "to"=>"1007", "event"=>"async_record", "recordurl"=>"http://202.62.85.170/media/be5ce54d-897d-47c4-b7c8-0d35566e2e84.wav", "start_time"=>1377536706, "end_time"=>1377536762, "callstatus"=>"inprogress", "callee"=>"8954103919", "caller"=>"XXXXX", "direction"=>"inbound"}
[2013-08-26 22:36:02] DEBUG Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Receiving message: #<Punchblock::Event::End target_call_id="24aec2f0-27b7-4148-9fee-d454fb21f91a", target_mixer_name=nil, component_id=nil, headers={}, reason=:hangup__NORMAL_CLEARING, platform_code=nil>
[2013-08-26 22:36:02] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Call ended due to hangup__NORMAL_CLEARING
[2013-08-26 22:36:02] DEBUG Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: Receiving message: #<Punchblock::Event::Unjoined target_call_id="24aec2f0-27b7-4148-9fee-d454fb21f91a", target_mixer_name=nil, component_id=nil, call_uri="4f6f30f6-64a6-411b-8cd3-998ca03bb57d", mixer_name=nil>
[2013-08-26 22:36:02] INFO InvitationManager: call has ended and end reason was hangup
[2013-08-26 22:36:02] INFO InvitationManager: hangup event generation
[2013-08-26 22:36:02] ERROR Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 24aec2f0-27b7-4148-9fee-d454fb21f91a: #<Punchblock::Event::Complete target_call_id="24aec2f0-27b7-4148-9fee-d454fb21f91a", target_mixer_name=nil, component_id="be5ce54d-897d-47c4-b7c8-0d35566e2e84", reason=#<Punchblock::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, name=nil>, recording=#<Punchblock::Component::Record::Recording target_call_id=nil, target_mixer_name=nil, component_id=nil, uri="file:///var/punchblock/record/be5ce54d-897d-47c4-b7c8-0d35566e2e84.wav", duration=nil, size=nil>>
[2013-08-26 22:36:03] INFO Adhearsion::OutboundCall: 24aec2f0-27b7-4148-9fee-d454fb21f91a: response for dial
[2013-08-26 22:36:03] INFO Adhearsion::CallController: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ {"callback_url"=>"http://vendor.360invite.com/reportscallback.aspx", "from"=>"04039363936", "sound"=>nil, "to"=>"1007", "calluid"=>"test_call_id_from_170", "xml"=>"<request action=\"http://vendhhhhor.360invite.com/reportscallback.aspx\" method=\"post\"><to>8954103919</to><dial duration=\"30\" timeout=\"30\" action=\"http://127.0.0.1/callback\" method=\"post\" from=\"XXXXX\" record=\"true\">8954103919</dial> </request>", "user_id"=>0, "missed_call"=>false, "enclosure"=>"request"}
[2013-08-26 22:36:03] INFO Adhearsion::CallController: call back url is http://vendor.360invite.com/reportscallback.aspx
[2013-08-26 22:36:04] INFO Adhearsion::CallController: immediate response <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:36:04] INFO Adhearsion::CallController: XML recieved in new-call response : <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:36:04] INFO Adhearsion::CallController: process xml data ========================== <eventresponse><speak>call is being forwarded to farooq</speak><dial duration='60' timeout='30' action='http://vendor.360invite.com/reportscallback.aspx' method='post' from='04039363901' record='true'>9989820911</dial></eventresponse>
[2013-08-26 22:36:04] INFO Adhearsion::CallController: speak.....................................................
[2013-08-26 22:36:04] INFO Adhearsion::CallController: internal tts job done cb27de73ed455ec1966f44c0f1ef254b39122d41.mp3
[2013-08-26 22:36:04] INFO Adhearsion::CallController: EXCEPTION raised during OBD task This call is expired and is no longer accessible
[2013-08-26 22:36:04] DEBUG Adhearsion::CallController: Finished executing controller #<Adhearsion::CallController call=, metadata={}>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment