Skip to content

Instantly share code, notes, and snippets.

@ranlauf

ranlauf/gist:c516370c0129cfe1aaf5 Secret

Created November 25, 2015 17:38
Show Gist options
  • Save ranlauf/c516370c0129cfe1aaf5 to your computer and use it in GitHub Desktop.
Save ranlauf/c516370c0129cfe1aaf5 to your computer and use it in GitHub Desktop.
Download ZIP
Adhearsion 3 Beta 1 Log for menu test
Raw
gistfile1.txt
[2015-11-25 18:31:38.067] DEBUG Adhearsion::Rayo::Connection::Asterisk: Starting up...
[2015-11-25 18:31:38.072] INFO Adhearsion::HTTPServer: Starting HTTP server listening on 0.0.0.0:8080
[2015-11-25 18:31:38.085] INFO Celluloid: A Reel good HTTP server! (Codename "Bette")
[2015-11-25 18:31:38.085] INFO Celluloid: Listening on http://0.0.0.0:8080
[2015-11-25 18:31:38.091] INFO Adhearsion::Rayo::Initializer: Starting connection to server
[2015-11-25 18:31:38.100] INFO Adhearsion::Rayo::Initializer: Connected to Rayo server
[2015-11-25 18:31:38.102] INFO Adhearsion::Process: Transitioning from booting to running with 0 active calls due to booted event.
[2015-11-25 18:31:38.105] INFO Adhearsion::Initializer: Adhearsion v3.0.0.beta1 initialized in "development"!
[2015-11-25 18:31:38.110] WARN Adhearsion::Translator::Asterisk: Recordings directory /var/adhearsion/record does not exist. Recording might not work. This warning can be ignored if Adhearsion is running on a separate machine than Asterisk. See http://adhearsion.com/docs/call-controllers#recording
[2015-11-25 18:31:56.835] DEBUG Adhearsion::Call: @: Receiving message: #<Adhearsion::Event::Offer target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:56 +0100, headers={"X-agi_request"=>"async", "X-agi_channel"=>"SIP/111-00000037", "X-agi_language"=>"de", "X-agi_type"=>"SIP", "X-agi_uniqueid"=>"1448472714.504", "X-agi_version"=>"13.6.0", "X-agi_callerid"=>"111", "X-agi_calleridname"=>"biw CC 111", "X-agi_callingpres"=>"0", "X-agi_callingani2"=>"0", "X-agi_callington"=>"0", "X-agi_callingtns"=>"0", "X-agi_dnid"=>"777", "X-agi_rdnis"=>"unknown", "X-agi_context"=>"menu_test", "X-agi_extension"=>"777", "X-agi_priority"=>"1", "X-agi_enhanced"=>"0.0", "X-agi_accountcode"=>"", "X-agi_threadid"=>"140292152915712"}, to="777", from="biw CC 111 <SIP/111>">
[2015-11-25 18:31:56.836] INFO Adhearsion::Router: Call 10ad6b0b-1169-43e7-aa23-d9f13ea1c497 selected route "default" (TestController)
[2015-11-25 18:31:56.839] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Executing command #<Adhearsion::Rayo::Command::Accept target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:56 +0100, request_id="f5995d61-0ea5-4609-97e7-bddd84f222a7", headers={}>
[2015-11-25 18:31:56.926] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:56 +0100, name="AGIExecStart", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4787", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"4", "ChannelStateDesc"=>"Ring", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"1427323525", "Command"=>"EXEC RINGING"}>
[2015-11-25 18:31:56.928] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:56 +0100, name="AGIExecEnd", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4788", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"4", "ChannelStateDesc"=>"Ring", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"1427323525", "Command"=>"EXEC RINGING", "ResultCode"=>"200", "Result"=>"Success"}>
[2015-11-25 18:31:56.929] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:56 +0100, name="AsyncAGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4789", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"4", "ChannelStateDesc"=>"Ring", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Result"=>"200%20result%3D0%0A", "CommandId"=>"191ee6d3-16c7-41a9-bd0c-aaf6d581aa61"}>
[2015-11-25 18:31:56.930] INFO Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Executing controller #<TestController call=10ad6b0b-1169-43e7-aa23-d9f13ea1c497, metadata={}>
[2015-11-25 18:31:56.931] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Executing command #<Adhearsion::Rayo::Command::Answer target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:56 +0100, request_id="d2196240-f63c-4986-afa1-ae5ce3d31da7", headers={}>
[2015-11-25 18:31:56.932] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Offer target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:56 +0100, headers={"X-agi_request"=>"async", "X-agi_channel"=>"SIP/111-00000037", "X-agi_language"=>"de", "X-agi_type"=>"SIP", "X-agi_uniqueid"=>"1448472714.504", "X-agi_version"=>"13.6.0", "X-agi_callerid"=>"111", "X-agi_calleridname"=>"biw CC 111", "X-agi_callingpres"=>"0", "X-agi_callingani2"=>"0", "X-agi_callington"=>"0", "X-agi_callingtns"=>"0", "X-agi_dnid"=>"777", "X-agi_rdnis"=>"unknown", "X-agi_context"=>"menu_test", "X-agi_extension"=>"777", "X-agi_priority"=>"1", "X-agi_enhanced"=>"0.0", "X-agi_accountcode"=>"", "X-agi_threadid"=>"140292152915712"}, to="777", from="biw CC 111 <SIP/111>">
[2015-11-25 18:31:57.024] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, name="CEL", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4790", "File"=>"cel_manager.c", "Line"=>"299", "Func"=>"manager_log", "EventName"=>"ANSWER", "AccountCode"=>"", "CallerIDnum"=>"111", "CallerIDname"=>"biw CC 111", "CallerIDani"=>"111", "CallerIDrdnis"=>"", "CallerIDdnid"=>"777", "Exten"=>"777", "Context"=>"menu_test", "Channel"=>"SIP/111-00000037", "Application"=>"AGI", "AppData"=>"agi:async", "EventTime"=>"2015-11-25 18:31:54", "AMAFlags"=>"DOCUMENTATION", "UniqueID"=>"1448472714.504", "LinkedID"=>"1448472714.504", "Userfield"=>"", "Peer"=>"", "PeerAccount"=>"", "Extra"=>""}>
[2015-11-25 18:31:57.026] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, name="AGIExecStart", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4791", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"4", "ChannelStateDesc"=>"Ring", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"1388495162", "Command"=>"ANSWER"}>
[2015-11-25 18:31:57.027] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, name="Newstate", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4792", "File"=>"manager_channels.c", "Line"=>"677", "Func"=>"channel_snapshot_update", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504"}>
[2015-11-25 18:31:57.243] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, name="AGIExecEnd", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4794", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"1388495162", "Command"=>"ANSWER", "ResultCode"=>"200", "Result"=>"Success"}>
[2015-11-25 18:31:57.244] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, name="AsyncAGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4795", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Result"=>"200%20result%3D0%0A", "CommandId"=>"420e4b74-6203-436f-ab78-859207f77c08"}>
[2015-11-25 18:31:57.253] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Executing command #<Adhearsion::Rayo::Component::Prompt target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, request_id="ece545ca-c75e-4892-9ae7-8082e8efd69d", barge_in=true, output=#<Adhearsion::Rayo::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, request_id="4e6d833a-f662-429a-94de-bc26ec49eb70", headers={}, voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Adhearsion::Rayo::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
<audio src="file://vm-press"/>
<audio src="file://vm-first"/>
<audio src="file://vm-for"/>
<audio src="file://vm-password"/>
</speak>>]>, input=#<Adhearsion::Rayo::Component::Input target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, request_id="f4cf99fc-f6db-4a44-bff3-f75312d4c5d8", headers={}, max_silence=5000, min_confidence=0.5, mode=:dtmf, recognizer=nil, language="en-US", terminator="#", sensitivity=nil, initial_timeout=5000, inter_digit_timeout=2000, recognition_timeout=nil, grammars=[#<Adhearsion::Rayo::Component::Input::Grammar target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="options" tag-format="semantics/1.0-literals">
<rule id="options" scope="public">
<item>
<one-of>
<item>
<tag>0</tag>
<one-of>
<item>1</item>
<item>2</item>
<item>3</item>
</one-of>
</item>
</one-of>
</item>
</rule>
</grammar>, content_type="application/srgs+xml", url=nil>]>>
[2015-11-25 18:31:57.270] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:57 +0100, name="AGIExecStart", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4796", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"1948518814", "Command"=>"EXEC Playback \"vm-press&vm-first&vm-for&vm-password\""}>
[2015-11-25 18:31:58.635] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="DTMFBegin", headers={"Privilege"=>"dtmf,all", "SequenceNumber"=>"4797", "File"=>"manager_channels.c", "Line"=>"831", "Func"=>"channel_dtmf_begin_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Digit"=>"1", "Direction"=>"Received"}>
[2015-11-25 18:31:58.732] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="DTMFEnd", headers={"Privilege"=>"dtmf,all", "SequenceNumber"=>"4798", "File"=>"manager_channels.c", "Line"=>"878", "Func"=>"channel_dtmf_end_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Digit"=>"1", "DurationMs"=>"120", "Direction"=>"Received"}>
[2015-11-25 18:31:58.744] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"4799", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Variable"=>"PLAYBACKSTATUS", "Value"=>"FAILED"}>
[2015-11-25 18:31:58.745] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="AGIExecEnd", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4800", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"1948518814", "Command"=>"EXEC Playback \"vm-press&vm-first&vm-for&vm-password\"", "ResultCode"=>"200", "Result"=>"Success"}>
[2015-11-25 18:31:58.747] DEBUG Adhearsion::CallController::Input::PromptBuilder: Ask completed with result #<Adhearsion::CallController::Input::Result status=:match, confidence=1.0, utterance="1", interpretation="0", nlsml=#<Nokogiri::XML::Element:0x1b51f48 name="result" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1b51b4c name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1b51a0c name="confidence" value="1.0">] children=[#<Nokogiri::XML::Element:0x1b517b4 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1bba188 "0">]>, #<Nokogiri::XML::Element:0x1b514f8 name="input" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1b513cc name="mode" value="dtmf">] children=[#<Nokogiri::XML::Text:0x1cb1988 "1">]>]>]>>
[2015-11-25 18:31:58.751] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="AsyncAGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4801", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Result"=>"200%20result%3D0%0A", "CommandId"=>"81099210-44b0-4c0d-980f-97211b85977d"}>
[2015-11-25 18:31:58.753] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="AsyncAGIEnd", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4802", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504"}>
[2015-11-25 18:31:58.754] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"4803", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"menu_test", "Exten"=>"777", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Variable"=>"AGISTATUS", "Value"=>"HANGUP"}>
[2015-11-25 18:31:58.754] INFO Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Caller pressed 1
[2015-11-25 18:31:58.757] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="Newexten", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4804", "File"=>"manager_channels.c", "Line"=>"677", "Func"=>"channel_snapshot_update", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Extension"=>"1", "Application"=>"AGI", "AppData"=>"agi:async"}>
[2015-11-25 18:31:58.757] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="AsyncAGIStart", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4805", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Env"=>"agi_request%3A%20async%0Aagi_channel%3A%20SIP%2F111-00000037%0Aagi_language%3A%20de%0Aagi_type%3A%20SIP%0Aagi_uniqueid%3A%201448472714.504%0Aagi_version%3A%2013.6.0%0Aagi_callerid%3A%20111%0Aagi_calleridname%3A%20biw%20CC%20111%0Aagi_callingpres%3A%200%0Aagi_callingani2%3A%200%0Aagi_callington%3A%200%0Aagi_callingtns%3A%200%0Aagi_dnid%3A%20777%0Aagi_rdnis%3A%20unknown%0Aagi_context%3A%20adhearsion-redirect%0Aagi_extension%3A%201%0Aagi_priority%3A%201%0Aagi_enhanced%3A%200.0%0Aagi_accountcode%3A%20%0Aagi_threadid%3A%20140292152915712%0A%0A"}>
[2015-11-25 18:31:58.758] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Complete target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id="b39affa9-4558-4c0e-abfc-ac05069c17a1", source_uri="b39affa9-4558-4c0e-abfc-ac05069c17a1", domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, reason=#<Adhearsion::Rayo::Component::Input::Complete::Match target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name=:match, content_type="application/nlsml+xml", nlsml=#<Nokogiri::XML::Element:0x1b51f48 name="result" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Element:0x1b51b4c name="interpretation" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1b51a0c name="confidence" value="1.0">] children=[#<Nokogiri::XML::Element:0x1b517b4 name="instance" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> children=[#<Nokogiri::XML::Text:0x1bba188 "0">]>, #<Nokogiri::XML::Element:0x1b514f8 name="input" namespace=#<Nokogiri::XML::Namespace:0x1b51e6c href="http://www.ietf.org/xml/ns/mrcpv2"> attributes=[#<Nokogiri::XML::Attr:0x1b513cc name="mode" value="dtmf">] children=[#<Nokogiri::XML::Text:0x1cb1988 "1">]>]>]>>, recording=nil, fax=nil, fax_metadata={}>
[2015-11-25 18:31:58.758] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Executing command #<Adhearsion::Rayo::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, request_id="e6c1e859-884f-48fc-916e-8d223d8db4a4", headers={}, voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Adhearsion::Rayo::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
<audio src="file://vm-pls-try-again"/>
</speak>>]>
[2015-11-25 18:31:58.758] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Complete target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id="b8505ce9-ea4d-4ba9-a902-2b36c274d620", source_uri="b8505ce9-ea4d-4ba9-a902-2b36c274d620", domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, reason=#<Adhearsion::Event::Complete::Error target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name=:error, details="Terminated due to playback error">, recording=nil, fax=nil, fax_metadata={}>
[2015-11-25 18:31:58.781] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:31:58 +0100, name="AGIExecStart", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4806", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"2103661936", "Command"=>"EXEC Playback \"vm-pls-try-again\""}>
[2015-11-25 18:32:00.194] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:00 +0100, name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"4807", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Variable"=>"PLAYBACKSTATUS", "Value"=>"SUCCESS"}>
[2015-11-25 18:32:00.195] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:00 +0100, name="AGIExecEnd", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4808", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "CommandId"=>"2103661936", "Command"=>"EXEC Playback \"vm-pls-try-again\"", "ResultCode"=>"200", "Result"=>"Success"}>
[2015-11-25 18:32:00.195] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:00 +0100, name="AsyncAGIExec", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4809", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Result"=>"200%20result%3D0%0A", "CommandId"=>"f8d6d8d0-3e4c-48cb-95b1-ebac21d4b7a1"}>
[2015-11-25 18:32:11.797] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Executing command #<Adhearsion::Rayo::Component::Prompt target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, request_id="e0094308-3320-4319-ba7e-dba782a34f61", barge_in=true, output=#<Adhearsion::Rayo::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, request_id="a93d98a1-3ae1-4066-98bb-e7fe0d59b389", headers={}, voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Adhearsion::Rayo::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US">
<audio src="file://vm-press"/>
<audio src="file://vm-first"/>
<audio src="file://vm-for"/>
<audio src="file://vm-password"/>
</speak>>]>, input=#<Adhearsion::Rayo::Component::Input target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, request_id="f069c5ab-0105-4b77-8b64-ce0388c63bb3", headers={}, max_silence=5000, min_confidence=0.5, mode=:dtmf, recognizer=nil, language="en-US", terminator=nil, sensitivity=nil, initial_timeout=5000, inter_digit_timeout=2000, recognition_timeout=nil, grammars=[#<Adhearsion::Rayo::Component::Input::Grammar target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="options" tag-format="semantics/1.0-literals">
<rule id="options" scope="public">
<item>
<one-of>
<item>
<tag>0</tag>
<one-of>
<item>1</item>
<item>2</item>
<item>3</item>
</one-of>
</item>
</one-of>
</item>
</rule>
</grammar>, content_type="application/srgs+xml", url=nil>]>>
[2015-11-25 18:32:11.797] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name="CEL", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4819", "File"=>"cel_manager.c", "Line"=>"299", "Func"=>"manager_log", "EventName"=>"HANGUP", "AccountCode"=>"", "CallerIDnum"=>"111", "CallerIDname"=>"biw CC 111", "CallerIDani"=>"111", "CallerIDrdnis"=>"", "CallerIDdnid"=>"777", "Exten"=>"1", "Context"=>"adhearsion-redirect", "Channel"=>"SIP/111-00000037", "Application"=>"", "AppData"=>"", "EventTime"=>"2015-11-25 18:32:09", "AMAFlags"=>"DOCUMENTATION", "UniqueID"=>"1448472714.504", "LinkedID"=>"1448472714.504", "Userfield"=>"", "Peer"=>"", "PeerAccount"=>"", "Extra"=>"{\"hangupcause\":0,\"hangupsource\":\"SIP/111-00000037\",\"dialstatus\":\"\"}"}>
[2015-11-25 18:32:11.800] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name="HangupRequest", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4818", "File"=>"manager_channels.c", "Line"=>"732", "Func"=>"channel_hangup_request_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504"}>
[2015-11-25 18:32:11.800] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name="CEL", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4820", "File"=>"cel_manager.c", "Line"=>"299", "Func"=>"manager_log", "EventName"=>"CHAN_END", "AccountCode"=>"", "CallerIDnum"=>"111", "CallerIDname"=>"biw CC 111", "CallerIDani"=>"111", "CallerIDrdnis"=>"", "CallerIDdnid"=>"777", "Exten"=>"1", "Context"=>"adhearsion-redirect", "Channel"=>"SIP/111-00000037", "Application"=>"", "AppData"=>"", "EventTime"=>"2015-11-25 18:32:09", "AMAFlags"=>"DOCUMENTATION", "UniqueID"=>"1448472714.504", "LinkedID"=>"1448472714.504", "Userfield"=>"", "Peer"=>"", "PeerAccount"=>"", "Extra"=>""}>
[2015-11-25 18:32:11.800] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name="AsyncAGIEnd", headers={"Privilege"=>"agi,all", "SequenceNumber"=>"4821", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504"}>
[2015-11-25 18:32:11.804] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name="VarSet", headers={"Privilege"=>"dialplan,all", "SequenceNumber"=>"4822", "File"=>"manager.c", "Line"=>"1693", "Func"=>"manager_default_msg_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Variable"=>"AGISTATUS", "Value"=>"HANGUP"}>
[2015-11-25 18:32:11.804] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name="SoftHangupRequest", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4823", "File"=>"manager_channels.c", "Line"=>"732", "Func"=>"channel_hangup_request_cb", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Cause"=>"16"}>
[2015-11-25 18:32:11.805] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Asterisk::AMI target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name="Hangup", headers={"Privilege"=>"call,all", "SequenceNumber"=>"4824", "File"=>"manager_channels.c", "Line"=>"677", "Func"=>"channel_snapshot_update", "Channel"=>"SIP/111-00000037", "ChannelState"=>"6", "ChannelStateDesc"=>"Up", "CallerIDNum"=>"111", "CallerIDName"=>"biw CC 111", "ConnectedLineNum"=>"<unknown>", "ConnectedLineName"=>"<unknown>", "Language"=>"de", "AccountCode"=>"", "Context"=>"adhearsion-redirect", "Exten"=>"1", "Priority"=>"1", "Uniqueid"=>"1448472714.504", "Linkedid"=>"1448472714.504", "Cause"=>"0", "Cause-txt"=>"Unknown"}>
[2015-11-25 18:32:11.844] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::Complete target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id="ec964b3b-446c-4bb4-a76b-793f72551cdc", source_uri="ec964b3b-446c-4bb4-a76b-793f72551cdc", domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, reason=#<Adhearsion::Event::Complete::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, name=:hangup>, recording=nil, fax=nil, fax_metadata={}>
[2015-11-25 18:32:11.844] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Finished executing controller #<TestController call=10ad6b0b-1169-43e7-aa23-d9f13ea1c497, metadata={}>
[2015-11-25 18:32:11.845] DEBUG Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Receiving message: #<Adhearsion::Event::End target_call_id="10ad6b0b-1169-43e7-aa23-d9f13ea1c497", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, timestamp=Wed, 25 Nov 2015 18:32:11 +0100, headers={}, reason=:hungup, platform_code="0">
[2015-11-25 18:32:11.845] INFO Adhearsion::Call: 10ad6b0b-1169-43e7-aa23-d9f13ea1c497@: Call biw CC 111 <SIP/111> -> 777 ended due to hungup (code 0)
[2015-11-25 18:32:11.846] INFO Adhearsion::Router::Route: Call 10ad6b0b-1169-43e7-aa23-d9f13ea1c497 routing completed. Hanging up now.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment