Skip to content

Instantly share code, notes, and snippets.

@gemasergey

gemasergey/gist:3944490

Created October 24, 2012 07:09
Show Gist options

  • Select an option

    Learn more about clone URLs
  • Save gemasergey/3944490 to your computer and use it in GitHub Desktop.

Select an option

Learn more about clone URLs
Save gemasergey/3944490 to your computer and use it in GitHub Desktop.
Download ZIP
Adhearsion::OutboundCall.originate ghost call
Raw
gistfile1.txt
class TestOutCall < Adhearsion::CallController
def run
reject
Adhearsion::OutboundCall.originate "SIP/301", from: "30 <30>", timeout: 100, controller: Iamback
end
end
root@SERVER5:~/rbox# ahn -
Starting Adhearsion server at /root/rbox
[2012-10-24 08:30:49] TRACE Adhearsion::Initializer: OS: linux-gnu - RUBY: ruby 1.9.3
[2012-10-24 08:30:49] TRACE Adhearsion::Initializer: Environment: {"rvm_bin_path"=>"/usr/local/rvm/bin", "GEM_HOME"=>"/usr/local/rvm/gems/ruby-1.9.3-p286", "SHELL"=>"/bin/bash", "TERM"=>"xterm", "XDG_SESSION_COOKIE"=>"16e8c0ad735d1c8d9cb733834d57e3f8-1350809586.9319-1670370443", "IRBRC"=>"/usr/local/rvm/rubies/ruby-1.9.3-p286/.irbrc", "OLDPWD"=>"/root", "MY_RUBY_HOME"=>"/usr/local/rvm/rubies/ruby-1.9.3-p286", "USER"=>"root", "LS_COLORS"=>"rs=0:di=01;34:ln=01;36:hl=44;37:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:su=37;41:sg=30;43:ca=30;41:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arj=01;31:*.taz=01;31:*.lzh=01;31:*.lzma=01;31:*.zip=01;31:*.z=01;31:*.Z=01;31:*.dz=01;31:*.gz=01;31:*.bz2=01;31:*.bz=01;31:*.tbz2=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.rar=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.jpg=01;35:*.jpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.axv=01;35:*.anx=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=00;36:*.au=00;36:*.flac=00;36:*.mid=00;36:*.midi=00;36:*.mka=00;36:*.mp3=00;36:*.mpc=00;36:*.ogg=00;36:*.ra=00;36:*.wav=00;36:*.axa=00;36:*.oga=00;36:*.spx=00;36:*.xspf=00;36:", "SUDO_USER"=>"gemas", "__array_start"=>"0", "SUDO_UID"=>"1000", "rvm_path"=>"/usr/local/rvm", "USERNAME"=>"root", "escape_flag"=>"1", "rvm_prefix"=>"/usr/local", "PATH"=>"/usr/local/rvm/gems/ruby-1.9.3-p286/bin:/usr/local/rvm/gems/ruby-1.9.3-p286@global/bin:/usr/local/rvm/rubies/ruby-1.9.3-p286/bin:/usr/local/rvm/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games", "MAIL"=>"/var/mail/root", "PWD"=>"/root/rbox", "LANG"=>"C", "_second"=>"1", "rvm_version"=>"1.16.15 (stable)", "SHLVL"=>"1", "SUDO_COMMAND"=>"/bin/su", "HOME"=>"/root", "_first"=>"0", "LOGNAME"=>"root", "GEM_PATH"=>"/usr/local/rvm/gems/ruby-1.9.3-p286:/usr/local/rvm/gems/ruby-1.9.3-p286@global", "LESSOPEN"=>"| /usr/bin/lesspipe %s", "install_flag"=>"1", "SUDO_GID"=>"1000", "RUBY_VERSION"=>"ruby-1.9.3-p286", "LESSCLOSE"=>"/usr/bin/lesspipe %s %s", "_"=>"/usr/local/rvm/gems/ruby-1.9.3-p286/bin/ahn", "_ORIGINAL_GEM_PATH"=>"/usr/local/rvm/gems/ruby-1.9.3-p286:/usr/local/rvm/gems/ruby-1.9.3-p286@global", "BUNDLE_GEMFILE"=>"/root/rbox/Gemfile", "BUNDLE_BIN_PATH"=>"/usr/local/rvm/gems/ruby-1.9.3-p286@global/gems/bundler-1.2.1/bin/bundle", "RUBYOPT"=>"-I/usr/local/rvm/gems/ruby-1.9.3-p286@global/gems/bundler-1.2.1/lib -rbundler/setup"}
[2012-10-24 08:30:49] TRACE Adhearsion::Initializer: # ******* Configuration for adhearsion_activerecord **************
# Database adapter. It should be an adapter supported by ActiveRecord [AHN_ADHEARSION_ACTIVERECORD_ADAPTER]
config.adhearsion_activerecord.adapter = "mysql2"
# Database to connect to. [AHN_ADHEARSION_ACTIVERECORD_DATABASE]
config.adhearsion_activerecord.database = "callcenter"
# host where the database is installed [AHN_ADHEARSION_ACTIVERECORD_HOST]
config.adhearsion_activerecord.host = "192.168.2.55"
# paths to model files to load [AHN_ADHEARSION_ACTIVERECORD_MODEL_PATHS]
config.adhearsion_activerecord.model_paths = []
# valid password [AHN_ADHEARSION_ACTIVERECORD_PASSWORD]
config.adhearsion_activerecord.password = "1221"
# port where the database is listening [AHN_ADHEARSION_ACTIVERECORD_PORT]
config.adhearsion_activerecord.port = 3306
# valid db username [AHN_ADHEARSION_ACTIVERECORD_USERNAME]
config.adhearsion_activerecord.username = "root"
# ******* Configuration for punchblock **************
# The domain at which to address calls [AHN_PUNCHBLOCK_CALLS_DOMAIN]
config.punchblock.calls_domain = nil
# The amount of time to wait for a connection [AHN_PUNCHBLOCK_CONNECTION_TIMEOUT]
config.punchblock.connection_timeout = 60
# The default TTS voice to use. [AHN_PUNCHBLOCK_DEFAULT_VOICE]
config.punchblock.default_voice = nil
# Host punchblock needs to connect (where rayo/asterisk/freeswitch is located) [AHN_PUNCHBLOCK_HOST]
config.punchblock.host = "192.168.2.55"
# The media engine to use. Defaults to platform default. [AHN_PUNCHBLOCK_MEDIA_ENGINE]
config.punchblock.media_engine = nil
# The domain at which to address mixers [AHN_PUNCHBLOCK_MIXERS_DOMAIN]
config.punchblock.mixers_domain = nil
# Authentication credentials [AHN_PUNCHBLOCK_PASSWORD]
config.punchblock.password = "dtxyjcnm"
# Platform punchblock shall use to connect to the Telephony provider. Currently supported values:
# - :xmpp
# - :asterisk
# - :freeswitch [AHN_PUNCHBLOCK_PLATFORM]
config.punchblock.platform = :asterisk
# Port punchblock needs to connect [AHN_PUNCHBLOCK_PORT]
config.punchblock.port = 5038
# The number of times to (re)attempt connection to the server [AHN_PUNCHBLOCK_RECONNECT_ATTEMPTS]
config.punchblock.reconnect_attempts = Infinity
# Delay between connection attempts [AHN_PUNCHBLOCK_RECONNECT_TIMER]
config.punchblock.reconnect_timer = 5
# The root domain at which to address the server [AHN_PUNCHBLOCK_ROOT_DOMAIN]
config.punchblock.root_domain = nil
# Authentication credentials [AHN_PUNCHBLOCK_USERNAME]
config.punchblock.username = "ahn"
# ******* Configuration for platform **************
# Active environment. Supported values: development, production, staging, test [AHN_PLATFORM_ENVIRONMENT]
config.platform.environment = :development
# Folder to include the own libraries to be used. Adhearsion loads any ruby file
# located into this folder during the bootstrap process. Set to nil if you do not
# want these files to be loaded. This folder is relative to the application root folder. [AHN_PLATFORM_LIB]
config.platform.lib = "lib"
# Log configuration [AHN_PLATFORM_LOGGING]
config.platform.logging
# A log formatter to apply to all active outputters. If nil, the Adhearsion default formatter will be used. [AHN_PLATFORM_LOGGING_FORMATTER]
config.platform.logging.formatter = nil
# Supported levels (in increasing severity) -- :trace < :debug < :info < :warn < :error < :fatal [AHN_PLATFORM_LOGGING_LEVEL]
config.platform.logging.level = :trace
# An array of log outputters to use. The default is to log to stdout and log/adhearsion.log.
# Each item must be either a string to use as a filename, or a valid Logging appender (see http://github.com/TwP/logging) [AHN_PLATFORM_LOGGING_OUTPUTTERS]
config.platform.logging.outputters = ["log/adhearsion.log"]
# Adhearsion process name, useful to make it easier to find in the process list
# Pro tip: set this to your application's name and you can do "killall myapp"
# Does not work under JRuby. [AHN_PLATFORM_PROCESS_NAME]
config.platform.process_name = "ahn"
# Adhearsion application root folder [AHN_PLATFORM_ROOT]
config.platform.root = "/root/rbox"
[2012-10-24 08:30:49] TRACE Adhearsion::Initializer: Gem versions: ["rake 0.9.2.2", "i18n 0.6.1", "multi_json 1.3.6", "activesupport 3.2.8", "builder 3.0.4", "activemodel 3.2.8", "arel 3.0.2", "tzinfo 0.3.33", "activerecord 3.2.8", "adhearsion-loquacious 1.9.3", "bundler 1.2.1", "facter 1.6.13", "timers 1.0.1", "celluloid 0.12.2", "countdownlatch 1.0.0", "deep_merge 1.0.0", "ffi 1.1.5", "future-resource 1.0.0", "connection_pool 0.9.2", "girl_friday 0.10.0", "has-guarded-handlers 1.3.1", "little-plugger 1.1.3", "logging 1.8.0", "coderay 1.0.8", "method_source 0.8.1", "slop 3.3.3", "pry 0.9.10", "eventmachine 1.0.0", "nokogiri 1.5.5", "niceogiri 1.0.2", "blather 0.8.1", "nio4r 0.4.0", "celluloid-io 0.12.0", "ruby_ami 1.2.4", "json 1.7.5", "ruby_fs 1.0.1", "ruby_speech 1.0.0", "state_machine 1.1.2", "punchblock 1.5.1", "thor 0.16.0", "adhearsion 2.1.3", "adhearsion-activerecord 0.2.0", "adhearsion-asterisk 1.3.0", "mysql2 0.3.11"]
[2012-10-24 08:30:49] INFO Adhearsion::Initializer: Setting RAILS_ENV to "development"
[2012-10-24 08:30:49] INFO Adhearsion::PunchblockPlugin::Initializer: Starting connection to server
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: Starting up...
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: Starting up...
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] Asterisk Call Manager/1.1
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] Asterisk Call Manager/1.1
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Stream::Connected:0x90ae37c>
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Stream::Connected:0x9aa0394>
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [SEND]: Action: login
ActionID: 69043fa1-5247-4660-a378-3f74db1e51d0
Username: ahn
Secret: dtxyjcnm
Events: On
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [SEND] Action: login
ActionID: 7a33db32-c431-4e17-b7d3-cc3cbf0a4ff2
Username: ahn
Secret: dtxyjcnm
Events: On
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [SEND] Action: login
ActionID: 69043fa1-5247-4660-a378-3f74db1e51d0
Username: ahn
Secret: dtxyjcnm
Events: On
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 7a33db32-c431-4e17-b7d3-cc3cbf0a4ff2
Message: Authentication accepted
Event: FullyBooted
Privilege: system,all
Status: Fully Booted
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"7a33db32-c431-4e17-b7d3-cc3cbf0a4ff2", "Message"=>"Authentication accepted"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 69043fa1-5247-4660-a378-3f74db1e51d0
Message: Authentication accepted
Event: FullyBooted
Privilege: system,all
Status: Fully Booted
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"69043fa1-5247-4660-a378-3f74db1e51d0", "Message"=>"Authentication accepted"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="FullyBooted", headers={"Privilege"=>"system,all", "Status"=>"Fully Booted"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="FullyBooted", headers={"Privilege"=>"system,all", "Status"=>"Fully Booted"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] INFO Adhearsion::Console: Launching Adhearsion Console
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Response headers={"ActionID"=>"7a33db32-c431-4e17-b7d3-cc3cbf0a4ff2", "Message"=>"Authentication accepted"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"69043fa1-5247-4660-a378-3f74db1e51d0", "Message"=>"Authentication accepted"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [QUEUE]: Action: events
ActionID: 71ec00de-8ab3-4522-83ba-d341a4d90461
EventMask: Off
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="FullyBooted", headers={"Privilege"=>"system,all", "Status"=>"Fully Booted"}, text_body=nil, events=nil, action=nil>
AHN> [2012-10-24 08:30:49] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Event name="FullyBooted", headers={"Privilege"=>"system,all", "Status"=>"Fully Booted"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [SEND]: Action: events
ActionID: 71ec00de-8ab3-4522-83ba-d341a4d90461
EventMask: Off
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [SEND] Action: events
ActionID: 71ec00de-8ab3-4522-83ba-d341a4d90461
EventMask: Off
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 71ec00de-8ab3-4522-83ba-d341a4d90461
Events: Off
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [QUEUE]: Action: command
ActionID: be949990-c424-485b-8274-ad371eeb0aec
Command: dialplan add extension 1,1,AGI,agi:async into adhearsion-redirect
[2012-10-24 08:30:49] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"71ec00de-8ab3-4522-83ba-d341a4d90461", "Events"=>"Off"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [QUEUE]: Action: command
ActionID: ef803cb2-c625-4259-b9c5-8470b6cc2a2f
Command: dialplan show adhearsion-redirect
[2012-10-24 08:30:50] INFO Adhearsion::PunchblockPlugin::Initializer: Connected to Punchblock server
[2012-10-24 08:30:49] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"71ec00de-8ab3-4522-83ba-d341a4d90461", "Events"=>"Off"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:50] TRACE RubyAMI::Client: [SEND]: Action: command
ActionID: be949990-c424-485b-8274-ad371eeb0aec
Command: dialplan add extension 1,1,AGI,agi:async into adhearsion-redirect
[2012-10-24 08:30:50] INFO Adhearsion::Process: Transitioning from booting to running with 0 active calls due to booted event.
[2012-10-24 08:30:50] INFO Adhearsion::Initializer: Adhearsion v2.1.3 initialized in "development"!
[2012-10-24 08:30:50] DEBUG RubyAMI::Client: [SEND] Action: command
ActionID: be949990-c424-485b-8274-ad371eeb0aec
Command: dialplan add extension 1,1,AGI,agi:async into adhearsion-redirect
[2012-10-24 08:30:50] DEBUG RubyAMI::Client: [RECV] Response: Follows
Privilege: Command
ActionID: be949990-c424-485b-8274-ad371eeb0aec
[2012-10-24 08:30:50] DEBUG RubyAMI::Client: [RECV] Extension '1,1,AGI(agi:async)' added into 'adhearsion-redirect' context
--END COMMAND--
[2012-10-24 08:30:50] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"Privilege"=>"Command", "ActionID"=>"be949990-c424-485b-8274-ad371eeb0aec", "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-24 08:30:50] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"Privilege"=>"Command", "ActionID"=>"be949990-c424-485b-8274-ad371eeb0aec", "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-24 08:30:50] TRACE RubyAMI::Client: [SEND]: Action: command
ActionID: ef803cb2-c625-4259-b9c5-8470b6cc2a2f
Command: dialplan show adhearsion-redirect
[2012-10-24 08:30:50] DEBUG RubyAMI::Client: [SEND] Action: command
ActionID: ef803cb2-c625-4259-b9c5-8470b6cc2a2f
Command: dialplan show adhearsion-redirect
[2012-10-24 08:30:50] DEBUG RubyAMI::Client: [RECV] Response: Follows
Privilege: Command
ActionID: ef803cb2-c625-4259-b9c5-8470b6cc2a2f
[2012-10-24 08:30:50] 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-24 08:30:50] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"Privilege"=>"Command", "ActionID"=>"ef803cb2-c625-4259-b9c5-8470b6cc2a2f"}, 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-24 08:30:50] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"Privilege"=>"Command", "ActionID"=>"ef803cb2-c625-4259-b9c5-8470b6cc2a2f"}, 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-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: Newchannel
Privilege: call,all
Channel: SIP/rivas-0000007b
ChannelState: 0
ChannelStateDesc: Down
CallerIDNum: 0577602602
CallerIDName: 0577602602
AccountCode:
Exten: 7867399
Context: from-ahn
Uniqueid: 1351056537.123
Event: VarSet
Privilege: dialplan,all
Channel: SIP/rivas-0000007b
Variable: SIPURI
Value: sip:0577602602@192.168.2.112:5060
Uniqueid: 1351056537.123
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newchannel", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "ChannelState"=>"0", "ChannelStateDesc"=>"Down", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "AccountCode"=>"", "Exten"=>"7867399", "Context"=>"from-ahn", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"SIPURI", "Value"=>"sip:0577602602@192.168.2.112:5060", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newchannel", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "ChannelState"=>"0", "ChannelStateDesc"=>"Down", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "AccountCode"=>"", "Exten"=>"7867399", "Context"=>"from-ahn", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
Channel: SIP/rivas-0000007b
Variable: SIPDOMAIN
Value: 192.168.2.55
Uniqueid: 1351056537.123
Event: VarSet
Privilege: dialplan,all
Channel: SIP/rivas-0000007b
Variable: SIPCALLID
Value: 7dcbf57f222a328b7ab441d744cdf0d8@192.168.2.112:5060
Uniqueid: 1351056537.123
Event: Newstate
Privilege: call,all
Channel: SIP/rivas-0000007b
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 0577602602
CallerIDName: 0577602602
ConnectedLineNum:
ConnectedLineName:
Uniqueid: 1351056537.123
Event: Newexten
Privilege: dialplan,all
Channel: SIP/rivas-0000007b
Context: from-ahn
Extension: 7867399
Priority: 1
Application: AGI
AppData: agi:async
Uniqueid: 1351056537.123
Event: AsyncAGI
Privilege: agi,all
SubEvent: Start
Channel: SIP/rivas-0000007b
Env: agi_request%3A%20async%0Aagi_channel%3A%20SIP%2Frivas-0000007b%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056537.123%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%200577602602%0Aagi_calleridname%3A%200577602602%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%207867399%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-ahn%0Aagi_extension%3A%207867399%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281852560%0A%0A
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"SIPDOMAIN", "Value"=>"192.168.2.55", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"SIPURI", "Value"=>"sip:0577602602@192.168.2.112:5060", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"SIPCALLID", "Value"=>"7dcbf57f222a328b7ab441d744cdf0d8@192.168.2.112:5060", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"SIPDOMAIN", "Value"=>"192.168.2.55", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "ChannelState"=>"4", "ChannelStateDesc"=>"Ring", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "ConnectedLineNum"=>"", "ConnectedLineName"=>"", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"SIPCALLID", "Value"=>"7dcbf57f222a328b7ab441d744cdf0d8@192.168.2.112:5060", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "ChannelState"=>"4", "ChannelStateDesc"=>"Ring", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "ConnectedLineNum"=>"", "ConnectedLineName"=>"", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newexten", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Context"=>"from-ahn", "Extension"=>"7867399", "Priority"=>"1", "Application"=>"AGI", "AppData"=>"agi:async", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2Frivas-0000007b%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056537.123%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%200577602602%0Aagi_calleridname%3A%200577602602%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%207867399%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-ahn%0Aagi_extension%3A%207867399%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281852560%0A%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newexten", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Context"=>"from-ahn", "Extension"=>"7867399", "Priority"=>"1", "Application"=>"AGI", "AppData"=>"agi:async", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2Frivas-0000007b%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056537.123%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%200577602602%0Aagi_calleridname%3A%200577602602%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%207867399%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-ahn%0Aagi_extension%3A%207867399%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281852560%0A%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: : Receiving message: #<Punchblock::Event::Offer to="7867399", from="0577602602 <SIP/0577602602>", headers={:x_agi_request=>"async", :x_agi_channel=>"SIP/rivas-0000007b", :x_agi_language=>"ru", :x_agi_type=>"SIP", :x_agi_uniqueid=>"1351056537.123", :x_agi_version=>"1.8.13.1~dfsg-1", :x_agi_callerid=>"0577602602", :x_agi_calleridname=>"0577602602", :x_agi_callingpres=>"0", :x_agi_callingani2=>"0", :x_agi_callington=>"0", :x_agi_callingtns=>"0", :x_agi_dnid=>"7867399", :x_agi_rdnis=>"unknown", :x_agi_context=>"from-ahn", :x_agi_extension=>"7867399", :x_agi_priority=>"1", :x_agi_enhanced=>"0.0", :x_agi_accountcode=>"", :x_agi_threadid=>"-1281852560"}, call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2"
[2012-10-24 08:30:52] INFO Adhearsion::Router: Call 87be32d4-23d7-46fc-ab4a-32fb241bdbf2 selected route "default" (TestOutCall)
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Executing command #<Punchblock::Command::Accept headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [QUEUE]: Action: agi
ActionID: fae2fe21-c219-435a-9123-80b26503a0fd
Channel: SIP/rivas-0000007b
Command: EXEC RINGING
CommandID: 52fba2d6-243d-4686-98a3-f0946acd406f
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [SEND]: Action: agi
ActionID: fae2fe21-c219-435a-9123-80b26503a0fd
Channel: SIP/rivas-0000007b
Command: EXEC RINGING
CommandID: 52fba2d6-243d-4686-98a3-f0946acd406f
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: fae2fe21-c219-435a-9123-80b26503a0fd
Channel: SIP/rivas-0000007b
Command: EXEC RINGING
CommandID: 52fba2d6-243d-4686-98a3-f0946acd406f
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: fae2fe21-c219-435a-9123-80b26503a0fd
Message: Added AGI command to queue
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"fae2fe21-c219-435a-9123-80b26503a0fd", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"fae2fe21-c219-435a-9123-80b26503a0fd", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SubEvent: Start
Channel: SIP/rivas-0000007b
CommandId: 1988225871
Command: EXEC RINGING
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"1988225871", "Command"=>"EXEC RINGING"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SubEvent: End
Channel: SIP/rivas-0000007b
CommandId: 1988225871
Command: EXEC RINGING
ResultCode: 200
Result: Success
Event: AsyncAGI
Privilege: agi,all
SubEvent: Exec
Channel: SIP/rivas-0000007b
CommandID: 52fba2d6-243d-4686-98a3-f0946acd406f
Result: 200%20result%3D0%0A
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"1988225871", "Command"=>"EXEC RINGING"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"1988225871", "Command"=>"EXEC RINGING", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"1988225871", "Command"=>"EXEC RINGING", "ResultCode"=>"200", "Result"=>"Success"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"SIP/rivas-0000007b", "CommandID"=>"52fba2d6-243d-4686-98a3-f0946acd406f", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"SIP/rivas-0000007b", "CommandID"=>"52fba2d6-243d-4686-98a3-f0946acd406f", "Result"=>"200%20result%3D0%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"SIP/rivas-0000007b", :commandid=>"1988225871", :command=>"EXEC RINGING"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Exec", :channel=>"SIP/rivas-0000007b", :commandid=>"52fba2d6-243d-4686-98a3-f0946acd406f", :result=>"200%20result%3D0%0A"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"SIP/rivas-0000007b", :commandid=>"1988225871", :command=>"EXEC RINGING", :resultcode=>"200", :result=>"Success"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] INFO Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Executing controller #<TestOutCall call=87be32d4-23d7-46fc-ab4a-32fb241bdbf2, metadata={}>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Executing command #<Punchblock::Command::Reject reason=:busy, headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [QUEUE]: Action: agi
ActionID: 25393974-c50a-4d59-acf9-9a3f34e5f276
Channel: SIP/rivas-0000007b
Command: EXEC Busy
CommandID: 0e4cbe46-45d8-4710-9a9a-5e10d1219438
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [SEND]: Action: agi
ActionID: 25393974-c50a-4d59-acf9-9a3f34e5f276
Channel: SIP/rivas-0000007b
Command: EXEC Busy
CommandID: 0e4cbe46-45d8-4710-9a9a-5e10d1219438
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 25393974-c50a-4d59-acf9-9a3f34e5f276
Channel: SIP/rivas-0000007b
Command: EXEC Busy
CommandID: 0e4cbe46-45d8-4710-9a9a-5e10d1219438
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 25393974-c50a-4d59-acf9-9a3f34e5f276
Message: Added AGI command to queue
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"25393974-c50a-4d59-acf9-9a3f34e5f276", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"25393974-c50a-4d59-acf9-9a3f34e5f276", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SubEvent: Start
Channel: SIP/rivas-0000007b
CommandId: 199370866
Command: EXEC Busy
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"199370866", "Command"=>"EXEC Busy"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: Newstate
Privilege: call,all
Channel: SIP/rivas-0000007b
ChannelState: 7
ChannelStateDesc: Busy
CallerIDNum: 0577602602
CallerIDName: 0577602602
ConnectedLineNum:
ConnectedLineName:
Uniqueid: 1351056537.123
Event: AGIExec
Privilege: agi,all
SubEvent: End
Channel: SIP/rivas-0000007b
CommandId: 199370866
Command: EXEC Busy
ResultCode: 200
Result: Unknown Result
Event: AsyncAGI
Privilege: agi,all
SubEvent: Exec
Channel: SIP/rivas-0000007b
CommandID: 0e4cbe46-45d8-4710-9a9a-5e10d1219438
Result: 200%20result%3D-1%0A
Event: AsyncAGI
Privilege: agi,all
SubEvent: End
Channel: SIP/rivas-0000007b
Event: VarSet
Privilege: dialplan,all
Channel: SIP/rivas-0000007b
Variable: AGISTATUS
Value: HANGUP
Uniqueid: 1351056537.123
Event: Newexten
Privilege: dialplan,all
Channel: SIP/rivas-0000007b
Context: from-ahn
Extension: h
Priority: 1
Application: AGI
AppData: agi:async
Uniqueid: 1351056537.123
Event: AsyncAGI
Privilege: agi,all
SubEvent: Start
Channel: SIP/rivas-0000007b
Env: agi_request%3A%20async%0Aagi_channel%3A%20SIP%2Frivas-0000007b%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056537.123%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%200577602602%0Aagi_calleridname%3A%200577602602%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%207867399%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-ahn%0Aagi_extension%3A%20h%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281852560%0A%0A
Event: AsyncAGI
Privilege: agi,all
SubEvent: End
Channel: SIP/rivas-0000007b
Event: VarSet
Privilege: dialplan,all
Channel: SIP/rivas-0000007b
Variable: AGISTATUS
Value: HANGUP
Uniqueid: 1351056537.123
Event: Hangup
Privilege: call,all
Channel: SIP/rivas-0000007b
Uniqueid: 1351056537.123
CallerIDNum: 0577602602
CallerIDName: 0577602602
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Cause: 0
Cause-txt: Unknown
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"199370866", "Command"=>"EXEC Busy"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "ChannelState"=>"7", "ChannelStateDesc"=>"Busy", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "ConnectedLineNum"=>"", "ConnectedLineName"=>"", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "ChannelState"=>"7", "ChannelStateDesc"=>"Busy", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "ConnectedLineNum"=>"", "ConnectedLineName"=>"", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"199370866", "Command"=>"EXEC Busy", "ResultCode"=>"200", "Result"=>"Unknown Result"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b", "CommandId"=>"199370866", "Command"=>"EXEC Busy", "ResultCode"=>"200", "Result"=>"Unknown Result"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"SIP/rivas-0000007b", "CommandID"=>"0e4cbe46-45d8-4710-9a9a-5e10d1219438", "Result"=>"200%20result%3D-1%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"SIP/rivas-0000007b", "CommandID"=>"0e4cbe46-45d8-4710-9a9a-5e10d1219438", "Result"=>"200%20result%3D-1%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newexten", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Context"=>"from-ahn", "Extension"=>"h", "Priority"=>"1", "Application"=>"AGI", "AppData"=>"agi:async", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2Frivas-0000007b%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056537.123%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%200577602602%0Aagi_calleridname%3A%200577602602%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%207867399%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-ahn%0Aagi_extension%3A%20h%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281852560%0A%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newexten", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Context"=>"from-ahn", "Extension"=>"h", "Priority"=>"1", "Application"=>"AGI", "AppData"=>"agi:async", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/rivas-0000007b"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Offer to="7867399", from="0577602602 <SIP/0577602602>", headers={:x_agi_request=>"async", :x_agi_channel=>"SIP/rivas-0000007b", :x_agi_language=>"ru", :x_agi_type=>"SIP", :x_agi_uniqueid=>"1351056537.123", :x_agi_version=>"1.8.13.1~dfsg-1", :x_agi_callerid=>"0577602602", :x_agi_calleridname=>"0577602602", :x_agi_callingpres=>"0", :x_agi_callingani2=>"0", :x_agi_callington=>"0", :x_agi_callingtns=>"0", :x_agi_dnid=>"7867399", :x_agi_rdnis=>"unknown", :x_agi_context=>"from-ahn", :x_agi_extension=>"7867399", :x_agi_priority=>"1", :x_agi_enhanced=>"0.0", :x_agi_accountcode=>"", :x_agi_threadid=>"-1281852560"}, call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2"
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/rivas-0000007b", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2Frivas-0000007b%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056537.123%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%200577602602%0Aagi_calleridname%3A%200577602602%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%207867399%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-ahn%0Aagi_extension%3A%20h%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281852560%0A%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"SIP/rivas-0000007b", :commandid=>"199370866", :command=>"EXEC Busy"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Newstate", attributes_hash={:privilege=>"call,all", :channel=>"SIP/rivas-0000007b", :channelstate=>"7", :channelstatedesc=>"Busy", :calleridnum=>"0577602602", :calleridname=>"0577602602", :connectedlinenum=>"", :connectedlinename=>"", :uniqueid=>"1351056537.123"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "Uniqueid"=>"1351056537.123", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Cause"=>"0", "Cause-txt"=>"Unknown"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"SIP/rivas-0000007b", "Uniqueid"=>"1351056537.123", "CallerIDNum"=>"0577602602", "CallerIDName"=>"0577602602", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Cause"=>"0", "Cause-txt"=>"Unknown"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"SIP/rivas-0000007b", :commandid=>"199370866", :command=>"EXEC Busy", :resultcode=>"200", :result=>"Unknown Result"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/rivas-0000007b", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1351056537.123"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Exec", :channel=>"SIP/rivas-0000007b", :commandid=>"0e4cbe46-45d8-4710-9a9a-5e10d1219438", :result=>"200%20result%3D-1%0A"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"SIP/rivas-0000007b"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/rivas-0000007b", :variable=>"AGISTATUS", :value=>"HANGUP", :uniqueid=>"1351056537.123"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial to="SIP/301", from="30 <30>", join=nil, headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Newexten", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/rivas-0000007b", :context=>"from-ahn", :extension=>"h", :priority=>"1", :application=>"AGI", :appdata=>"agi:async", :uniqueid=>"1351056537.123"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"SIP/rivas-0000007b", :env=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2Frivas-0000007b%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056537.123%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%200577602602%0Aagi_calleridname%3A%200577602602%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%207867399%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20from-ahn%0Aagi_extension%3A%20h%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281852560%0A%0A"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"SIP/rivas-0000007b"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Hangup", attributes_hash={:privilege=>"call,all", :channel=>"SIP/rivas-0000007b", :uniqueid=>"1351056537.123", :calleridnum=>"0577602602", :calleridname=>"0577602602", :connectedlinenum=>"<unknown>", :connectedlinename=>"<unknown>", :cause=>"0", :cause_txt=>"Unknown"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/rivas-0000007b", :variable=>"AGISTATUS", :value=>"HANGUP", :uniqueid=>"1351056537.123"}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Receiving message: #<Punchblock::Event::End reason=:hangup, headers_hash={}, target_call_id="87be32d4-23d7-46fc-ab4a-32fb241bdbf2", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:52] INFO Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Call ended
[2012-10-24 08:30:52] DEBUG Adhearsion::Call: 87be32d4-23d7-46fc-ab4a-32fb241bdbf2: Finished executing controller #<TestOutCall call=87be32d4-23d7-46fc-ab4a-32fb241bdbf2, metadata={}>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [QUEUE]: Action: originate
ActionID: afbc79f1-5929-47ab-acbc-b9ca8a27c57a
Async: true
Application: AGI
Data: agi:async
Channel: SIP/301
Callerid: 30 <30>
Variable: punchblock_call_id=6f178ab2-19cd-4766-87d3-69a5bde9dc8e
Timeout: 100000
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [SEND]: Action: originate
ActionID: afbc79f1-5929-47ab-acbc-b9ca8a27c57a
Async: true
Application: AGI
Data: agi:async
Channel: SIP/301
Callerid: 30 <30>
Variable: punchblock_call_id=6f178ab2-19cd-4766-87d3-69a5bde9dc8e
Timeout: 100000
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [SEND] Action: originate
ActionID: afbc79f1-5929-47ab-acbc-b9ca8a27c57a
Async: true
Application: AGI
Data: agi:async
Channel: SIP/301
Callerid: 30 <30>
Variable: punchblock_call_id=6f178ab2-19cd-4766-87d3-69a5bde9dc8e
Timeout: 100000
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: afbc79f1-5929-47ab-acbc-b9ca8a27c57a
Message: Originate successfully queued
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"afbc79f1-5929-47ab-acbc-b9ca8a27c57a", "Message"=>"Originate successfully queued"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: Newchannel
Privilege: call,all
Channel: SIP/301-0000007c
ChannelState: 0
ChannelStateDesc: Down
CallerIDNum:
CallerIDName:
AccountCode:
Exten:
Context: phones
Uniqueid: 1351056538.124
Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
Variable: SIPCALLID
Value: 57ff020f5ae99cd05cf91b7613338663@192.168.2.55:5060
Uniqueid: 1351056538.124
Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
Variable: punchblock_call_id
Value: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e
Uniqueid: 1351056538.124
Event: NewAccountCode
Privilege: call,all
Channel: SIP/301-0000007c
Uniqueid: 1351056538.124
AccountCode:
OldAccountCode:
Event: NewCallerid
Privilege: call,all
Channel: SIP/301-0000007c
CallerIDNum: 30
CallerIDName: 30
Uniqueid: 1351056538.124
CID-CallingPres: 0 (Presentation Allowed, Not Screened)
Event: QueueMemberStatus
Privilege: agent,all
Queue: priority
Location: Sip/301
MemberName: admin
Membership: realtime
Penalty: 9
CallsTaken: 0
LastCall: 0
Status: 6
Paused: 1
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newchannel", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "ChannelState"=>"0", "ChannelStateDesc"=>"Down", "CallerIDNum"=>"", "CallerIDName"=>"", "AccountCode"=>"", "Exten"=>"", "Context"=>"phones", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"afbc79f1-5929-47ab-acbc-b9ca8a27c57a", "Message"=>"Originate successfully queued"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"SIPCALLID", "Value"=>"57ff020f5ae99cd05cf91b7613338663@192.168.2.55:5060", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newchannel", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "ChannelState"=>"0", "ChannelStateDesc"=>"Down", "CallerIDNum"=>"", "CallerIDName"=>"", "AccountCode"=>"", "Exten"=>"", "Context"=>"phones", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"punchblock_call_id", "Value"=>"6f178ab2-19cd-4766-87d3-69a5bde9dc8e", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Uniqueid"=>"1351056538.124", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"SIPCALLID", "Value"=>"57ff020f5ae99cd05cf91b7613338663@192.168.2.55:5060", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewCallerid", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "CallerIDNum"=>"30", "CallerIDName"=>"30", "Uniqueid"=>"1351056538.124", "CID-CallingPres"=>"0 (Presentation Allowed, Not Screened)"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"punchblock_call_id", "Value"=>"6f178ab2-19cd-4766-87d3-69a5bde9dc8e", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"6", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Uniqueid"=>"1351056538.124", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="NewCallerid", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "CallerIDNum"=>"30", "CallerIDName"=>"30", "Uniqueid"=>"1351056538.124", "CID-CallingPres"=>"0 (Presentation Allowed, Not Screened)"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] Event: Newstate
Privilege: call,all
Channel: SIP/301-0000007c
ChannelState: 5
ChannelStateDesc: Ringing
CallerIDNum: 30
CallerIDName: 30
ConnectedLineNum: 30
ConnectedLineName: 30
Uniqueid: 1351056538.124
Event: QueueMemberStatus
Privilege: agent,all
Queue: priority
Location: Sip/301
MemberName: admin
Membership: realtime
Penalty: 9
CallsTaken: 0
LastCall: 0
Status: 6
Paused: 1
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"6", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "ChannelState"=>"5", "ChannelStateDesc"=>"Ringing", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"6", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "ChannelState"=>"5", "ChannelStateDesc"=>"Ringing", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"6", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:52] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :variable=>"punchblock_call_id", :value=>"6f178ab2-19cd-4766-87d3-69a5bde9dc8e", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:55] DEBUG RubyAMI::Client: [RECV] Event: QueueMemberStatus
Privilege: agent,all
Queue: priority
Location: Sip/301
MemberName: admin
Membership: realtime
Penalty: 9
CallsTaken: 0
LastCall: 0
Status: 3
Paused: 1
[2012-10-24 08:30:55] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"3", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:55] DEBUG RubyAMI::Client: [RECV] Event: Newstate
Privilege: call,all
Channel: SIP/301-0000007c
ChannelState: 6
ChannelStateDesc: Up
CallerIDNum: 30
CallerIDName: 30
ConnectedLineNum: 30
ConnectedLineName: 30
Uniqueid: 1351056538.124
Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
Variable: punchblock_call_id
Value: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e
Uniqueid: 1351056538.124
Event: NewAccountCode
Privilege: call,all
Channel: SIP/301-0000007c
Uniqueid: 1351056538.124
AccountCode:
OldAccountCode:
Event: QueueMemberStatus
Privilege: agent,all
Queue: priority
Location: Sip/301
MemberName: admin
Membership: realtime
Penalty: 9
CallsTaken: 0
LastCall: 0
Status: 3
Paused: 1
Event: OriginateResponse
Privilege: call,all
ActionID: afbc79f1-5929-47ab-acbc-b9ca8a27c57a
Response: Success
Channel: SIP/301-0000007c
Context:
Exten:
Reason: 4
Uniqueid: 1351056538.124
CallerIDNum: 30
CallerIDName: 30
Event: AsyncAGI
Privilege: agi,all
SubEvent: Start
Channel: SIP/301-0000007c
Env: agi_request%3A%20async%0Aagi_channel%3A%20SIP%2F301-0000007c%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056538.124%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%2030%0Aagi_calleridname%3A%2030%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%20phones%0Aagi_extension%3A%20%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281606800%0A%0A
[2012-10-24 08:30:55] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"3", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:55] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:55] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"punchblock_call_id", "Value"=>"6f178ab2-19cd-4766-87d3-69a5bde9dc8e", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:55] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Newstate", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"punchblock_call_id", "Value"=>"6f178ab2-19cd-4766-87d3-69a5bde9dc8e", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Uniqueid"=>"1351056538.124", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="NewAccountCode", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Uniqueid"=>"1351056538.124", "AccountCode"=>"", "OldAccountCode"=>""}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"3", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Newstate", attributes_hash={:privilege=>"call,all", :channel=>"SIP/301-0000007c", :channelstate=>"6", :channelstatedesc=>"Up", :calleridnum=>"30", :calleridname=>"30", :connectedlinenum=>"30", :connectedlinename=>"30", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"3", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="OriginateResponse", headers={"Privilege"=>"call,all", "ActionID"=>"afbc79f1-5929-47ab-acbc-b9ca8a27c57a", "Response"=>"Success", "Channel"=>"SIP/301-0000007c", "Context"=>"", "Exten"=>"", "Reason"=>"4", "Uniqueid"=>"1351056538.124", "CallerIDNum"=>"30", "CallerIDName"=>"30"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Answered headers_hash={}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] INFO Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Executing controller #<Iamback call=6f178ab2-19cd-4766-87d3-69a5bde9dc8e, metadata={}>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/301-0000007c", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2F301-0000007c%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056538.124%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%2030%0Aagi_calleridname%3A%2030%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%20phones%0Aagi_extension%3A%20%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281606800%0A%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/301-0000007c", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2F301-0000007c%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056538.124%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%2030%0Aagi_calleridname%3A%2030%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%20phones%0Aagi_extension%3A%20%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281606800%0A%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="OriginateResponse", headers={"Privilege"=>"call,all", "ActionID"=>"afbc79f1-5929-47ab-acbc-b9ca8a27c57a", "Response"=>"Success", "Channel"=>"SIP/301-0000007c", "Context"=>"", "Exten"=>"", "Reason"=>"4", "Uniqueid"=>"1351056538.124", "CallerIDNum"=>"30", "CallerIDName"=>"30"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="NewAccountCode", attributes_hash={:privilege=>"call,all", :channel=>"SIP/301-0000007c", :uniqueid=>"1351056538.124", :accountcode=>"", :oldaccountcode=>""}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Executing command #<Punchblock::Component::Asterisk::AGI::Command name="EXEC queue", params_array=["priority", "", "", "", "30", ""], target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [QUEUE]: Action: agi
ActionID: 365c94a7-df10-4ef7-8e72-d0fa73134210
Channel: SIP/301-0000007c
Command: EXEC queue "priority" "" "" "" "30" ""
CommandID: ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"SIP/301-0000007c", :env=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2F301-0000007c%0Aagi_language%3A%20ru%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201351056538.124%0Aagi_version%3A%201.8.13.1~dfsg-1%0Aagi_callerid%3A%2030%0Aagi_calleridname%3A%2030%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%20phones%0Aagi_extension%3A%20%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20-1281606800%0A%0A"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="OriginateResponse", attributes_hash={:privilege=>"call,all", :actionid=>"afbc79f1-5929-47ab-acbc-b9ca8a27c57a", :response=>"Success", :channel=>"SIP/301-0000007c", :context=>"", :exten=>"", :reason=>"4", :uniqueid=>"1351056538.124", :calleridnum=>"30", :calleridname=>"30"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [SEND]: Action: agi
ActionID: 365c94a7-df10-4ef7-8e72-d0fa73134210
Channel: SIP/301-0000007c
Command: EXEC queue "priority" "" "" "" "30" ""
CommandID: ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 365c94a7-df10-4ef7-8e72-d0fa73134210
Channel: SIP/301-0000007c
Command: EXEC queue "priority" "" "" "" "30" ""
CommandID: ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :variable=>"punchblock_call_id", :value=>"6f178ab2-19cd-4766-87d3-69a5bde9dc8e", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] Response: Success
ActionID: 365c94a7-df10-4ef7-8e72-d0fa73134210
Message: Added AGI command to queue
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Response headers={"ActionID"=>"365c94a7-df10-4ef7-8e72-d0fa73134210", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Response headers={"ActionID"=>"365c94a7-df10-4ef7-8e72-d0fa73134210", "Message"=>"Added AGI command to queue"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] Event: AGIExec
Privilege: agi,all
SubEvent: Start
Channel: SIP/301-0000007c
CommandId: 763208179
Command: EXEC queue "priority" "" "" "" "30" ""
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/301-0000007c", "CommandId"=>"763208179", "Command"=>"EXEC queue \"priority\" \"\" \"\" \"\" \"30\" \"\""}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] Event: Join
Privilege: call,all
Channel: SIP/301-0000007c
CallerIDNum: 30
CallerIDName: 30
ConnectedLineNum: 30
ConnectedLineName: 30
Queue: priority
Position: 1
Count: 1
Uniqueid: 1351056538.124
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"Start", "Channel"=>"SIP/301-0000007c", "CommandId"=>"763208179", "Command"=>"EXEC queue \"priority\" \"\" \"\" \"\" \"30\" \"\""}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Join", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Queue"=>"priority", "Position"=>"1", "Count"=>"1", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Join", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Queue"=>"priority", "Position"=>"1", "Count"=>"1", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] Event: MusicOnHold
Privilege: call,all
State: Start
Channel: SIP/301-0000007c
UniqueID: 1351056538.124
Class: default
[2012-10-24 08:30:56] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="MusicOnHold", headers={"Privilege"=>"call,all", "State"=>"Start", "Channel"=>"SIP/301-0000007c", "UniqueID"=>"1351056538.124", "Class"=>"default"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"Start", :channel=>"SIP/301-0000007c", :commandid=>"763208179", :command=>"EXEC queue \"priority\" \"\" \"\" \"\" \"30\" \"\""}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="MusicOnHold", headers={"Privilege"=>"call,all", "State"=>"Start", "Channel"=>"SIP/301-0000007c", "UniqueID"=>"1351056538.124", "Class"=>"default"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Join", attributes_hash={:privilege=>"call,all", :channel=>"SIP/301-0000007c", :calleridnum=>"30", :calleridname=>"30", :connectedlinenum=>"30", :connectedlinename=>"30", :queue=>"priority", :position=>"1", :count=>"1", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:56] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="MusicOnHold", attributes_hash={:privilege=>"call,all", :state=>"Start", :channel=>"SIP/301-0000007c", :uniqueid=>"1351056538.124", :class=>"default"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
Variable: RTPAUDIOQOS
Value: ssrc=420165818;themssrc=83700051;lp=0;rxjitter=0.000414;rxcount=71;txjitter=0.000000;txcount=67;rlp=0;rtt=0.000000
Uniqueid: 1351056538.124
Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
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: 1351056538.124
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"RTPAUDIOQOS", "Value"=>"ssrc=420165818;themssrc=83700051;lp=0;rxjitter=0.000414;rxcount=71;txjitter=0.000000;txcount=67;rlp=0;rtt=0.000000", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "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"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"RTPAUDIOQOS", "Value"=>"ssrc=420165818;themssrc=83700051;lp=0;rxjitter=0.000414;rxcount=71;txjitter=0.000000;txcount=67;rlp=0;rtt=0.000000", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "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"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
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: 1351056538.124
Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
Variable: RTPAUDIOQOSRTT
Value: minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;
Uniqueid: 1351056538.124
Event: QueueCallerAbandon
Privilege: agent,all
Queue: priority
Uniqueid: 1351056538.124
Position: 1
OriginalPosition: 1
HoldTime: 1
Event: Leave
Privilege: call,all
Channel: SIP/301-0000007c
Queue: priority
Count: 0
Position: 1
Uniqueid: 1351056538.124
Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
Variable: QUEUEPOSITION
Value: 1
Uniqueid: 1351056538.124
Event: AGIExec
Privilege: agi,all
SubEvent: End
Channel: SIP/301-0000007c
CommandId: 763208179
Command: EXEC queue "priority" "" "" "" "30" ""
ResultCode: 200
Result: Unknown Result
Event: AsyncAGI
Privilege: agi,all
SubEvent: Exec
Channel: SIP/301-0000007c
CommandID: ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b
Result: 200%20result%3D-1%0A
Event: AsyncAGI
Privilege: agi,all
SubEvent: End
Channel: SIP/301-0000007c
Event: VarSet
Privilege: dialplan,all
Channel: SIP/301-0000007c
Variable: AGISTATUS
Value: HANGUP
Uniqueid: 1351056538.124
Event: Hangup
Privilege: call,all
Channel: SIP/301-0000007c
Uniqueid: 1351056538.124
CallerIDNum: 30
CallerIDName: 30
ConnectedLineNum: 30
ConnectedLineName: 30
Cause: 16
Cause-txt: Normal Clearing
Event: QueueMemberStatus
Privilege: agent,all
Queue: priority
Location: Sip/301
MemberName: admin
Membership: realtime
Penalty: 9
CallsTaken: 0
LastCall: 0
Status: 1
Paused: 1
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "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"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "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"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"RTPAUDIOQOSRTT", "Value"=>"minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :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=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="QueueCallerAbandon", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Uniqueid"=>"1351056538.124", "Position"=>"1", "OriginalPosition"=>"1", "HoldTime"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Leave", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Queue"=>"priority", "Count"=>"0", "Position"=>"1", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"RTPAUDIOQOSRTT", "Value"=>"minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="QueueCallerAbandon", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Uniqueid"=>"1351056538.124", "Position"=>"1", "OriginalPosition"=>"1", "HoldTime"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"QUEUEPOSITION", "Value"=>"1", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :variable=>"RTPAUDIOQOS", :value=>"ssrc=420165818;themssrc=83700051;lp=0;rxjitter=0.000414;rxcount=71;txjitter=0.000000;txcount=67;rlp=0;rtt=0.000000", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/301-0000007c", "CommandId"=>"763208179", "Command"=>"EXEC queue \"priority\" \"\" \"\" \"\" \"30\" \"\"", "ResultCode"=>"200", "Result"=>"Unknown Result"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"SIP/301-0000007c", "CommandID"=>"ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b", "Result"=>"200%20result%3D-1%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Leave", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Queue"=>"priority", "Count"=>"0", "Position"=>"1", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"QUEUEPOSITION", "Value"=>"1", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/301-0000007c"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :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=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AGIExec", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/301-0000007c", "CommandId"=>"763208179", "Command"=>"EXEC queue \"priority\" \"\" \"\" \"\" \"30\" \"\"", "ResultCode"=>"200", "Result"=>"Unknown Result"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Uniqueid"=>"1351056538.124", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Cause"=>"16", "Cause-txt"=>"Normal Clearing"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"Exec", "Channel"=>"SIP/301-0000007c", "CommandID"=>"ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b", "Result"=>"200%20result%3D-1%0A"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="AsyncAGI", headers={"Privilege"=>"agi,all", "SubEvent"=>"End", "Channel"=>"SIP/301-0000007c"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"1", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="VarSet", headers={"Privilege"=>"dialplan,all", "Channel"=>"SIP/301-0000007c", "Variable"=>"AGISTATUS", "Value"=>"HANGUP", "Uniqueid"=>"1351056538.124"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="Hangup", headers={"Privilege"=>"call,all", "Channel"=>"SIP/301-0000007c", "Uniqueid"=>"1351056538.124", "CallerIDNum"=>"30", "CallerIDName"=>"30", "ConnectedLineNum"=>"30", "ConnectedLineName"=>"30", "Cause"=>"16", "Cause-txt"=>"Normal Clearing"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"1", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :variable=>"RTPAUDIOQOSRTT", :value=>"minrtt=0.000000;maxrtt=0.000000;avgrtt=0.000000;stdevrtt=0.000000;", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Leave", attributes_hash={:privilege=>"call,all", :channel=>"SIP/301-0000007c", :queue=>"priority", :count=>"0", :position=>"1", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :variable=>"QUEUEPOSITION", :value=>"1", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AGIExec", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"SIP/301-0000007c", :commandid=>"763208179", :command=>"EXEC queue \"priority\" \"\" \"\" \"\" \"30\" \"\"", :resultcode=>"200", :result=>"Unknown Result"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"End", :channel=>"SIP/301-0000007c"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="AsyncAGI", attributes_hash={:privilege=>"agi,all", :subevent=>"Exec", :channel=>"SIP/301-0000007c", :commandid=>"ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b", :result=>"200%20result%3D-1%0A"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="VarSet", attributes_hash={:privilege=>"dialplan,all", :channel=>"SIP/301-0000007c", :variable=>"AGISTATUS", :value=>"HANGUP", :uniqueid=>"1351056538.124"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Asterisk::AMI::Event name="Hangup", attributes_hash={:privilege=>"call,all", :channel=>"SIP/301-0000007c", :uniqueid=>"1351056538.124", :calleridnum=>"30", :calleridname=>"30", :connectedlinenum=>"30", :connectedlinename=>"30", :cause=>"16", :cause_txt=>"Normal Clearing"}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Executing command #<Punchblock::Component::Asterisk::AGI::Command name="GET VARIABLE", params_array=["QUEUESTATUS"], target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [QUEUE]: Action: agi
ActionID: 59bce0be-32f6-4b4b-b1ab-104eb083986c
Channel: SIP/301-0000007c
Command: GET VARIABLE "QUEUESTATUS"
CommandID: 18248b4c-faa2-403a-9146-583db768ab01
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [SEND]: Action: agi
ActionID: 59bce0be-32f6-4b4b-b1ab-104eb083986c
Channel: SIP/301-0000007c
Command: GET VARIABLE "QUEUESTATUS"
CommandID: 18248b4c-faa2-403a-9146-583db768ab01
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::End reason=:hangup, headers_hash={}, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id=nil, target_mixer_name=nil>
[2012-10-24 08:30:57] INFO Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Call ended
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [SEND] Action: agi
ActionID: 59bce0be-32f6-4b4b-b1ab-104eb083986c
Channel: SIP/301-0000007c
Command: GET VARIABLE "QUEUESTATUS"
CommandID: 18248b4c-faa2-403a-9146-583db768ab01
[2012-10-24 08:30:57] DEBUG Adhearsion::OutboundCall: 6f178ab2-19cd-4766-87d3-69a5bde9dc8e: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success code=200, result=-1, data="">, recording=nil, target_call_id="6f178ab2-19cd-4766-87d3-69a5bde9dc8e", component_id="ef689ea5-4ac4-4a82-b5f9-a020b7e80e5b", target_mixer_name=nil>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] Response: Error
ActionID: 59bce0be-32f6-4b4b-b1ab-104eb083986c
Message: Channel SIP/301-0000007c does not exist.
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Error message="Channel SIP/301-0000007c does not exist.", headers=>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-ACTIONS]: #<RubyAMI::Error message="Channel SIP/301-0000007c does not exist.", headers=>
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] Event: QueueMemberStatus
Privilege: agent,all
Queue: priority
Location: Sip/301
MemberName: admin
Membership: realtime
Penalty: 9
CallsTaken: 0
LastCall: 0
Status: 1
Paused: 1
[2012-10-24 08:30:57] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"1", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:30:57] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="QueueMemberStatus", headers={"Privilege"=>"agent,all", "Queue"=>"priority", "Location"=>"Sip/301", "MemberName"=>"admin", "Membership"=>"realtime", "Penalty"=>"9", "CallsTaken"=>"0", "LastCall"=>"0", "Status"=>"1", "Paused"=>"1"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:31:03] DEBUG RubyAMI::Client: [RECV] Event: PeerStatus
Privilege: system,all
ChannelType: SIP
Peer: SIP/rivas
PeerStatus: Registered
Address: 192.168.2.112:5060
[2012-10-24 08:31:03] DEBUG RubyAMI::Client: [RECV] #<RubyAMI::Event name="PeerStatus", headers={"Privilege"=>"system,all", "ChannelType"=>"SIP", "Peer"=>"SIP/rivas", "PeerStatus"=>"Registered", "Address"=>"192.168.2.112:5060"}, text_body=nil, events=nil, action=nil>
[2012-10-24 08:31:03] TRACE RubyAMI::Client: [RECV-EVENTS]: #<RubyAMI::Event name="PeerStatus", headers={"Privilege"=>"system,all", "ChannelType"=>"SIP", "Peer"=>"SIP/rivas", "PeerStatus"=>"Registered", "Address"=>"192.168.2.112:5060"}, text_body=nil, events=nil, action=nil>
AHN> take
AHN> ex[2012-10-24 08:31:26] WARN Adhearsion::Console: No calls active to take
AHN> exit
[2012-10-24 08:31:29] INFO Celluloid: Terminating 9 actors...
[2012-10-24 08:31:29] DEBUG RubyAMI::Client: Finalizing stream
[2012-10-24 08:31:29] DEBUG RubyAMI::Client: Finalizing stream
[2012-10-24 08:31:30] INFO Celluloid: Shutdown completed cleanly
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment