Skip to content

Instantly share code, notes, and snippets.

@smpallen99

smpallen99/Log

Created October 19, 2012 20:48
Show Gist options
  • Save smpallen99/3920641 to your computer and use it in GitHub Desktop.
Save smpallen99/3920641 to your computer and use it in GitHub Desktop.
Download ZIP
Adhearsion Ghost Calls
Raw
adhearsion.rb
class DrbEndpoint
def group_call(channel = nil, prompt = nil)
channel ||= 'SIP/3100'
prompt ||= 'custom/conference_invite3'
logger.info "=====> initiated call to #{channel}"
Adhearsion::OutboundCall.originate channel
end
end
Adhearsion.config do |config|
config.development do |dev|
dev.platform.logging.level = :debug
end
##
# Use with Asterisk
#
config.punchblock.platform = :asterisk # Use Asterisk
config.punchblock.username = "adhearsion" # Your AMI username
config.punchblock.password = "xxxx" # Your AMI password
config.punchblock.host = "10.35.15.100" # "127.0.0.1" # Your AMI host
config.adhearsion_drb.host = "127.0.0.1"
config.adhearsion_drb.port = "9950".to_i
config.adhearsion_drb.acl.allow = ["127.0.0.1"]
config.adhearsion_drb.acl.deny = []
config.adhearsion_drb.shared_object = DrbEndpoint.new
end
Adhearsion::Events.draw do
end
Adhearsion.router do
route 'Everyone else', TestCallController
end
Raw
Log
➜ sample
➜ sample ahn -
Starting Adhearsion server at /Users/spallen/myprojects/adhearsion/sample
[2012-10-19 16:41:28] DEBUG Adhearsion::LinuxProcName: Error while attaching libc function prctl: Function 'prctl' not found in [libc.dylib]
[2012-10-19 16:41:28] INFO Adhearsion::Initializer: Setting RAILS_ENV to "development"
[2012-10-19 16:41:28] INFO Adhearsion::Drb::Plugin::Service: Starting DRb on 127.0.0.1:9950
[2012-10-19 16:41:28] INFO Adhearsion::Console: Launching Adhearsion Console
AHN> [2012-10-19 16:41:28] INFO Adhearsion::PunchblockPlugin::Initializer: Starting connection to server
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: Starting up...
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: Starting up...
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Asterisk Call Manager/1.1
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [SEND] Action: login
ActionID: faa5fde2-28c1-4048-ba80-2c7382f11d29
Username: adhearsion
Secret: test123
Events: On
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Asterisk Call Manager/1.1
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [SEND] Action: login
ActionID: 032baf14-184b-4799-9776-880d458b3cf8
Username: adhearsion
Secret: test123
Events: On
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Response: Success
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: faa5fde2-28c1-4048-ba80-2c7382f11d29
Message: Authentication accepted
Event: FullyBooted
Privilege: system,all
Status: Fully Booted
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"faa5fde2-28c1-4048-ba80-2c7382f11d29", "Message"=>"Authentication accepted"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] ActionID: 032baf14-184b-4799-9776-880d458b3cf8
Message: Authentication accepted
Event: FullyBooted
Privilege: system,all
Status: Fully Booted
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="FullyBooted", headers={"Privilege"=>"system,all", "Status"=>"Fully Booted"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"032baf14-184b-4799-9776-880d458b3cf8", "Message"=>"Authentication accepted"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="FullyBooted", headers={"Privilege"=>"system,all", "Status"=>"Fully Booted"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [SEND] Action: events
ActionID: f29af3ec-b640-4030-b7ec-5f900105b49a
EventMask: Off
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: f29af3ec-b640-4030-b7ec-5f900105b49a
Events: Off
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"f29af3ec-b640-4030-b7ec-5f900105b49a", "Events"=>"Off"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [SEND] Action: command
ActionID: 9584f107-1ac6-4181-aa57-3b5fd56cce29
Command: dialplan add extension 1,1,AGI,agi:async into adhearsion-redirect
[2012-10-19 16:41:28] INFO Adhearsion::PunchblockPlugin::Initializer: Connected to Punchblock server
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Response: Follows
Privilege: Command
ActionID: 9584f107-1ac6-4181-aa57-3b5fd56cce29
[2012-10-19 16:41:28] INFO Adhearsion::Process: Transitioning from booting to running with 0 active calls due to booted event.
[2012-10-19 16:41:28] INFO Adhearsion::Initializer: Adhearsion v2.1.3 initialized in "development"!
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Extension '1,1,AGI(agi:async)' added into 'adhearsion-redirect' context
--END COMMAND--
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"Privilege"=>"Command", "ActionID"=>"9584f107-1ac6-4181-aa57-3b5fd56cce29", "Extension '1,1,AGI(agi"=>"async)' added into 'adhearsion-redirect' context\n--END COMMAND--"}, text_body="Extension '1,1,AGI(agi:async)' added into 'adhearsion-redirect' context", events=nil, action=nil>
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [SEND] Action: command
ActionID: b46eec91-541a-4b9e-b945-7a532e860d05
Command: dialplan show adhearsion-redirect
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] Response: Follows
Privilege: Command
ActionID: b46eec91-541a-4b9e-b945-7a532e860d05
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] [ Context 'adhearsion-redirect' created by 'pbx_config' ]
'1' => 1. AGI(agi:async) [pbx_config]
-= 1 extension (1 priority) in 1 context. =-
--END COMMAND--
[2012-10-19 16:41:28] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"Privilege"=>"Command", "ActionID"=>"b46eec91-541a-4b9e-b945-7a532e860d05"}, text_body="[ Context 'adhearsion-redirect' created by 'pbx_config' ]\n '1' => 1. AGI(agi:async) [pbx_config]\n\n-= 1 extension (1 priority) in 1 context. =-", events=nil, action=nil>
[2012-10-19 16:42:06] INFO DrbEndpoint: =====> initiated call to SIP/3100
[2012-10-19 16:42:06] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial to="SIP/3100", from=nil, join=nil, headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [SEND] Action: originate
ActionID: 6bbac02b-d41e-43b0-afc8-2f85da0fa0bf
Async: true
Application: AGI
Data: agi:async
Channel: SIP/3100
Callerid:
Variable: punchblock_call_id=f0fba150-0031-42aa-9102-aec91a23faf7
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 6bbac02b-d41e-43b0-afc8-2f85da0fa0bf
Message: Originate successfully queued
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"6bbac02b-d41e-43b0-afc8-2f85da0fa0bf", "Message"=>"Originate successfully queued"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] Event: Newchannel
Privilege: call,all
SequenceNumber: 1227
File: channel.c
Line: 1349
Func: __ast_channel_alloc_ap
Channel: SIP/3100-00000069
ChannelState: 0
ChannelStateDesc: Down
CallerIDNum: 3100
CallerIDName: device
AccountCode:
Exten:
Context: from-internal
Uniqueid: 1350673691.126
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newchannel", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1227", "File"=>"channel.c", "Line"=>"1349", "Func"=>"__ast_channel_alloc_ap", "Channel"=>"SIP/3100-00000069", "ChannelState"=>"0", "ChannelStateDesc"=>"Down", "CallerIDNum"=>"3100", "CallerIDName"=>"device", "AccountCode"=>"", "Exten"=>"", "Context"=>"from-internal", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1228
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: SIPCALLID
Value: 2f97a3f2342112a70b8c6ead7990e52c@10.30.15.124:5060
Uniqueid: 1350673691.126
Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1229
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: punchblock_call_id
Value: f0fba150-0031-42aa-9102-aec91a23faf7
Uniqueid: 1350673691.126
Event: NewAccountCode
Privilege: call,all
SequenceNumber: 1230
File: cdr.c
Line: 1010
Func: ast_cdr_setaccount
Channel: SIP/3100-00000069
Uniqueid: 1350673691.126
AccountCode:
OldAccountCode:
Event: NewCallerid
Privilege: call,all
SequenceNumber: 1231
File: channel.c
Line: 6371
Func: report_new_callerid
Channel: SIP/3100-00000069
CallerIDNum: 3100
CallerIDName: device
Uniqueid: 1350673691.126
CID-CallingPres: 0 (Presentation Allowed, Not Screened)
Event: ExtensionStatus
Privilege: call,all
SequenceNumber: 1232
File: manager.c
Line: 5255
Func: manager_state_cb
Exten: 3100
Context: ext-local
Hint: SIP/3100
Status: 8
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1228", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"SIPCALLID", "Value"=>"2f97a3f2342112a70b8c6ead7990e52c@10.30.15.124:5060", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1229", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"punchblock_call_id", "Value"=>"f0fba150-0031-42aa-9102-aec91a23faf7", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1230", "File"=>"cdr.c", "Line"=>"1010", "Func"=>"ast_cdr_setaccount", "Channel"=>"SIP/3100-00000069", "Uniqueid"=>"1350673691.126", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewCallerid", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1231", "File"=>"channel.c", "Line"=>"6371", "Func"=>"report_new_callerid", "Channel"=>"SIP/3100-00000069", "CallerIDNum"=>"3100", "CallerIDName"=>"device", "Uniqueid"=>"1350673691.126", "CID-CallingPres"=>"0 (Presentation Allowed, Not Screened)"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1232", "File"=>"manager.c", "Line"=>"5255", "Func"=>"manager_state_cb", "Exten"=>"3100", "Context"=>"ext-local", "Hint"=>"SIP/3100", "Status"=>"8"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1229", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"punchblock_call_id", :value=>"f0fba150-0031-42aa-9102-aec91a23faf7", :uniqueid=>"1350673691.126"}, target_call_id="f0fba150-0031-42aa-9102-aec91a23faf7", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] Event: Newstate
Privilege: call,all
SequenceNumber: 1233
File: channel.c
Line: 6991
Func: ast_setstate
Channel: SIP/3100-00000069
ChannelState: 5
ChannelStateDesc: Ringing
CallerIDNum: 3100
CallerIDName: device
ConnectedLineNum:
ConnectedLineName:
Uniqueid: 1350673691.126
[2012-10-19 16:42:07] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1233", "File"=>"channel.c", "Line"=>"6991", "Func"=>"ast_setstate", "Channel"=>"SIP/3100-00000069", "ChannelState"=>"5", "ChannelStateDesc"=>"Ringing", "CallerIDNum"=>"3100", "CallerIDName"=>"device", "ConnectedLineNum"=>"", "ConnectedLineName"=>"", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:07] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Newstate", attributes_hash={:privilege=>"call,all", :sequencenumber=>"1233", :file=>"channel.c", :line=>"6991", :func=>"ast_setstate", :channel=>"SIP/3100-00000069", :channelstate=>"5", :channelstatedesc=>"Ringing", :calleridnum=>"3100", :calleridname=>"device", :connectedlinenum=>"", :connectedlinename=>"", :uniqueid=>"1350673691.126"}, target_call_id="f0fba150-0031-42aa-9102-aec91a23faf7", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:07] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: Receiving message: #<Punchblock::Event::Ringing headers_hash={}, target_call_id="f0fba150-0031-42aa-9102-aec91a23faf7", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] Event: Newstate
Privilege: call,all
SequenceNumber: 1234
File: channel.c
Line: 6991
Func: ast_setstate
Channel: SIP/3100-00000069
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 3100
CallerIDName: device
ConnectedLineNum:
ConnectedLineName:
Uniqueid: 1350673691.126
Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1235
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: punchblock_call_id
Value: f0fba150-0031-42aa-9102-aec91a23faf7
Uniqueid: 1350673691.126
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1234", "File"=>"channel.c", "Line"=>"6991", "Func"=>"ast_setstate", "Channel"=>"SIP/3100-00000069", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"3100", "CallerIDName"=>"device", "ConnectedLineNum"=>"", "ConnectedLineName"=>"", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1235", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"punchblock_call_id", "Value"=>"f0fba150-0031-42aa-9102-aec91a23faf7", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] Event: NewAccountCode
Privilege: call,all
SequenceNumber: 1236
File: cdr.c
Line: 1010
Func: ast_cdr_setaccount
Channel: SIP/3100-00000069
Uniqueid: 1350673691.126
AccountCode:
OldAccountCode:
Event: OriginateResponse
Privilege: call,all
SequenceNumber: 1237
File: manager.c
Line: 3768
Func: fast_originate
ActionID: 6bbac02b-d41e-43b0-afc8-2f85da0fa0bf
Response: Success
Channel: SIP/3100-00000069
Context:
Exten:
Reason: 4
Uniqueid: 1350673691.126
CallerIDNum: <unknown>
CallerIDName: <unknown>
Event: ExtensionStatus
Privilege: call,all
SequenceNumber: 1238
File: manager.c
Line: 5255
Func: manager_state_cb
Exten: 3100
Context: ext-local
Hint: SIP/3100
Status: 1
Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1239
File: res_agi.c
Line: 1314
Func: launch_asyncagi
SubEvent: Start
Channel: SIP/3100-00000069
Env: agi_request%3A%20async%0Aagi_channel%3A%20SIP%2F3100-00000069%0Aagi_language%3A%20en%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201350673691.126%0Aagi_version%3A%201.8.16.0%0Aagi_callerid%3A%203100%0Aagi_calleridname%3A%20device%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%20%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%201112004928%0A%0A
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1236", "File"=>"cdr.c", "Line"=>"1010", "Func"=>"ast_cdr_setaccount", "Channel"=>"SIP/3100-00000069", "Uniqueid"=>"1350673691.126", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="OriginateResponse", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1237", "File"=>"manager.c", "Line"=>"3768", "Func"=>"fast_originate", "ActionID"=>"6bbac02b-d41e-43b0-afc8-2f85da0fa0bf", "Response"=>"Success", "Channel"=>"SIP/3100-00000069", "Context"=>"", "Exten"=>"", "Reason"=>"4", "Uniqueid"=>"1350673691.126", "CallerIDNum"=>"<unknown>", "CallerIDName"=>"<unknown>"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1238", "File"=>"manager.c", "Line"=>"5255", "Func"=>"manager_state_cb", "Exten"=>"3100", "Context"=>"ext-local", "Hint"=>"SIP/3100", "Status"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1239", "File"=>"res_agi.c", "Line"=>"1314", "Func"=>"launch_asyncagi", "SubEvent"=>"Start", "Channel"=>"SIP/3100-00000069", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2F3100-00000069%0Aagi_language%3A%20en%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201350673691.126%0Aagi_version%3A%201.8.16.0%0Aagi_callerid%3A%203100%0Aagi_calleridname%3A%20device%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%20%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%201112004928%0A%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Newstate", attributes_hash={:privilege=>"call,all", :sequencenumber=>"1234", :file=>"channel.c", :line=>"6991", :func=>"ast_setstate", :channel=>"SIP/3100-00000069", :channelstate=>"6", :channelstatedesc=>"Up", :calleridnum=>"3100", :calleridname=>"device", :connectedlinenum=>"", :connectedlinename=>"", :uniqueid=>"1350673691.126"}, target_call_id="f0fba150-0031-42aa-9102-aec91a23faf7", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="NewAccountCode", attributes_hash={:privilege=>"call,all", :sequencenumber=>"1236", :file=>"cdr.c", :line=>"1010", :func=>"ast_cdr_setaccount", :channel=>"SIP/3100-00000069", :uniqueid=>"1350673691.126", :accountcode=>"", :oldaccountcode=>""}, target_call_id="f0fba150-0031-42aa-9102-aec91a23faf7", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: Receiving message: #<Punchblock::Event::Answered headers_hash={}, target_call_id="f0fba150-0031-42aa-9102-aec91a23faf7", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] INFO Adhearsion::Router: Call f0fba150-0031-42aa-9102-aec91a23faf7 selected route "Everyone else" (TestCallController)
[2012-10-19 16:42:10] INFO Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: Executing controller #<TestCallController call=f0fba150-0031-42aa-9102-aec91a23faf7, metadata={}>
[2012-10-19 16:42:10] ERROR Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: <NoMethodError> undefined method `call' for #<Thread:0x007fb18f41a968 sleep>
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/adhearsion-2.1.3/lib/adhearsion/outbound_call.rb:78:in `block in run_router'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/adhearsion-2.1.3/lib/adhearsion/foundation/exception_handler.rb:5:in `catching_standard_errors'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/adhearsion-2.1.3/lib/adhearsion/outbound_call.rb:76:in `run_router'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/adhearsion-2.1.3/lib/adhearsion/outbound_call.rb:84:in `block in run_router_on_answer'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:113:in `call'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:113:in `call_handler'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:85:in `block (3 levels) in trigger_handler'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:85:in `catch'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:85:in `block (2 levels) in trigger_handler'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:84:in `each'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:84:in `find'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:84:in `block in trigger_handler'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:83:in `catch'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/has-guarded-handlers-1.3.1/lib/has_guarded_handlers.rb:83:in `trigger_handler'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/adhearsion-2.1.3/lib/adhearsion/call.rb:101:in `block in deliver_message'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/adhearsion-2.1.3/lib/adhearsion/foundation/exception_handler.rb:5:in `catching_standard_errors'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/adhearsion-2.1.3/lib/adhearsion/call.rb:101:in `deliver_message'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/celluloid-0.12.2/lib/celluloid/calls.rb:99:in `dispatch'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/celluloid-0.12.2/lib/celluloid/actor.rb:323:in `block in handle_message'
/Users/spallen/.rvm/gems/ruby-1.9.3-p194@apps/gems/celluloid-0.12.2/lib/celluloid/tasks/task_fiber.rb:22:in `block in initialize'
[2012-10-19 16:42:10] INFO Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7:
=====> TestCallController Run
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: : Receiving message: #<Punchblock::Event::Offer to="", from="device <SIP/3100>", headers={:x_agi_request=>"async", :x_agi_channel=>"SIP/3100-00000069", :x_agi_language=>"en", :x_agi_type=>"SIP", :x_agi_uniqueid=>"1350673691.126", :x_agi_version=>"1.8.16.0", :x_agi_callerid=>"3100", :x_agi_calleridname=>"device", :x_agi_callingpres=>"0", :x_agi_callingani2=>"0", :x_agi_callington=>"0", :x_agi_callingtns=>"0", :x_agi_dnid=>"unknown", :x_agi_rdnis=>"unknown", :x_agi_context=>"from-internal", :x_agi_extension=>"", :x_agi_priority=>"1", :x_agi_enhanced=>"0.0", :x_agi_accountcode=>"", :x_agi_threadid=>"1112004928"}, call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4"
[2012-10-19 16:42:10] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: 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/grammar+grxml", 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>
[2012-10-19 16:42:10] INFO Adhearsion::Router: Call 5e4c7af0-635e-4c0f-977f-a047e6fe04c4 selected route "Everyone else" (TestCallController)
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Executing command #<Punchblock::Command::Accept headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-19 16:42:10] DEBUG Adhearsion::OutboundCall: f0fba150-0031-42aa-9102-aec91a23faf7: 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">hello</speak>, interrupt_on=nil, start_offset=nil, start_paused=false, repeat_interval=nil, repeat_times=nil, max_time=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 31f80f9e-e7f9-4488-9696-2548e18a9ac0
Channel: SIP/3100-00000069
Command: EXEC RINGING
CommandID: 66b51acb-4f1f-490f-ac46-ab720d86bf5b
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 31f80f9e-e7f9-4488-9696-2548e18a9ac0
Message: Added AGI command to queue
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"31f80f9e-e7f9-4488-9696-2548e18a9ac0", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 32f64672-b23f-422c-8fa3-004d1fd08610
Channel: SIP/3100-00000069
Command: EXEC Playback "hello"
CommandID: c4f461b3-6239-454b-921c-56bceaf4f922
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 32f64672-b23f-422c-8fa3-004d1fd08610
Message: Added AGI command to queue
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"32f64672-b23f-422c-8fa3-004d1fd08610", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SequenceNumber: 1240
File: res_agi.c
Line: 3365
Func: agi_handle_command
SubEvent: Start
Channel: SIP/3100-00000069
CommandId: 1833389758
Command: EXEC RINGING
Event: AGIExec
Privilege: agi,all
SequenceNumber: 1241
File: res_agi.c
Line: 3406
Func: agi_handle_command
SubEvent: End
Channel: SIP/3100-00000069
CommandId: 1833389758
Command: EXEC RINGING
ResultCode: 200
Result: Success
Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1242
File: res_agi.c
Line: 1354
Func: launch_asyncagi
SubEvent: Exec
Channel: SIP/3100-00000069
CommandID: 66b51acb-4f1f-490f-ac46-ab720d86bf5b
Result: 200%20result%3D0%0A
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1240", "File"=>"res_agi.c", "Line"=>"3365", "Func"=>"agi_handle_command", "SubEvent"=>"Start", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1833389758", "Command"=>"EXEC RINGING"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1241", "File"=>"res_agi.c", "Line"=>"3406", "Func"=>"agi_handle_command", "SubEvent"=>"End", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1833389758", "Command"=>"EXEC RINGING", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1242", "File"=>"res_agi.c", "Line"=>"1354", "Func"=>"launch_asyncagi", "SubEvent"=>"Exec", "Channel"=>"SIP/3100-00000069", "CommandID"=>"66b51acb-4f1f-490f-ac46-ab720d86bf5b", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SequenceNumber: 1243
File: res_agi.c
Line: 3365
Func: agi_handle_command
SubEvent: Start
Channel: SIP/3100-00000069
CommandId: 1487340990
Command: EXEC Playback "hello"
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1243", "File"=>"res_agi.c", "Line"=>"3365", "Func"=>"agi_handle_command", "SubEvent"=>"Start", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1487340990", "Command"=>"EXEC Playback \"hello\""}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1241", :file=>"res_agi.c", :line=>"3406", :func=>"agi_handle_command", :subevent=>"End", :channel=>"SIP/3100-00000069", :commandid=>"1833389758", :command=>"EXEC RINGING", :resultcode=>"200", :result=>"Success"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1240", :file=>"res_agi.c", :line=>"3365", :func=>"agi_handle_command", :subevent=>"Start", :channel=>"SIP/3100-00000069", :commandid=>"1833389758", :command=>"EXEC RINGING"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1242", :file=>"res_agi.c", :line=>"1354", :func=>"launch_asyncagi", :subevent=>"Exec", :channel=>"SIP/3100-00000069", :commandid=>"66b51acb-4f1f-490f-ac46-ab720d86bf5b", :result=>"200%20result%3D0%0A"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1243", :file=>"res_agi.c", :line=>"3365", :func=>"agi_handle_command", :subevent=>"Start", :channel=>"SIP/3100-00000069", :commandid=>"1487340990", :command=>"EXEC Playback \"hello\""}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:10] INFO Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Executing controller #<TestCallController call=5e4c7af0-635e-4c0f-977f-a047e6fe04c4, metadata={}>
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Offer to="", from="device <SIP/3100>", headers={:x_agi_request=>"async", :x_agi_channel=>"SIP/3100-00000069", :x_agi_language=>"en", :x_agi_type=>"SIP", :x_agi_uniqueid=>"1350673691.126", :x_agi_version=>"1.8.16.0", :x_agi_callerid=>"3100", :x_agi_calleridname=>"device", :x_agi_callingpres=>"0", :x_agi_callingani2=>"0", :x_agi_callington=>"0", :x_agi_callingtns=>"0", :x_agi_dnid=>"unknown", :x_agi_rdnis=>"unknown", :x_agi_context=>"from-internal", :x_agi_extension=>"", :x_agi_priority=>"1", :x_agi_enhanced=>"0.0", :x_agi_accountcode=>"", :x_agi_threadid=>"1112004928"}, call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4"
[2012-10-19 16:42:10] INFO Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4:
=====> TestCallController Run
[2012-10-19 16:42:10] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Executing command #<Punchblock::Command::Answer headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 370fedac-3950-4246-ae14-28b5185ecbb0
Channel: SIP/3100-00000069
Command: ANSWER
CommandID: 951662b7-91f2-493f-bb87-ebe7ba1679de
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] Response: Success
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] ActionID: 370fedac-3950-4246-ae14-28b5185ecbb0
Message: Added AGI command to queue
[2012-10-19 16:42:10] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"370fedac-3950-4246-ae14-28b5185ecbb0", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1244
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: PLAYBACKSTATUS
Value: SUCCESS
Uniqueid: 1350673691.126
Event: AGIExec
Privilege: agi,all
SequenceNumber: 1245
File: res_agi.c
Line: 3406
Func: agi_handle_command
SubEvent: End
Channel: SIP/3100-00000069
CommandId: 1487340990
Command: EXEC Playback "hello"
ResultCode: 200
Result: Success
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1244", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1245", "File"=>"res_agi.c", "Line"=>"3406", "Func"=>"agi_handle_command", "SubEvent"=>"End", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1487340990", "Command"=>"EXEC Playback \"hello\"", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1246
File: res_agi.c
Line: 1354
Func: launch_asyncagi
SubEvent: Exec
Channel: SIP/3100-00000069
CommandID: c4f461b3-6239-454b-921c-56bceaf4f922
Result: 200%20result%3D0%0A
Event: AGIExec
Privilege: agi,all
SequenceNumber: 1247
File: res_agi.c
Line: 3365
Func: agi_handle_command
SubEvent: Start
Channel: SIP/3100-00000069
CommandId: 293377328
Command: ANSWER
Event: AGIExec
Privilege: agi,all
SequenceNumber: 1248
File: res_agi.c
Line: 3406
Func: agi_handle_command
SubEvent: End
Channel: SIP/3100-00000069
CommandId: 293377328
Command: ANSWER
ResultCode: 200
Result: Success
Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1249
File: res_agi.c
Line: 1354
Func: launch_asyncagi
SubEvent: Exec
Channel: SIP/3100-00000069
CommandID: 951662b7-91f2-493f-bb87-ebe7ba1679de
Result: 200%20result%3D0%0A
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1246", "File"=>"res_agi.c", "Line"=>"1354", "Func"=>"launch_asyncagi", "SubEvent"=>"Exec", "Channel"=>"SIP/3100-00000069", "CommandID"=>"c4f461b3-6239-454b-921c-56bceaf4f922", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1247", "File"=>"res_agi.c", "Line"=>"3365", "Func"=>"agi_handle_command", "SubEvent"=>"Start", "Channel"=>"SIP/3100-00000069", "CommandId"=>"293377328", "Command"=>"ANSWER"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1248", "File"=>"res_agi.c", "Line"=>"3406", "Func"=>"agi_handle_command", "SubEvent"=>"End", "Channel"=>"SIP/3100-00000069", "CommandId"=>"293377328", "Command"=>"ANSWER", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1249", "File"=>"res_agi.c", "Line"=>"1354", "Func"=>"launch_asyncagi", "SubEvent"=>"Exec", "Channel"=>"SIP/3100-00000069", "CommandID"=>"951662b7-91f2-493f-bb87-ebe7ba1679de", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1245", :file=>"res_agi.c", :line=>"3406", :func=>"agi_handle_command", :subevent=>"End", :channel=>"SIP/3100-00000069", :commandid=>"1487340990", :command=>"EXEC Playback \"hello\"", :resultcode=>"200", :result=>"Success"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1246", :file=>"res_agi.c", :line=>"1354", :func=>"launch_asyncagi", :subevent=>"Exec", :channel=>"SIP/3100-00000069", :commandid=>"c4f461b3-6239-454b-921c-56bceaf4f922", :result=>"200%20result%3D0%0A"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1244", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"PLAYBACKSTATUS", :value=>"SUCCESS", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1247", :file=>"res_agi.c", :line=>"3365", :func=>"agi_handle_command", :subevent=>"Start", :channel=>"SIP/3100-00000069", :commandid=>"293377328", :command=>"ANSWER"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1248", :file=>"res_agi.c", :line=>"3406", :func=>"agi_handle_command", :subevent=>"End", :channel=>"SIP/3100-00000069", :commandid=>"293377328", :command=>"ANSWER", :resultcode=>"200", :result=>"Success"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1249", :file=>"res_agi.c", :line=>"1354", :func=>"launch_asyncagi", :subevent=>"Exec", :channel=>"SIP/3100-00000069", :commandid=>"951662b7-91f2-493f-bb87-ebe7ba1679de", :result=>"200%20result%3D0%0A"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: 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/grammar+grxml", 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>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 769a7717-f47a-4ced-87b6-270cd752e026
Channel: SIP/3100-00000069
Command: EXEC Progress
CommandID: 0190667e-d32a-4245-ad57-037c163625f2
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: 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">hello</speak>, interrupt_on=nil, start_offset=nil, start_paused=false, repeat_interval=nil, repeat_times=nil, max_time=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SequenceNumber: 1250
File: res_agi.c
Line: 3365
Func: agi_handle_command
SubEvent: Start
Channel: SIP/3100-00000069
CommandId: 1682371871
Command: EXEC Progress
Event: AGIExec
Privilege: agi,all
SequenceNumber: 1251
File: res_agi.c
Line: 3406
Func: agi_handle_command
SubEvent: End
Channel: SIP/3100-00000069
CommandId: 1682371871
Command: EXEC Progress
ResultCode: 200
Result: Success
Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1252
File: res_agi.c
Line: 1354
Func: launch_asyncagi
SubEvent: Exec
Channel: SIP/3100-00000069
CommandID: 0190667e-d32a-4245-ad57-037c163625f2
Result: 200%20result%3D0%0A
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 769a7717-f47a-4ced-87b6-270cd752e026
Message: Added AGI command to queue
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1250", "File"=>"res_agi.c", "Line"=>"3365", "Func"=>"agi_handle_command", "SubEvent"=>"Start", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1682371871", "Command"=>"EXEC Progress"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"769a7717-f47a-4ced-87b6-270cd752e026", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1251", "File"=>"res_agi.c", "Line"=>"3406", "Func"=>"agi_handle_command", "SubEvent"=>"End", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1682371871", "Command"=>"EXEC Progress", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1252", "File"=>"res_agi.c", "Line"=>"1354", "Func"=>"launch_asyncagi", "SubEvent"=>"Exec", "Channel"=>"SIP/3100-00000069", "CommandID"=>"0190667e-d32a-4245-ad57-037c163625f2", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1250", :file=>"res_agi.c", :line=>"3365", :func=>"agi_handle_command", :subevent=>"Start", :channel=>"SIP/3100-00000069", :commandid=>"1682371871", :command=>"EXEC Progress"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1251", :file=>"res_agi.c", :line=>"3406", :func=>"agi_handle_command", :subevent=>"End", :channel=>"SIP/3100-00000069", :commandid=>"1682371871", :command=>"EXEC Progress", :resultcode=>"200", :result=>"Success"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1252", :file=>"res_agi.c", :line=>"1354", :func=>"launch_asyncagi", :subevent=>"Exec", :channel=>"SIP/3100-00000069", :commandid=>"0190667e-d32a-4245-ad57-037c163625f2", :result=>"200%20result%3D0%0A"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 7ece3957-79c3-472b-b6f0-19996f972f66
Channel: SIP/3100-00000069
Command: EXEC Playback "hello,noanswer"
CommandID: 6b42ab94-de87-4b66-90ed-c6fc0e5d557e
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 7ece3957-79c3-472b-b6f0-19996f972f66
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] Message: Added AGI command to queue
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"7ece3957-79c3-472b-b6f0-19996f972f66", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SequenceNumber: 1253
File: res_agi.c
Line: 3365
Func: agi_handle_command
SubEvent: Start
Channel: SIP/3100-00000069
CommandId: 1253559287
Command: EXEC Playback "hello,noanswer"
[2012-10-19 16:42:11] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1253", "File"=>"res_agi.c", "Line"=>"3365", "Func"=>"agi_handle_command", "SubEvent"=>"Start", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1253559287", "Command"=>"EXEC Playback \"hello,noanswer\""}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:11] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1253", :file=>"res_agi.c", :line=>"3365", :func=>"agi_handle_command", :subevent=>"Start", :channel=>"SIP/3100-00000069", :commandid=>"1253559287", :command=>"EXEC Playback \"hello,noanswer\""}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:12] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1254
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: PLAYBACKSTATUS
Value: SUCCESS
Uniqueid: 1350673691.126
Event: AGIExec
Privilege: agi,all
SequenceNumber: 1255
File: res_agi.c
Line: 3406
Func: agi_handle_command
SubEvent: End
Channel: SIP/3100-00000069
CommandId: 1253559287
Command: EXEC Playback "hello,noanswer"
ResultCode: 200
Result: Success
[2012-10-19 16:42:12] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1254", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:12] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1255", "File"=>"res_agi.c", "Line"=>"3406", "Func"=>"agi_handle_command", "SubEvent"=>"End", "Channel"=>"SIP/3100-00000069", "CommandId"=>"1253559287", "Command"=>"EXEC Playback \"hello,noanswer\"", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:12] DEBUG RubyAMI::Client: [RECV] Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1256
File: res_agi.c
Line: 1354
Func: launch_asyncagi
SubEvent: Exec
Channel: SIP/3100-00000069
CommandID: 6b42ab94-de87-4b66-90ed-c6fc0e5d557e
Result: 200%20result%3D0%0A
[2012-10-19 16:42:12] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1256", "File"=>"res_agi.c", "Line"=>"1354", "Func"=>"launch_asyncagi", "SubEvent"=>"Exec", "Channel"=>"SIP/3100-00000069", "CommandID"=>"6b42ab94-de87-4b66-90ed-c6fc0e5d557e", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:12] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1254", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"PLAYBACKSTATUS", :value=>"SUCCESS", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:12] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1255", :file=>"res_agi.c", :line=>"3406", :func=>"agi_handle_command", :subevent=>"End", :channel=>"SIP/3100-00000069", :commandid=>"1253559287", :command=>"EXEC Playback \"hello,noanswer\"", :resultcode=>"200", :result=>"Success"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:12] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1256", :file=>"res_agi.c", :line=>"1354", :func=>"launch_asyncagi", :subevent=>"Exec", :channel=>"SIP/3100-00000069", :commandid=>"6b42ab94-de87-4b66-90ed-c6fc0e5d557e", :result=>"200%20result%3D0%0A"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:12] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Component::Output::Complete::Success name=:success, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="fd173118-f517-40a1-a723-17ccbbd7f685", target_mixer_name=nil>, recording=nil, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="fd173118-f517-40a1-a723-17ccbbd7f685", target_mixer_name=nil>
[2012-10-19 16:42:12] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Executing command #<Punchblock::Component::Input mode=:dtmf, terminator=nil, recognizer=nil, initial_timeout=1000000, inter_digit_timeout=1000000, sensitivity=nil, min_confidence=nil, grammar=#<Punchblock::Component::Input::Grammar content_type="application/grammar+grxml", 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>
[2012-10-19 16:42:12] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Event::Complete::Stop name=:stop, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="e3afb970-1c97-4f01-b913-8192b79d99ee", target_mixer_name=nil>, recording=nil, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="e3afb970-1c97-4f01-b913-8192b79d99ee", target_mixer_name=nil>
[2012-10-19 16:42:15] DEBUG RubyAMI::Client: [RECV] Event: DTMF
Privilege: dtmf,all
SequenceNumber: 1259
File: channel.c
Line: 3641
Func: send_dtmf_event
Channel: SIP/3100-00000069
Uniqueid: 1350673691.126
Digit: 1
Direction: Received
Begin: Yes
End: No
[2012-10-19 16:42:15] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="DTMF", headers={"Privilege"=>"dtmf,all", "SequenceNumber"=>"1259", "File"=>"channel.c", "Line"=>"3641", "Func"=>"send_dtmf_event", "Channel"=>"SIP/3100-00000069", "Uniqueid"=>"1350673691.126", "Digit"=>"1", "Direction"=>"Received", "Begin"=>"Yes", "End"=>"No"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:15] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="DTMF", attributes_hash={:privilege=>"dtmf,all", :sequencenumber=>"1259", :file=>"channel.c", :line=>"3641", :func=>"send_dtmf_event", :channel=>"SIP/3100-00000069", :uniqueid=>"1350673691.126", :digit=>"1", :direction=>"Received", :begin=>"Yes", :end=>"No"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:15] DEBUG RubyAMI::Client: [RECV] Event: DTMF
Privilege: dtmf,all
SequenceNumber: 1260
File: channel.c
Line: 3641
Func: send_dtmf_event
Channel: SIP/3100-00000069
Uniqueid: 1350673691.126
Digit: 1
Direction: Received
Begin: No
End: Yes
[2012-10-19 16:42:15] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="DTMF", headers={"Privilege"=>"dtmf,all", "SequenceNumber"=>"1260", "File"=>"channel.c", "Line"=>"3641", "Func"=>"send_dtmf_event", "Channel"=>"SIP/3100-00000069", "Uniqueid"=>"1350673691.126", "Digit"=>"1", "Direction"=>"Received", "Begin"=>"No", "End"=>"Yes"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:15] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="DTMF", attributes_hash={:privilege=>"dtmf,all", :sequencenumber=>"1260", :file=>"channel.c", :line=>"3641", :func=>"send_dtmf_event", :channel=>"SIP/3100-00000069", :uniqueid=>"1350673691.126", :digit=>"1", :direction=>"Received", :begin=>"No", :end=>"Yes"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: 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">calling</speak>, interrupt_on=nil, start_offset=nil, start_paused=false, repeat_interval=nil, repeat_times=nil, max_time=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Component::Input::Complete::Success mode=:dtmf, confidence=1.0, interpretation="dtmf-1", utterance="1", name=:success, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="52979425-08d5-4691-b674-03d482f7ba90", target_mixer_name=nil>, recording=nil, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="52979425-08d5-4691-b674-03d482f7ba90", target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 5721e755-9a77-4f3f-89c1-4b041ab1d08f
Channel: SIP/3100-00000069
Command: EXEC Playback "calling,noanswer"
CommandID: f6bb131b-9749-4f4f-8d35-0370b3a0edb4
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] Response: Success
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] ActionID: 5721e755-9a77-4f3f-89c1-4b041ab1d08f
Message: Added AGI command to queue
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"5721e755-9a77-4f3f-89c1-4b041ab1d08f", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SequenceNumber: 1261
File: res_agi.c
Line: 3365
Func: agi_handle_command
SubEvent: Start
Channel: SIP/3100-00000069
CommandId: 855436791
Command: EXEC Playback "calling,noanswer"
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1261", "File"=>"res_agi.c", "Line"=>"3365", "Func"=>"agi_handle_command", "SubEvent"=>"Start", "Channel"=>"SIP/3100-00000069", "CommandId"=>"855436791", "Command"=>"EXEC Playback \"calling,noanswer\""}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1261", :file=>"res_agi.c", :line=>"3365", :func=>"agi_handle_command", :subevent=>"Start", :channel=>"SIP/3100-00000069", :commandid=>"855436791", :command=>"EXEC Playback \"calling,noanswer\""}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1263
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: PLAYBACKSTATUS
Value: SUCCESS
Uniqueid: 1350673691.126
Event: AGIExec
Privilege: agi,all
SequenceNumber: 1264
File: res_agi.c
Line: 3406
Func: agi_handle_command
SubEvent: End
Channel: SIP/3100-00000069
CommandId: 855436791
Command: EXEC Playback "calling,noanswer"
ResultCode: 200
Result: Success
Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1265
File: res_agi.c
Line: 1354
Func: launch_asyncagi
SubEvent: Exec
Channel: SIP/3100-00000069
CommandID: f6bb131b-9749-4f4f-8d35-0370b3a0edb4
Result: 200%20result%3D0%0A
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1263", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1264", "File"=>"res_agi.c", "Line"=>"3406", "Func"=>"agi_handle_command", "SubEvent"=>"End", "Channel"=>"SIP/3100-00000069", "CommandId"=>"855436791", "Command"=>"EXEC Playback \"calling,noanswer\"", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1265", "File"=>"res_agi.c", "Line"=>"1354", "Func"=>"launch_asyncagi", "SubEvent"=>"Exec", "Channel"=>"SIP/3100-00000069", "CommandID"=>"f6bb131b-9749-4f4f-8d35-0370b3a0edb4", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1263", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"PLAYBACKSTATUS", :value=>"SUCCESS", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1265", :file=>"res_agi.c", :line=>"1354", :func=>"launch_asyncagi", :subevent=>"Exec", :channel=>"SIP/3100-00000069", :commandid=>"f6bb131b-9749-4f4f-8d35-0370b3a0edb4", :result=>"200%20result%3D0%0A"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1264", :file=>"res_agi.c", :line=>"3406", :func=>"agi_handle_command", :subevent=>"End", :channel=>"SIP/3100-00000069", :commandid=>"855436791", :command=>"EXEC Playback \"calling,noanswer\"", :resultcode=>"200", :result=>"Success"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] INFO Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Hanging up
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Executing command #<Punchblock::Command::Hangup headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [SEND] Action: hangup
ActionID: d6c87747-bcbc-4cb3-a1ee-e2676846aa6c
Channel: SIP/3100-00000069
Cause: 16
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: d6c87747-bcbc-4cb3-a1ee-e2676846aa6c
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] Message: Channel Hungup
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"d6c87747-bcbc-4cb3-a1ee-e2676846aa6c", "Message"=>"Channel Hungup"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] Event: AsyncAGI
Privilege: agi,all
SequenceNumber: 1266
File: res_agi.c
Line: 1417
Func: launch_asyncagi
SubEvent: End
Channel: SIP/3100-00000069
Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1267
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: AGISTATUS
Value: HANGUP
Uniqueid: 1350673691.126
Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1268
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: RTPAUDIOQOS
Value: ssrc=1008943839;themssrc=3833283878;lp=0;rxjitter=0.000498;rxcount=327;txjitter=0.000000;txcount=134;rlp=0;rtt=65535.999000
Uniqueid: 1350673691.126
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"1266", "File"=>"res_agi.c", "Line"=>"1417", "Func"=>"launch_asyncagi", "SubEvent"=>"End", "Channel"=>"SIP/3100-00000069"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1267", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Component::Output::Complete::Success name=:success, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="84ac2f55-22a6-48dc-89e5-6607ca6eafcc", target_mixer_name=nil>, recording=nil, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id="84ac2f55-22a6-48dc-89e5-6607ca6eafcc", target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1268", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"RTPAUDIOQOS", "Value"=>"ssrc=1008943839;themssrc=3833283878;lp=0;rxjitter=0.000498;rxcount=327;txjitter=0.000000;txcount=134;rlp=0;rtt=65535.999000", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1269
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: RTPAUDIOQOSJITTER
Value: minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000;
Uniqueid: 1350673691.126
Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1270
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: RTPAUDIOQOSLOSS
Value: minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000;
Uniqueid: 1350673691.126
Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1271
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: RTPAUDIOQOSRTT
Value: minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;
Uniqueid: 1350673691.126
Event: VarSet
Privilege: dialplan,all
SequenceNumber: 1272
File: pbx.c
Line: 10022
Func: pbx_builtin_setvar_helper
Channel: SIP/3100-00000069
Variable: RTPAUDIOQOS
Value: ssrc=1008943839;themssrc=3833283878;lp=0;rxjitter=0.000498;rxcount=327;txjitter=0.000000;txcount=134;rlp=0;rtt=65535.999000
Uniqueid: 1350673691.126
Event: Hangup
Privilege: call,all
SequenceNumber: 1273
File: channel.c
Line: 2880
Func: ast_hangup
Channel: SIP/3100-00000069
Uniqueid: 1350673691.126
CallerIDNum: 3100
CallerIDName: device
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Cause: 16
Cause-txt: Normal Clearing
Event: ExtensionStatus
Privilege: call,all
SequenceNumber: 1274
File: manager.c
Line: 5255
Func: manager_state_cb
Exten: 3100
Context: ext-local
Hint: SIP/3100
Status: 0
[2012-10-19 16:42:16] INFO Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Call was hung up
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1269", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"RTPAUDIOQOSJITTER", "Value"=>"minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000;", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1270", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"RTPAUDIOQOSLOSS", "Value"=>"minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000;", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1271", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"RTPAUDIOQOSRTT", "Value"=>"minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"1272", "File"=>"pbx.c", "Line"=>"10022", "Func"=>"pbx_builtin_setvar_helper", "Channel"=>"SIP/3100-00000069", "Variable"=>"RTPAUDIOQOS", "Value"=>"ssrc=1008943839;themssrc=3833283878;lp=0;rxjitter=0.000498;rxcount=327;txjitter=0.000000;txcount=134;rlp=0;rtt=65535.999000", "Uniqueid"=>"1350673691.126"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Finished executing controller #<TestCallController call=5e4c7af0-635e-4c0f-977f-a047e6fe04c4, metadata={}>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Hangup", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1273", "File"=>"channel.c", "Line"=>"2880", "Func"=>"ast_hangup", "Channel"=>"SIP/3100-00000069", "Uniqueid"=>"1350673691.126", "CallerIDNum"=>"3100", "CallerIDName"=>"device", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Cause"=>"16", "Cause-txt"=>"Normal Clearing"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="ExtensionStatus", headers={"Privilege"=>"call,all", "SequenceNumber"=>"1274", "File"=>"manager.c", "Line"=>"5255", "Func"=>"manager_state_cb", "Exten"=>"3100", "Context"=>"ext-local", "Hint"=>"SIP/3100", "Status"=>"0"}, text_body=nil, events=nil, action=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1267", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"AGISTATUS", :value=>"HANGUP", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :sequencenumber=>"1266", :file=>"res_agi.c", :line=>"1417", :func=>"launch_asyncagi", :subevent=>"End", :channel=>"SIP/3100-00000069"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1268", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"RTPAUDIOQOS", :value=>"ssrc=1008943839;themssrc=3833283878;lp=0;rxjitter=0.000498;rxcount=327;txjitter=0.000000;txcount=134;rlp=0;rtt=65535.999000", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1269", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"RTPAUDIOQOSJITTER", :value=>"minrxjitter=0.000000;maxrxjitter=0.000000;avgrxjitter=0.000000;stdevrxjitter=0.000000;reported_minjitter=0.000000;reported_maxjitter=0.000000;reported_avgjitter=0.000000;reported_stdevjitter=0.000000;", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1270", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"RTPAUDIOQOSLOSS", :value=>"minrxlost=0.000000;maxrxlost=0.000000;avgrxlost=0.000000;stdevrxlost=0.000000;reported_minlost=0.000000;reported_maxlost=0.000000;reported_avglost=0.000000;reported_stdevlost=0.000000;", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1271", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"RTPAUDIOQOSRTT", :value=>"minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :sequencenumber=>"1272", :file=>"pbx.c", :line=>"10022", :func=>"pbx_builtin_setvar_helper", :channel=>"SIP/3100-00000069", :variable=>"RTPAUDIOQOS", :value=>"ssrc=1008943839;themssrc=3833283878;lp=0;rxjitter=0.000498;rxcount=327;txjitter=0.000000;txcount=134;rlp=0;rtt=65535.999000", :uniqueid=>"1350673691.126"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Hangup", attributes_hash={:privilege=>"call,all", :sequencenumber=>"1273", :file=>"channel.c", :line=>"2880", :func=>"ast_hangup", :channel=>"SIP/3100-00000069", :uniqueid=>"1350673691.126", :calleridnum=>"3100", :calleridname=>"device", :connectedlinenum=>"<unknown>", :connectedlinename=>"<unknown>", :cause=>"16", :cause_txt=>"Normal Clearing"}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] DEBUG Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Receiving message: #<Punchblock::Event::End reason=:hangup, headers_hash={}, target_call_id="5e4c7af0-635e-4c0f-977f-a047e6fe04c4", component_id=nil, target_mixer_name=nil>
[2012-10-19 16:42:16] INFO Adhearsion::Call: 5e4c7af0-635e-4c0f-977f-a047e6fe04c4: Call ended
Raw
test_call_controller.rb
class TestCallController < Adhearsion::CallController
def run
logger.info "\n\n=====> TestCallController Run\n\n"
answer
found = false
ask 'hello', :timeout => 1000, :limit => 2 do |buffer|
found = true if buffer == "1"
end
if found
play "calling"
else
play 'by'
end
hangup
end
end
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment