Created
August 26, 2013 17:15
-
-
Save neerajnagi/6344007 to your computer and use it in GitHub Desktop.
async recording failing
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
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