Skip to content

Instantly share code, notes, and snippets.

@smpallen99

smpallen99/adhearsion.log

Created April 16, 2013 15:04
Show Gist options
  • Save smpallen99/5396665 to your computer and use it in GitHub Desktop.
Save smpallen99/5396665 to your computer and use it in GitHub Desktop.
Download ZIP
Adhearsion hangup exception
Raw
adhearsion.log
[2013-04-16 10:58:05] DEBUG DrbEndpoint: DrbEndpoint#notification_call(112, UCX/304, 6, 304 User, Test 304)
[2013-04-16 10:58:05] DEBUG DrbEndpoint: Last message id is: 112
[2013-04-16 10:58:06] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial to="UCX/304", from="304 User", join=nil, headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [QUEUE]: Action: originate
ActionID: 78452038-a272-4952-be2d-08c8501e6801
Async: true
Application: AGI
Data: agi:async
Channel: UCX/304
Callerid: 304 User
Variable: punchblock_call_id=14a34a5e-51a3-4575-a51e-9c73ea31dff9
Timeout: 20000
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [SEND]: Action: originate
ActionID: 78452038-a272-4952-be2d-08c8501e6801
Async: true
Application: AGI
Data: agi:async
Channel: UCX/304
Callerid: 304 User
Variable: punchblock_call_id=14a34a5e-51a3-4575-a51e-9c73ea31dff9
Timeout: 20000
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [SEND] Action: originate
ActionID: 78452038-a272-4952-be2d-08c8501e6801
Async: true
Application: AGI
Data: agi:async
Channel: UCX/304
Callerid: 304 User
Variable: punchblock_call_id=14a34a5e-51a3-4575-a51e-9c73ea31dff9
Timeout: 20000
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] Event: Newchannel
Privilege: call,all
Channel: UCX/304@304-0
ChannelState: 0
ChannelStateDesc: Down
CallerIDNum: 304
CallerIDName:
AccountCode:
Exten:
Context: from-internal
Uniqueid: 1360213046.1619
Event: VarSet
Privilege: dialplan,all
Channel: UCX/304@304-0
Variable: punchblock_call_id
Value: 14a34a5e-51a3-4575-a51e-9c73ea31dff9
Uniqueid: 1360213046.1619
Event: NewAccountCode
Privilege: call,all
Channel: UCX/304@304-0
Uniqueid: 1360213046.1619
AccountCode:
OldAccountCode:
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newchannel", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "ChannelState"=>"0", "ChannelStateDesc"=>"Down", "CallerIDNum"=>"304", "CallerIDName"=>"", "AccountCode"=>"", "Exten"=>"", "Context"=>"from-internal", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] Response: Success
ActionID: 78452038-a272-4952-be2d-08c8501e6801
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] Message: Originate successfully queued
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"punchblock_call_id", "Value"=>"14a34a5e-51a3-4575-a51e-9c73ea31dff9", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "Uniqueid"=>"1360213046.1619", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newchannel", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "ChannelState"=>"0", "ChannelStateDesc"=>"Down", "CallerIDNum"=>"304", "CallerIDName"=>"", "AccountCode"=>"", "Exten"=>"", "Context"=>"from-internal", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"punchblock_call_id", "Value"=>"14a34a5e-51a3-4575-a51e-9c73ea31dff9", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"78452038-a272-4952-be2d-08c8501e6801", "Message"=>"Originate successfully queued"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] Event: NewCallerid
Privilege: call,all
Channel: UCX/304@304-0
CallerIDNum: 304
CallerIDName: 304 User
Uniqueid: 1360213046.1619
CID-CallingPres: 0 (Presentation Allowed, Not Screened)
Event: ExtensionStatus
Privilege: call,all
Exten: 304
Context: ext-local
Hint: UCX/304@304
Status: 8
Event: Newstate
Privilege: call,all
Channel: UCX/304@304-0
ChannelState: 5
ChannelStateDesc: Ringing
CallerIDNum: 304
CallerIDName: 304 User
ConnectedLineNum:
ConnectedLineName: 304 User
Uniqueid: 1360213046.1619
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"78452038-a272-4952-be2d-08c8501e6801", "Message"=>"Originate successfully queued"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewCallerid", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "Uniqueid"=>"1360213046.1619", "CID-CallingPres"=>"0 (Presentation Allowed, Not Screened)"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "Uniqueid"=>"1360213046.1619", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "Exten"=>"304", "Context"=>"ext-local", "Hint"=>"UCX/304@304", "Status"=>"8"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "ChannelState"=>"5", "ChannelStateDesc"=>"Ringing", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "ConnectedLineNum"=>"", "ConnectedLineName"=>"304 User", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="NewCallerid", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "Uniqueid"=>"1360213046.1619", "CID-CallingPres"=>"0 (Presentation Allowed, Not Screened)"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "Exten"=>"304", "Context"=>"ext-local", "Hint"=>"UCX/304@304", "Status"=>"8"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "ChannelState"=>"5", "ChannelStateDesc"=>"Ringing", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "ConnectedLineNum"=>"", "ConnectedLineName"=>"304 User", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:06] DEBUG DrbEndpoint: ===> going to do originate now
[2013-04-16 10:58:06] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"UCX/304@304-0", :variable=>"punchblock_call_id", :value=>"14a34a5e-51a3-4575-a51e-9c73ea31dff9", :uniqueid=>"1360213046.1619"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:06] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="NewAccountCode", attributes_hash={:privilege=>"call,all", :channel=>"UCX/304@304-0", :uniqueid=>"1360213046.1619", :accountcode=>"", :oldaccountcode=>""}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:06] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="NewCallerid", attributes_hash={:privilege=>"call,all", :channel=>"UCX/304@304-0", :calleridnum=>"304", :calleridname=>"304 User", :uniqueid=>"1360213046.1619", :cid_callingpres=>"0 (Presentation Allowed, Not Screened)"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:06] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Newstate", attributes_hash={:privilege=>"call,all", :channel=>"UCX/304@304-0", :channelstate=>"5", :channelstatedesc=>"Ringing", :calleridnum=>"304", :calleridname=>"304 User", :connectedlinenum=>"", :connectedlinename=>"304 User", :uniqueid=>"1360213046.1619"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:06] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Ringing headers_hash={}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] Event: Newstate
Privilege: call,all
Channel: UCX/304@304-0
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 304
CallerIDName: 304 User
ConnectedLineNum:
ConnectedLineName: 304 User
Uniqueid: 1360213046.1619
Event: VarSet
Privilege: dialplan,all
Channel: UCX/304@304-0
Variable: punchblock_call_id
Value: 14a34a5e-51a3-4575-a51e-9c73ea31dff9
Uniqueid: 1360213046.1619
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "ConnectedLineNum"=>"", "ConnectedLineName"=>"304 User", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"punchblock_call_id", "Value"=>"14a34a5e-51a3-4575-a51e-9c73ea31dff9", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] Event: NewAccountCode
Privilege: call,all
Channel: UCX/304@304-0
Uniqueid: 1360213046.1619
AccountCode:
OldAccountCode:
Event: OriginateResponse
Privilege: call,all
ActionID: 78452038-a272-4952-be2d-08c8501e6801
Response: Success
Channel: UCX/304@304-0
Context:
Exten:
Reason: 4
Uniqueid: 1360213046.1619
CallerIDNum: <unknown>
CallerIDName: 304 User
Event: ExtensionStatus
Privilege: call,all
Exten: 304
Context: ext-local
Hint: UCX/304@304
Status: 2
Event: AsyncAGI
Privilege: agi,all
SubEvent: Start
Channel: UCX/304@304-0
Env: agi_request%3A%20async%0Aagi_channel%3A%20UCX%2F304%40304-0%0Aagi_language%3A%20en%0Aagi_type%3A%20UCX%0Aagi_uniqueid%3A%201360213046.1619%0Aagi_version%3A%201.8.17.0%0Aagi_callerid%3A%20304%0Aagi_calleridname%3A%20304%20User%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%20unknown%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-internal%0Aagi_extension%3A%20s%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%201088198976%0A%0A
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"punchblock_call_id", "Value"=>"14a34a5e-51a3-4575-a51e-9c73ea31dff9", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "Uniqueid"=>"1360213046.1619", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "ConnectedLineNum"=>"", "ConnectedLineName"=>"304 User", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="OriginateResponse", headers={"Privilege"=>"call,all", "ActionID"=>"78452038-a272-4952-be2d-08c8501e6801", "Response"=>"Success", "Channel"=>"UCX/304@304-0", "Context"=>"", "Exten"=>"", "Reason"=>"4", "Uniqueid"=>"1360213046.1619", "CallerIDNum"=>"<unknown>", "CallerIDName"=>"304 User"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "Uniqueid"=>"1360213046.1619", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "Exten"=>"304", "Context"=>"ext-local", "Hint"=>"UCX/304@304", "Status"=>"2"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="OriginateResponse", headers={"Privilege"=>"call,all", "ActionID"=>"78452038-a272-4952-be2d-08c8501e6801", "Response"=>"Success", "Channel"=>"UCX/304@304-0", "Context"=>"", "Exten"=>"", "Reason"=>"4", "Uniqueid"=>"1360213046.1619", "CallerIDNum"=>"<unknown>", "CallerIDName"=>"304 User"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"UCX/304@304-0", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20UCX%2F304%40304-0%0Aagi_language%3A%20en%0Aagi_type%3A%20UCX%0Aagi_uniqueid%3A%201360213046.1619%0Aagi_version%3A%201.8.17.0%0Aagi_callerid%3A%20304%0Aagi_calleridname%3A%20304%20User%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%20unknown%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-internal%0Aagi_extension%3A%20s%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%201088198976%0A%0A"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "Exten"=>"304", "Context"=>"ext-local", "Hint"=>"UCX/304@304", "Status"=>"2"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"UCX/304@304-0", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20UCX%2F304%40304-0%0Aagi_language%3A%20en%0Aagi_type%3A%20UCX%0Aagi_uniqueid%3A%201360213046.1619%0Aagi_version%3A%201.8.17.0%0Aagi_callerid%3A%20304%0Aagi_calleridname%3A%20304%20User%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%20unknown%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-internal%0Aagi_extension%3A%20s%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%201088198976%0A%0A"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"UCX/304@304-0", :variable=>"punchblock_call_id", :value=>"14a34a5e-51a3-4575-a51e-9c73ea31dff9", :uniqueid=>"1360213046.1619"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Newstate", attributes_hash={:privilege=>"call,all", :channel=>"UCX/304@304-0", :channelstate=>"6", :channelstatedesc=>"Up", :calleridnum=>"304", :calleridname=>"304 User", :connectedlinenum=>"", :connectedlinename=>"304 User", :uniqueid=>"1360213046.1619"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="NewAccountCode", attributes_hash={:privilege=>"call,all", :channel=>"UCX/304@304-0", :uniqueid=>"1360213046.1619", :accountcode=>"", :oldaccountcode=>""}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="OriginateResponse", attributes_hash={:privilege=>"call,all", :actionid=>"78452038-a272-4952-be2d-08c8501e6801", :response=>"Success", :channel=>"UCX/304@304-0", :context=>"", :exten=>"", :reason=>"4", :uniqueid=>"1360213046.1619", :calleridnum=>"<unknown>", :calleridname=>"304 User"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"UCX/304@304-0", :env=>"agi_request%3A%20async%0Aagi_channel%3A%20UCX%2F304%40304-0%0Aagi_language%3A%20en%0Aagi_type%3A%20UCX%0Aagi_uniqueid%3A%201360213046.1619%0Aagi_version%3A%201.8.17.0%0Aagi_callerid%3A%20304%0Aagi_calleridname%3A%20304%20User%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%20unknown%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-internal%0Aagi_extension%3A%20s%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%201088198976%0A%0A"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Answered headers_hash={}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:08] INFO Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Executing controller #<CallContoller call=14a34a5e-51a3-4575-a51e-9c73ea31dff9, metadata={:call_id=>112, :prompt=>"Test 304", :channel=>"UCX/304", :contact_id=>6, :name=>"304 User", :message=>#<Message id: 112, topic: "Test 304", status: "initial", to: "UCX/304", from: nil, message_type: "call", messageable_type: "MultipartyNotification", messageable_id: 71, sender_type: "Contact", sender_id: 6, receiver_type: "Contact", receiver_id: 6, created_at: "2013-04-16 14:58:05", updated_at: "2013-04-16 14:58:05", address_position: 0, retry_attempt: 0, queue_position: 112>}>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: CallController#Run
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Executing command #<Punchblock::Component::Output target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new, voice=nil, ssml=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">custom/code_orange</speak>, interrupt_on=nil, start_offset=nil, start_paused=false, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [QUEUE]: Action: agi
ActionID: a6eb41d1-5c73-4220-a699-07dbf8568358
Channel: UCX/304@304-0
Command: EXEC Playback "custom/code_orange"
CommandID: 5a8bf452-a0f8-4e71-b708-10c167279e93
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [SEND]: Action: agi
ActionID: a6eb41d1-5c73-4220-a699-07dbf8568358
Channel: UCX/304@304-0
Command: EXEC Playback "custom/code_orange"
CommandID: 5a8bf452-a0f8-4e71-b708-10c167279e93
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [SEND] Action: agi
ActionID: a6eb41d1-5c73-4220-a699-07dbf8568358
Channel: UCX/304@304-0
Command: EXEC Playback "custom/code_orange"
CommandID: 5a8bf452-a0f8-4e71-b708-10c167279e93
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] Response: Success
ActionID: a6eb41d1-5c73-4220-a699-07dbf8568358
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] Message: Added AGI command to queue
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"a6eb41d1-5c73-4220-a699-07dbf8568358", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"a6eb41d1-5c73-4220-a699-07dbf8568358", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SubEvent: Start
Channel: UCX/304@304-0
CommandId: 1303994933
Command: EXEC Playback "custom/code_orange"
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"UCX/304@304-0", "CommandId"=>"1303994933", "Command"=>"EXEC Playback \"custom/code_orange\""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"UCX/304@304-0", "CommandId"=>"1303994933", "Command"=>"EXEC Playback \"custom/code_orange\""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:08] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"UCX/304@304-0", :commandid=>"1303994933", :command=>"EXEC Playback \"custom/code_orange\""}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
Channel: UCX/304@304-0
Variable: PLAYBACKSTATUS
Value: SUCCESS
Uniqueid: 1360213046.1619
Event: AGIExec
Privilege: agi,all
SubEvent: End
Channel: UCX/304@304-0
CommandId: 1303994933
Command: EXEC Playback "custom/code_orange"
ResultCode: 200
Result: Success
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"UCX/304@304-0", "CommandId"=>"1303994933", "Command"=>"EXEC Playback \"custom/code_orange\"", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] Event: AsyncAGI
Privilege: agi,all
SubEvent: Exec
Channel: UCX/304@304-0
CommandID: 5a8bf452-a0f8-4e71-b708-10c167279e93
Result: 200%20result%3D0%0A
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"UCX/304@304-0", "CommandID"=>"5a8bf452-a0f8-4e71-b708-10c167279e93", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"UCX/304@304-0", "CommandId"=>"1303994933", "Command"=>"EXEC Playback \"custom/code_orange\"", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"UCX/304@304-0", "CommandID"=>"5a8bf452-a0f8-4e71-b708-10c167279e93", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"UCX/304@304-0", :commandid=>"1303994933", :command=>"EXEC Playback \"custom/code_orange\"", :resultcode=>"200", :result=>"Success"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:11] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"UCX/304@304-0", :variable=>"PLAYBACKSTATUS", :value=>"SUCCESS", :uniqueid=>"1360213046.1619"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:11] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Exec", :channel=>"UCX/304@304-0", :commandid=>"5a8bf452-a0f8-4e71-b708-10c167279e93", :result=>"200%20result%3D0%0A"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:11] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Component::Output::Complete::Success name=:success, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id="884b243d-4883-4c27-8576-9332607fca62", target_mixer_name=nil>, recording=nil, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id="884b243d-4883-4c27-8576-9332607fca62", target_mixer_name=nil>
[2013-04-16 10:58:11] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Executing command #<Punchblock::Component::Input mode=:dtmf, terminator=nil, recognizer=nil, initial_timeout=nil, inter_digit_timeout=nil, sensitivity=nil, min_confidence=nil, grammar=#<Punchblock::Component::Input::Grammar content_type="application/srgs+xml", value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="inputdigits">
<rule id="inputdigits" scope="public">
<one-of>
<item>0</item>
<item>1</item>
<item>2</item>
<item>3</item>
<item>4</item>
<item>5</item>
<item>6</item>
<item>7</item>
<item>8</item>
<item>9</item>
<item>#</item>
<item>*</item>
</one-of>
</rule>
</grammar>, url=nil, target_call_id=nil, component_id=nil, target_mixer_name=nil>, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-04-16 10:58:11] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Executing command #<Punchblock::Component::Output target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new, voice=nil, ssml=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">custom/press_1_or_2</speak>, interrupt_on=nil, start_offset=nil, start_paused=false, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [QUEUE]: Action: agi
ActionID: 7a1797ec-a06c-4553-8f72-86b86ed8b981
Channel: UCX/304@304-0
Command: EXEC Playback "custom/press_1_or_2"
CommandID: baa368af-4479-4790-aea6-88dc97141707
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [SEND]: Action: agi
ActionID: 7a1797ec-a06c-4553-8f72-86b86ed8b981
Channel: UCX/304@304-0
Command: EXEC Playback "custom/press_1_or_2"
CommandID: baa368af-4479-4790-aea6-88dc97141707
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [SEND] Action: agi
ActionID: 7a1797ec-a06c-4553-8f72-86b86ed8b981
Channel: UCX/304@304-0
Command: EXEC Playback "custom/press_1_or_2"
CommandID: baa368af-4479-4790-aea6-88dc97141707
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] Response: Success
ActionID: 7a1797ec-a06c-4553-8f72-86b86ed8b981
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] Message: Added AGI command to queue
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"7a1797ec-a06c-4553-8f72-86b86ed8b981", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"7a1797ec-a06c-4553-8f72-86b86ed8b981", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SubEvent: Start
Channel: UCX/304@304-0
CommandId: 1033783847
Command: EXEC Playback "custom/press_1_or_2"
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"UCX/304@304-0", "CommandId"=>"1033783847", "Command"=>"EXEC Playback \"custom/press_1_or_2\""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"UCX/304@304-0", "CommandId"=>"1033783847", "Command"=>"EXEC Playback \"custom/press_1_or_2\""}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:11] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"UCX/304@304-0", :commandid=>"1033783847", :command=>"EXEC Playback \"custom/press_1_or_2\""}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
Channel: UCX/304@304-0
Variable: PLAYBACKSTATUS
Value: SUCCESS
Uniqueid: 1360213046.1619
Event: AGIExec
Privilege: agi,all
SubEvent: End
Channel: UCX/304@304-0
CommandId: 1033783847
Command: EXEC Playback "custom/press_1_or_2"
ResultCode: 200
Result: Unknown Result
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"UCX/304@304-0", "CommandId"=>"1033783847", "Command"=>"EXEC Playback \"custom/press_1_or_2\"", "ResultCode"=>"200", "Result"=>"Unknown Result"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"UCX/304@304-0", "CommandId"=>"1033783847", "Command"=>"EXEC Playback \"custom/press_1_or_2\"", "ResultCode"=>"200", "Result"=>"Unknown Result"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] Event: AsyncAGI
Privilege: agi,all
SubEvent: Exec
Channel: UCX/304@304-0
CommandID: baa368af-4479-4790-aea6-88dc97141707
Result: 200%20result%3D-1%0A
Event: AsyncAGI
Privilege: agi,all
SubEvent: End
Channel: UCX/304@304-0
Event: VarSet
Privilege: dialplan,all
Channel: UCX/304@304-0
Variable: AGISTATUS
Value: HANGUP
Uniqueid: 1360213046.1619
Event: Hangup
Privilege: call,all
Channel: UCX/304@304-0
Uniqueid: 1360213046.1619
CallerIDNum: 304
CallerIDName: 304 User
ConnectedLineNum: <unknown>
ConnectedLineName: 304 User
Cause: 0
Cause-txt: Unknown
Event: ExtensionStatus
Privilege: call,all
Exten: 304
Context: ext-local
Hint: UCX/304@304
Status: 0
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"UCX/304@304-0", "CommandID"=>"baa368af-4479-4790-aea6-88dc97141707", "Result"=>"200%20result%3D-1%0A"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"UCX/304@304-0"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"UCX/304@304-0", "CommandID"=>"baa368af-4479-4790-aea6-88dc97141707", "Result"=>"200%20result%3D-1%0A"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"UCX/304@304-0"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "Uniqueid"=>"1360213046.1619", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"304 User", "Cause"=>"0", "Cause-txt"=>"Unknown"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV] #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "Exten"=>"304", "Context"=>"ext-local", "Hint"=>"UCX/304@304", "Status"=>"0"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"UCX/304@304-0", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1360213046.1619"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"UCX/304@304-0", "Uniqueid"=>"1360213046.1619", "CallerIDNum"=>"304", "CallerIDName"=>"304 User", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"304 User", "Cause"=>"0", "Cause-txt"=>"Unknown"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "Exten"=>"304", "Context"=>"ext-local", "Hint"=>"UCX/304@304", "Status"=>"0"}, text_body=nil, events=nil, action=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"UCX/304@304-0", :commandid=>"1033783847", :command=>"EXEC Playback \"custom/press_1_or_2\"", :resultcode=>"200", :result=>"Unknown Result"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"UCX/304@304-0", :variable=>"PLAYBACKSTATUS", :value=>"SUCCESS", :uniqueid=>"1360213046.1619"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Exec", :channel=>"UCX/304@304-0", :commandid=>"baa368af-4479-4790-aea6-88dc97141707", :result=>"200%20result%3D-1%0A"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"UCX/304@304-0"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Hangup", attributes_hash={:privilege=>"call,all", :channel=>"UCX/304@304-0", :uniqueid=>"1360213046.1619", :calleridnum=>"304", :calleridname=>"304 User", :connectedlinenum=>"<unknown>", :connectedlinename=>"304 User", :cause=>"0", :cause_txt=>"Unknown"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"UCX/304@304-0", :variable=>"AGISTATUS", :value=>"HANGUP", :uniqueid=>"1360213046.1619"}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Component::Output::Complete::Success name=:success, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id="8e111a18-81fb-4476-9a02-d34a310c8a7d", target_mixer_name=nil>, recording=nil, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id="8e111a18-81fb-4476-9a02-d34a310c8a7d", target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Event::Complete::Hangup name=:hangup, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id="91a97a73-71ef-4029-88c7-965b00aecc33", target_mixer_name=nil>, recording=nil, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id="91a97a73-71ef-4029-88c7-965b00aecc33", target_mixer_name=nil>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Executing command #<Punchblock::Component::Input mode=:dtmf, terminator=nil, recognizer=nil, initial_timeout=8000, inter_digit_timeout=8000, sensitivity=nil, min_confidence=nil, grammar=#<Punchblock::Component::Input::Grammar content_type="application/srgs+xml", value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="inputdigits">
<rule id="inputdigits" scope="public">
<one-of>
<item>0</item>
<item>1</item>
<item>2</item>
<item>3</item>
<item>4</item>
<item>5</item>
<item>6</item>
<item>7</item>
<item>8</item>
<item>9</item>
<item>#</item>
<item>*</item>
</one-of>
</rule>
</grammar>, url=nil, target_call_id=nil, component_id=nil, target_mixer_name=nil>, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Receiving message: #<Punchblock::Event::End reason=:hangup, headers_hash={}, target_call_id="14a34a5e-51a3-4575-a51e-9c73ea31dff9", component_id=nil, target_mixer_name=nil>
[2013-04-16 10:58:13] INFO Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Call ended
[2013-04-16 10:58:13] ERROR Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: <Adhearsion::Call::Hangup> Adhearsion::Call::Hangup
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/gems/celluloid-0.13.0/lib/celluloid/responses.rb:29:in `value'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/gems/celluloid-0.13.0/lib/celluloid/actor.rb:69:in `call'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/gems/celluloid-0.13.0/lib/celluloid/legacy.rb:14:in `method_missing'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call.rb:23:in `method_missing'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:174:in `write_and_await_response'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:179:in `execute_component_and_await_completion'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller/input.rb:215:in `wait_for_digit'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller/input.rb:196:in `play_sound_files_for_menu'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller/input.rb:156:in `block in menu'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller/input.rb:139:in `catch'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller/input.rb:139:in `menu'
/Users/spallen/myprojects/adhearsion/ace/ucx-ace/plugins/ace_code_orange/lib/ace_code_orange/call_controller.rb:10:in `block in run'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/foundation/exception_handler.rb:5:in `catching_standard_errors'
/Users/spallen/myprojects/adhearsion/ace/ucx-ace/plugins/ace_code_orange/lib/ace_code_orange/call_controller.rb:5:in `run'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:106:in `execute!'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:81:in `block in exec'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:80:in `catch'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:80:in `exec'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:97:in `exec_with_callback'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:91:in `block (2 levels) in bg_exec'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/foundation/exception_handler.rb:5:in `catching_standard_errors'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/bundler/gems/adhearsion-c1153ea9d489/lib/adhearsion/call_controller.rb:90:in `block in bg_exec'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/gems/logging-1.8.1/lib/logging/diagnostic_context.rb:323:in `call'
/Users/spallen/.rvm/gems/ruby-1.9.3-p327@ace/gems/logging-1.8.1/lib/logging/diagnostic_context.rb:323:in `block in create_with_logging_context'
[2013-04-16 10:58:13] DEBUG Adhearsion::OutboundCall: 14a34a5e-51a3-4575-a51e-9c73ea31dff9: Finished executing controller #<CallContoller call=14a34a5e-51a3-4575-a51e-9c73ea31dff9, metadata={:call_id=>112, :prompt=>"Test 304", :channel=>"UCX/304", :contact_id=>6, :name=>"304 User", :message=>#<Message id: 112, topic: "Test 304", status: "initial", to: "UCX/304", from: nil, message_type: "call", messageable_type: "MultipartyNotification", messageable_id: 71, sender_type: "Contact", sender_id: 6, receiver_type: "Contact", receiver_id: 6, created_at: "2013-04-16 14:58:05", updated_at: "2013-04-16 14:58:05", address_position: 0, retry_attempt: 0, queue_position: 112>}>
Raw
call_controller.rb
# encoding: utf-8
class CallContoller < Adhearsion::CallController
def run
catching_standard_errors do
logger.debug "CallController#Run"
answer
play 'custom/test'
menu 'custom/press_1_or_2', tries: 2, timeout: 8.to_i.seconds do
match 1 do
play 'custom/acceptance'
logger.debug "===> pressed 1"
end
match 2 do
play 'custom/decline'
logger.debug "===> pressed 2"
end
end
end
end
end
Raw
originate.rb
def originate(meta_data, name)
call_timeout = 20
@my_call = Adhearsion::OutboundCall.originate meta_data[:channel], controller: CallContoller,
controller_metadata: meta_data,
from: name,
timeout: call_timeout.to_i.seconds
end
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment