Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
Bits o' a log
[2014-02-16 12:04:21] DEBUG Roster: Selecting 363@10.1.2.87/browser for inside_sales_prospect
[2014-02-16 12:04:21] INFO Roster: Updating 363@10.1.2.87/browser to composing
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Finished executing controller #<OutboundConnectingController call=6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d, metadata={}>
[2014-02-16 12:04:21] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Connecting customer inside_sales 25376541 to extension 363
[2014-02-16 12:04:21] INFO OutboundCustomersController: Call was hung up while executing a controller
[2014-02-16 12:04:21] DEBUG OutboundCustomersController: Finished executing controller #<OutboundCustomersController call=, metadata={}>
[2014-02-16 12:04:21] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: connecting inside_sales 25376541 to extension 363
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 232d2f70-566d-47ae-82d9-eb2a4d532e0a@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/usr/share/asterisk/sounds/en/confbridge-join"]>
[2014-02-16 12:04:21] INFO Roster: Updating 363@10.1.2.87/browser to unavailable
[2014-02-16 12:04:21] DEBUG AMI: #disconnect_agent_extension(333)
[2014-02-16 12:04:21] INFO Adhearsion::OutboundCall: 7732e2e6-46f1-4d13-9957-0247f892e964@: Hanging up
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 7732e2e6-46f1-4d13-9957-0247f892e964@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 7732e2e6-46f1-4d13-9957-0247f892e964@: Receiving message: #<Punchblock::Event::End target_call_id="7732e2e6-46f1-4d13-9957-0247f892e964", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:21] INFO Adhearsion::OutboundCall: 7732e2e6-46f1-4d13-9957-0247f892e964@: Call ended due to hangup
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 7732e2e6-46f1-4d13-9957-0247f892e964@: Agent 333 call ended
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 232d2f70-566d-47ae-82d9-eb2a4d532e0a@: Receiving message: #<Punchblock::Event::Complete target_call_id="232d2f70-566d-47ae-82d9-eb2a4d532e0a", target_mixer_name=nil, component_id="ec1a0449-5aae-4d0d-8efd-d6c8e3822bd2", source_uri="ec1a0449-5aae-4d0d-8efd-d6c8e3822bd2", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:21] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Joining to #<Celluloid::ActorProxy:0x6174094d>
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="232d2f70-566d-47ae-82d9-eb2a4d532e0a", mixer_name=nil, direction=nil, media=nil>
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Receiving message: #<Punchblock::Event::Joined target_call_id="6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="232d2f70-566d-47ae-82d9-eb2a4d532e0a", mixer_name=nil>
[2014-02-16 12:04:21] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Joined to call 232d2f70-566d-47ae-82d9-eb2a4d532e0a
[2014-02-16 12:04:21] DEBUG Adhearsion::OutboundCall: 232d2f70-566d-47ae-82d9-eb2a4d532e0a@: Receiving message: #<Punchblock::Event::Joined target_call_id="232d2f70-566d-47ae-82d9-eb2a4d532e0a", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d", mixer_name=nil>
[2014-02-16 12:04:21] INFO Adhearsion::OutboundCall: 232d2f70-566d-47ae-82d9-eb2a4d532e0a@: Joined to call 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d
[2014-02-16 12:04:22] INFO Roster: Updating 360@10.1.2.87/browser to available
[2014-02-16 12:04:22] DEBUG AMI: #disconnect_agent_extension(371)
[2014-02-16 12:04:22] DEBUG AMI: #disconnect_agent_extension(371)
[2014-02-16 12:04:22] DEBUG Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Receiving message: #<Punchblock::Event::Complete target_call_id="bec1823f-aa64-4860-ab92-6bab1cd3163a", target_mixer_name=nil, component_id="36b8e7a3-1658-4d37-a6d9-8f338447f495", source_uri="36b8e7a3-1658-4d37-a6d9-8f338447f495", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:22] DEBUG Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:04:22] INFO Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Call to inside_sales 25376537 detected a machine
[2014-02-16 12:04:22] DEBUG Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Receiving message: #<Punchblock::Event::Complete target_call_id="bec1823f-aa64-4860-ab92-6bab1cd3163a", target_mixer_name=nil, component_id="dd4025e2-3830-46f0-b99b-edfd7dfe0e0b", source_uri="dd4025e2-3830-46f0-b99b-edfd7dfe0e0b", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="MACHINE">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:22] INFO Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Hung up on answering machine at inside_sales 25376537
[2014-02-16 12:04:22] DEBUG Adhearsion::OutboundCall: 3a655a08-e8a2-481c-a063-bf773b28ebbf@: Receiving message: #<Punchblock::Event::End target_call_id="3a655a08-e8a2-481c-a063-bf773b28ebbf", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:22] INFO Adhearsion::OutboundCall: 3a655a08-e8a2-481c-a063-bf773b28ebbf@: Call ended due to hangup
[2014-02-16 12:04:22] DEBUG Adhearsion::OutboundCall: 3a655a08-e8a2-481c-a063-bf773b28ebbf@: Agent 373 call ended
[2014-02-16 12:04:22] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/373", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Receiving message: #<Punchblock::Event::Ringing target_call_id="0a5394f3-8613-459e-9715-e5bd3d9f8438", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Receiving message: #<Punchblock::Event::Answered target_call_id="0a5394f3-8613-459e-9715-e5bd3d9f8438", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:23] INFO Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Executing controller #<AgentWaitingController call=0a5394f3-8613-459e-9715-e5bd3d9f8438, metadata={}>
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Finished executing controller #<AgentWaitingController call=0a5394f3-8613-459e-9715-e5bd3d9f8438, metadata={}>
[2014-02-16 12:04:23] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:04:23] INFO Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Hanging up
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Finished executing controller #<SalesCallsController call=bec1823f-aa64-4860-ab92-6bab1cd3163a, metadata={:customer_id=>25376537, :customer_type=>"inside_sales", :call_started=>1392570225.082, :answered?=>true}>
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Receiving message: #<Punchblock::Event::End target_call_id="bec1823f-aa64-4860-ab92-6bab1cd3163a", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:23] INFO Adhearsion::OutboundCall: bec1823f-aa64-4860-ab92-6bab1cd3163a@: Call ended due to hangup
[2014-02-16 12:04:23] INFO Adhearsion::Router::Route: Call routing completed. Hanging up now...
[2014-02-16 12:04:23] DEBUG CallInformation: pop bec1823f-aa64-4860-ab92-6bab1cd3163a
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: 6d930606-f1e1-4d4a-ac8f-08be8f8a511f@: Receiving message: #<Punchblock::Event::Ringing target_call_id="6d930606-f1e1-4d4a-ac8f-08be8f8a511f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:23] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:23] DEBUG QueueWorker: Looking for 3 inside_sales_prospect calls...
[2014-02-16 12:04:23] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"6032357399","from":"508-263-9010","customer_id":25376546,"name":"Matt Greenwood"}
[2014-02-16 12:04:23] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/6032357399", from="5082639010", timeout=30000, join=nil>
[2014-02-16 12:04:23] DEBUG CallInformation: PUSH 22e2606b-ed31-479e-85f2-830cdbb2bdc7 {:customer_id=>25376546, :customer_type=>:inside_sales, :call_started=>1392570263.921, :answered?=>false}
[2014-02-16 12:04:23] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"4437585243","from":"240-965-8500","customer_id":25376547,"name":"Florence Falem"}
[2014-02-16 12:04:24] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/4437585243", from="2409658500", timeout=30000, join=nil>
[2014-02-16 12:04:24] DEBUG CallInformation: PUSH d2b588b8-6ec8-4842-92ef-3426fe53e74f {:customer_id=>25376547, :customer_type=>:inside_sales, :call_started=>1392570264.163, :answered?=>false}
[2014-02-16 12:04:24] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"8567518109","from":"484-482-2479","customer_id":25376548,"name":"Laura Fiorello"}
[2014-02-16 12:04:24] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/8567518109", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:04:24] DEBUG CallInformation: PUSH f9215d1f-779c-4900-943c-2e2be8f75a3f {:customer_id=>25376548, :customer_type=>:inside_sales, :call_started=>1392570264.207, :answered?=>false}
[2014-02-16 12:04:26] DEBUG Adhearsion::OutboundCall: f9215d1f-779c-4900-943c-2e2be8f75a3f@: Receiving message: #<Punchblock::Event::Ringing target_call_id="f9215d1f-779c-4900-943c-2e2be8f75a3f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:26] DEBUG AMI: #disconnect_agent_extension(373)
[2014-02-16 12:04:26] INFO Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Hanging up
[2014-02-16 12:04:26] DEBUG Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:26] DEBUG Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Receiving message: #<Punchblock::Event::End target_call_id="0a5394f3-8613-459e-9715-e5bd3d9f8438", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:26] INFO Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Call ended due to hangup
[2014-02-16 12:04:26] DEBUG Adhearsion::OutboundCall: 0a5394f3-8613-459e-9715-e5bd3d9f8438@: Agent 373 call ended
[2014-02-16 12:04:26] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/373", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:04:26] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:26] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:26] DEBUG Adhearsion::OutboundCall: 6be71816-3d6d-4d84-9578-c243051a71d8@: Receiving message: #<Punchblock::Event::Ringing target_call_id="6be71816-3d6d-4d84-9578-c243051a71d8", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:27] DEBUG Adhearsion::OutboundCall: 6be71816-3d6d-4d84-9578-c243051a71d8@: Receiving message: #<Punchblock::Event::Answered target_call_id="6be71816-3d6d-4d84-9578-c243051a71d8", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:27] INFO Adhearsion::OutboundCall: 6be71816-3d6d-4d84-9578-c243051a71d8@: Executing controller #<AgentWaitingController call=6be71816-3d6d-4d84-9578-c243051a71d8, metadata={}>
[2014-02-16 12:04:27] DEBUG Adhearsion::OutboundCall: 6be71816-3d6d-4d84-9578-c243051a71d8@: Finished executing controller #<AgentWaitingController call=6be71816-3d6d-4d84-9578-c243051a71d8, metadata={}>
[2014-02-16 12:04:27] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:04:27] DEBUG Adhearsion::OutboundCall: 6da46240-5103-4165-bc27-5c9d5f4e829a@: Receiving message: #<Punchblock::Event::Ringing target_call_id="6da46240-5103-4165-bc27-5c9d5f4e829a", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:28] DEBUG Adhearsion::OutboundCall: 6be71816-3d6d-4d84-9578-c243051a71d8@: Receiving message: #<Punchblock::Event::End target_call_id="6be71816-3d6d-4d84-9578-c243051a71d8", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:28] INFO Adhearsion::OutboundCall: 6be71816-3d6d-4d84-9578-c243051a71d8@: Call ended due to hangup
[2014-02-16 12:04:28] DEBUG Adhearsion::OutboundCall: 6be71816-3d6d-4d84-9578-c243051a71d8@: Agent 373 call ended
[2014-02-16 12:04:28] DEBUG Adhearsion::OutboundCall: 6da46240-5103-4165-bc27-5c9d5f4e829a@: Receiving message: #<Punchblock::Event::End target_call_id="6da46240-5103-4165-bc27-5c9d5f4e829a", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:busy, platform_code="17">
[2014-02-16 12:04:28] INFO Adhearsion::OutboundCall: 6da46240-5103-4165-bc27-5c9d5f4e829a@: Call ended due to busy
[2014-02-16 12:04:28] DEBUG CallInformation: pop 6da46240-5103-4165-bc27-5c9d5f4e829a
[2014-02-16 12:04:29] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:29] DEBUG QueueWorker: Looking for 1 inside_sales_prospect calls...
[2014-02-16 12:04:29] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"8572648196","from":"508-263-9010","customer_id":25376549,"name":"Lillyanna Saktur"}
[2014-02-16 12:04:30] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/8572648196", from="5082639010", timeout=30000, join=nil>
[2014-02-16 12:04:30] DEBUG CallInformation: PUSH f7b48db4-ce9d-43fa-894e-389565cfa448 {:customer_id=>25376549, :customer_type=>:inside_sales, :call_started=>1392570269.989, :answered?=>false}
[2014-02-16 12:04:31] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/373", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:04:31] DEBUG Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Receiving message: #<Punchblock::Event::Ringing target_call_id="307e291c-ae90-4754-b128-21e6237b4eda", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:32] DEBUG Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Receiving message: #<Punchblock::Event::Answered target_call_id="307e291c-ae90-4754-b128-21e6237b4eda", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:32] INFO Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Executing controller #<AgentWaitingController call=307e291c-ae90-4754-b128-21e6237b4eda, metadata={}>
[2014-02-16 12:04:32] DEBUG Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Finished executing controller #<AgentWaitingController call=307e291c-ae90-4754-b128-21e6237b4eda, metadata={}>
[2014-02-16 12:04:32] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:04:32] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:32] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:33] INFO Roster: Updating 373@10.1.2.87/browser to available
[2014-02-16 12:04:34] DEBUG PeerStatusEventHandler: #<Punchblock::Event::Asterisk::AMI::Event target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="PeerStatus", headers={"Privilege"=>"system,all", "Timestamp"=>"1392570274.901638", "ChannelType"=>"SIP", "Peer"=>"SIP/500", "PeerStatus"=>"Registered", "Address"=>"10.10.2.149:5064"}>
[2014-02-16 12:04:34] DEBUG PeerStatusEventHandler: The SIP peer SIP/500 is Registered
[2014-02-16 12:04:34] WARN Roster: Skipping presence update for 371@10.1.2.87/browser: missing active_agent
[2014-02-16 12:04:35] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:35] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:38] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:38] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:41] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:41] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:42] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/371", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:04:42] DEBUG Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Receiving message: #<Punchblock::Event::Ringing target_call_id="341ae3e8-f68b-4534-8666-194e28fd7ecb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:42] DEBUG Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Receiving message: #<Punchblock::Event::Answered target_call_id="341ae3e8-f68b-4534-8666-194e28fd7ecb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:42] INFO Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Executing controller #<AgentWaitingController call=341ae3e8-f68b-4534-8666-194e28fd7ecb, metadata={}>
[2014-02-16 12:04:42] DEBUG Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Finished executing controller #<AgentWaitingController call=341ae3e8-f68b-4534-8666-194e28fd7ecb, metadata={}>
[2014-02-16 12:04:42] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Answered target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:43] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Executing controller #<SalesCallsController call=d2b588b8-6ec8-4842-92ef-3426fe53e74f, metadata={:customer_id=>25376547, :customer_type=>"inside_sales", :call_started=>1392570264.163, :answered?=>false}>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Outgoing call beginning for inside_sales 25376547
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Complete target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id="84e187a0-c1fd-4b0d-8fc2-d47d7b675dba", source_uri="84e187a0-c1fd-4b0d-8fc2-d47d7b675dba", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Complete target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id="9bd7336b-83aa-43c5-a68d-d1deb9bdef2d", source_uri="9bd7336b-83aa-43c5-a68d-d1deb9bdef2d", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376547_767040.wav"]>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Complete target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id="c7440399-3335-4c66-b3f4-cab8ab152269", source_uri="c7440399-3335-4c66-b3f4-cab8ab152269", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:43] DEBUG AMI: #disconnect_agent_extension(371)
[2014-02-16 12:04:43] INFO Roster: Updating 371@10.1.2.87/browser to unavailable
[2014-02-16 12:04:43] INFO Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Hanging up
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Receiving message: #<Punchblock::Event::End target_call_id="341ae3e8-f68b-4534-8666-194e28fd7ecb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:43] INFO Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Call ended due to hangup
[2014-02-16 12:04:43] DEBUG Adhearsion::OutboundCall: 341ae3e8-f68b-4534-8666-194e28fd7ecb@: Agent 371 call ended
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Receiving message: #<Punchblock::Event::Answered target_call_id="f7b48db4-ce9d-43fa-894e-389565cfa448", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:44] INFO Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Executing controller #<SalesCallsController call=f7b48db4-ce9d-43fa-894e-389565cfa448, metadata={:customer_id=>25376549, :customer_type=>"inside_sales", :call_started=>1392570269.989, :answered?=>false}>
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Outgoing call beginning for inside_sales 25376549
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Receiving message: #<Punchblock::Event::Complete target_call_id="f7b48db4-ce9d-43fa-894e-389565cfa448", target_mixer_name=nil, component_id="58f33e5b-5523-42c0-b2b8-d878f7854fbf", source_uri="58f33e5b-5523-42c0-b2b8-d878f7854fbf", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Receiving message: #<Punchblock::Event::Complete target_call_id="f7b48db4-ce9d-43fa-894e-389565cfa448", target_mixer_name=nil, component_id="dd82726b-32fd-49e8-b864-10e76b29c8e2", source_uri="dd82726b-32fd-49e8-b864-10e76b29c8e2", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376549_319337.wav"]>
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:04:44] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Receiving message: #<Punchblock::Event::Complete target_call_id="f7b48db4-ce9d-43fa-894e-389565cfa448", target_mixer_name=nil, component_id="35b5a95c-3e29-48bd-98e5-0b177afa7151", source_uri="35b5a95c-3e29-48bd-98e5-0b177afa7151", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:44] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:44] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Complete target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id="a2c08d18-7b85-435b-8506-fa564fe20f13", source_uri="a2c08d18-7b85-435b-8506-fa564fe20f13", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Call to inside_sales 25376547 detected a human
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Complete target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id="b897db44-971a-4d28-b7e2-48cc2b57c093", source_uri="b897db44-971a-4d28-b7e2-48cc2b57c093", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="HUMAN">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Finished executing controller #<SalesCallsController call=d2b588b8-6ec8-4842-92ef-3426fe53e74f, metadata={:customer_id=>25376547, :customer_type=>"inside_sales", :call_started=>1392570264.163, :answered?=>true}>
[2014-02-16 12:04:45] DEBUG Roster: Selecting 301@10.1.2.87/browser for inside_sales_prospect
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Receiving message: #<Punchblock::Event::Complete target_call_id="f7b48db4-ce9d-43fa-894e-389565cfa448", target_mixer_name=nil, component_id="68f458f4-1f66-4ef1-9f65-c1df25c6332b", source_uri="68f458f4-1f66-4ef1-9f65-c1df25c6332b", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Call to inside_sales 25376549 detected a human
[2014-02-16 12:04:45] INFO Roster: Updating 301@10.1.2.87/browser to composing
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Receiving message: #<Punchblock::Event::Complete target_call_id="f7b48db4-ce9d-43fa-894e-389565cfa448", target_mixer_name=nil, component_id="fd9c0648-6e94-4dcc-94ef-067056ce798f", source_uri="fd9c0648-6e94-4dcc-94ef-067056ce798f", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="HUMAN">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Finished executing controller #<SalesCallsController call=f7b48db4-ce9d-43fa-894e-389565cfa448, metadata={:customer_id=>25376549, :customer_type=>"inside_sales", :call_started=>1392570269.989, :answered?=>true}>
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Finished executing controller #<OutboundConnectingController call=d2b588b8-6ec8-4842-92ef-3426fe53e74f, metadata={}>
[2014-02-16 12:04:45] DEBUG Roster: Selecting 343@10.1.2.87/browser for inside_sales_prospect
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Connecting customer inside_sales 25376547 to extension 301
[2014-02-16 12:04:45] INFO Roster: Updating 343@10.1.2.87/browser to composing
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Finished executing controller #<OutboundConnectingController call=f7b48db4-ce9d-43fa-894e-389565cfa448, metadata={}>
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Connecting customer inside_sales 25376549 to extension 343
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: connecting inside_sales 25376549 to extension 343
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: 141cef83-95b0-4207-83d1-545ac291a8c8@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/usr/share/asterisk/sounds/en/confbridge-join"]>
[2014-02-16 12:04:45] INFO Roster: Updating 343@10.1.2.87/browser to unavailable
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: 141cef83-95b0-4207-83d1-545ac291a8c8@: Receiving message: #<Punchblock::Event::Complete target_call_id="141cef83-95b0-4207-83d1-545ac291a8c8", target_mixer_name=nil, component_id="736f6aa8-cb33-4d4d-ac00-4b71f4eae409", source_uri="736f6aa8-cb33-4d4d-ac00-4b71f4eae409", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Joining to #<Celluloid::ActorProxy:0x3235272a>
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="141cef83-95b0-4207-83d1-545ac291a8c8", mixer_name=nil, direction=nil, media=nil>
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: connecting inside_sales 25376547 to extension 301
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Receiving message: #<Punchblock::Event::Joined target_call_id="f7b48db4-ce9d-43fa-894e-389565cfa448", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="141cef83-95b0-4207-83d1-545ac291a8c8", mixer_name=nil>
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: f7b48db4-ce9d-43fa-894e-389565cfa448@: Joined to call 141cef83-95b0-4207-83d1-545ac291a8c8
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: 141cef83-95b0-4207-83d1-545ac291a8c8@: Receiving message: #<Punchblock::Event::Joined target_call_id="141cef83-95b0-4207-83d1-545ac291a8c8", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="f7b48db4-ce9d-43fa-894e-389565cfa448", mixer_name=nil>
[2014-02-16 12:04:45] DEBUG Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/usr/share/asterisk/sounds/en/confbridge-join"]>
[2014-02-16 12:04:45] INFO Adhearsion::OutboundCall: 141cef83-95b0-4207-83d1-545ac291a8c8@: Joined to call f7b48db4-ce9d-43fa-894e-389565cfa448
[2014-02-16 12:04:45] INFO Roster: Updating 301@10.1.2.87/browser to unavailable
[2014-02-16 12:04:46] DEBUG Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: Receiving message: #<Punchblock::Event::Complete target_call_id="b91a082e-9f4b-4bc4-8933-5c91695902cb", target_mixer_name=nil, component_id="5f49c294-83b3-44a7-b052-b62804bdca11", source_uri="5f49c294-83b3-44a7-b052-b62804bdca11", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:46] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Joining to #<Celluloid::ActorProxy:0x29590b69>
[2014-02-16 12:04:46] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="b91a082e-9f4b-4bc4-8933-5c91695902cb", mixer_name=nil, direction=nil, media=nil>
[2014-02-16 12:04:46] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Joined target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="b91a082e-9f4b-4bc4-8933-5c91695902cb", mixer_name=nil>
[2014-02-16 12:04:46] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Joined to call b91a082e-9f4b-4bc4-8933-5c91695902cb
[2014-02-16 12:04:46] DEBUG Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: Receiving message: #<Punchblock::Event::Joined target_call_id="b91a082e-9f4b-4bc4-8933-5c91695902cb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="d2b588b8-6ec8-4842-92ef-3426fe53e74f", mixer_name=nil>
[2014-02-16 12:04:46] INFO Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: Joined to call d2b588b8-6ec8-4842-92ef-3426fe53e74f
[2014-02-16 12:04:47] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:47] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:48] DEBUG Adhearsion::OutboundCall: 6d930606-f1e1-4d4a-ac8f-08be8f8a511f@: Receiving message: #<Punchblock::Event::Answered target_call_id="6d930606-f1e1-4d4a-ac8f-08be8f8a511f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:48] WARN Adhearsion::Router: Call 6d930606-f1e1-4d4a-ac8f-08be8f8a511f could not find a matching route. Rejecting.
[2014-02-16 12:04:50] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:50] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:50] DEBUG Adhearsion::OutboundCall: 865fa93c-94da-4110-9bbb-8d563b521307@: Receiving message: #<Punchblock::Event::End target_call_id="865fa93c-94da-4110-9bbb-8d563b521307", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:50] INFO Adhearsion::OutboundCall: 865fa93c-94da-4110-9bbb-8d563b521307@: Call ended due to hangup
[2014-02-16 12:04:50] DEBUG Adhearsion::OutboundCall: 7fee80ed-283c-42cc-a9d0-7802faa3812c@: Receiving message: #<Punchblock::Event::End target_call_id="7fee80ed-283c-42cc-a9d0-7802faa3812c", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:50] INFO Adhearsion::OutboundCall: 7fee80ed-283c-42cc-a9d0-7802faa3812c@: Call ended due to hangup
[2014-02-16 12:04:50] DEBUG CallInformation: pop 865fa93c-94da-4110-9bbb-8d563b521307
[2014-02-16 12:04:50] DEBUG CallInformation: pop 7fee80ed-283c-42cc-a9d0-7802faa3812c
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Answered target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:52] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Executing controller #<SalesCallsController call=22e2606b-ed31-479e-85f2-830cdbb2bdc7, metadata={:customer_id=>25376546, :customer_type=>"inside_sales", :call_started=>1392570263.921, :answered?=>false}>
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Outgoing call beginning for inside_sales 25376546
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Complete target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id="6861ee45-ebb3-4e16-ab15-7612665c24e1", source_uri="6861ee45-ebb3-4e16-ab15-7612665c24e1", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Complete target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id="8b16ed2c-a23f-452a-944b-1501e0e1fe33", source_uri="8b16ed2c-a23f-452a-944b-1501e0e1fe33", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376546_745802.wav"]>
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Complete target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id="e9687148-e4e6-496b-bd6d-f78e0725fc44", source_uri="e9687148-e4e6-496b-bd6d-f78e0725fc44", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:52] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:04:53] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Complete target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id="48fb2ea3-41ca-4e7b-a29b-796b764154ca", source_uri="48fb2ea3-41ca-4e7b-a29b-796b764154ca", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:53] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:04:53] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Call to inside_sales 25376546 detected a human
[2014-02-16 12:04:53] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Complete target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id="9b297996-bf4c-4998-b08d-0a827303ce76", source_uri="9b297996-bf4c-4998-b08d-0a827303ce76", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="HUMAN">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:53] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Finished executing controller #<SalesCallsController call=22e2606b-ed31-479e-85f2-830cdbb2bdc7, metadata={:customer_id=>25376546, :customer_type=>"inside_sales", :call_started=>1392570263.921, :answered?=>true}>
[2014-02-16 12:04:53] DEBUG Roster: Selecting 360@10.1.2.87/browser for inside_sales_prospect
[2014-02-16 12:04:53] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:53] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:53] INFO Roster: Updating 360@10.1.2.87/browser to composing
[2014-02-16 12:04:53] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Finished executing controller #<OutboundConnectingController call=22e2606b-ed31-479e-85f2-830cdbb2bdc7, metadata={}>
[2014-02-16 12:04:53] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Connecting customer inside_sales 25376546 to extension 360
[2014-02-16 12:04:54] DEBUG Adhearsion::OutboundCall: f9215d1f-779c-4900-943c-2e2be8f75a3f@: Receiving message: #<Punchblock::Event::End target_call_id="f9215d1f-779c-4900-943c-2e2be8f75a3f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:04:54] INFO Adhearsion::OutboundCall: f9215d1f-779c-4900-943c-2e2be8f75a3f@: Call ended due to hangup
[2014-02-16 12:04:54] DEBUG CallInformation: pop f9215d1f-779c-4900-943c-2e2be8f75a3f
[2014-02-16 12:04:54] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: connecting inside_sales 25376546 to extension 360
[2014-02-16 12:04:54] DEBUG Adhearsion::OutboundCall: 25a13720-e1d8-4f5c-9963-a8ffc5714216@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/usr/share/asterisk/sounds/en/confbridge-join"]>
[2014-02-16 12:04:54] INFO Roster: Updating 360@10.1.2.87/browser to unavailable
[2014-02-16 12:04:54] DEBUG Adhearsion::OutboundCall: 25a13720-e1d8-4f5c-9963-a8ffc5714216@: Receiving message: #<Punchblock::Event::Complete target_call_id="25a13720-e1d8-4f5c-9963-a8ffc5714216", target_mixer_name=nil, component_id="bebfb3cf-8f61-408c-a9e6-a22f501cdfae", source_uri="bebfb3cf-8f61-408c-a9e6-a22f501cdfae", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:04:54] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Joining to #<Celluloid::ActorProxy:0x52d7d357>
[2014-02-16 12:04:54] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="25a13720-e1d8-4f5c-9963-a8ffc5714216", mixer_name=nil, direction=nil, media=nil>
[2014-02-16 12:04:54] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Joined target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="25a13720-e1d8-4f5c-9963-a8ffc5714216", mixer_name=nil>
[2014-02-16 12:04:54] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Joined to call 25a13720-e1d8-4f5c-9963-a8ffc5714216
[2014-02-16 12:04:54] DEBUG Adhearsion::OutboundCall: 25a13720-e1d8-4f5c-9963-a8ffc5714216@: Receiving message: #<Punchblock::Event::Joined target_call_id="25a13720-e1d8-4f5c-9963-a8ffc5714216", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="22e2606b-ed31-479e-85f2-830cdbb2bdc7", mixer_name=nil>
[2014-02-16 12:04:54] INFO Adhearsion::OutboundCall: 25a13720-e1d8-4f5c-9963-a8ffc5714216@: Joined to call 22e2606b-ed31-479e-85f2-830cdbb2bdc7
[2014-02-16 12:04:56] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:56] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:04:57] DEBUG AMI: #disconnect_homeowner_extension(354)
[2014-02-16 12:04:58] DEBUG AMI: #disconnect_homeowner_extension(373)
[2014-02-16 12:04:58] DEBUG AMI: #disconnect_homeowner_extension(319)
[2014-02-16 12:04:58] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/371", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:04:58] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Receiving message: #<Punchblock::Event::Ringing target_call_id="59405a12-2be2-4c14-9425-bd994884a380", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:59] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Receiving message: #<Punchblock::Event::Answered target_call_id="59405a12-2be2-4c14-9425-bd994884a380", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:04:59] INFO Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Executing controller #<AgentWaitingController call=59405a12-2be2-4c14-9425-bd994884a380, metadata={}>
[2014-02-16 12:04:59] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Finished executing controller #<AgentWaitingController call=59405a12-2be2-4c14-9425-bd994884a380, metadata={}>
[2014-02-16 12:04:59] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:04:59] INFO Roster: Updating 354@10.1.2.87/browser to available
[2014-02-16 12:04:59] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:04:59] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:00] INFO Roster: Updating 373@10.1.2.87/browser to unavailable
[2014-02-16 12:05:00] INFO Roster: Updating 371@10.1.2.87/browser to available
[2014-02-16 12:05:00] INFO Roster: Updating 373@10.1.2.87/browser to available
[2014-02-16 12:05:00] INFO Roster: Updating 319@10.1.2.87/browser to available
[2014-02-16 12:05:01] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/357", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:05:01] DEBUG Adhearsion::OutboundCall: 87d717bd-bbf8-4a7e-9283-b38b364b2236@: Receiving message: #<Punchblock::Event::Ringing target_call_id="87d717bd-bbf8-4a7e-9283-b38b364b2236", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:01] DEBUG Adhearsion::OutboundCall: 87d717bd-bbf8-4a7e-9283-b38b364b2236@: Receiving message: #<Punchblock::Event::Answered target_call_id="87d717bd-bbf8-4a7e-9283-b38b364b2236", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:01] INFO Adhearsion::OutboundCall: 87d717bd-bbf8-4a7e-9283-b38b364b2236@: Executing controller #<AgentWaitingController call=87d717bd-bbf8-4a7e-9283-b38b364b2236, metadata={}>
[2014-02-16 12:05:01] DEBUG Adhearsion::OutboundCall: 87d717bd-bbf8-4a7e-9283-b38b364b2236@: Finished executing controller #<AgentWaitingController call=87d717bd-bbf8-4a7e-9283-b38b364b2236, metadata={}>
[2014-02-16 12:05:01] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:05:02] DEBUG QueueWorker: Looking for 3 confirmation_calls calls...
[2014-02-16 12:05:02] DEBUG QueueWorker: confirmation_calls worker received request via Redis with JSON: {"customer_type":"appointment","to":"2537203376","from":"484-482-2479","customer_id":1054819,"name":"Erika Sloan"}
[2014-02-16 12:05:02] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/2537203376", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:05:02] DEBUG CallInformation: PUSH 7767e53f-2123-4a91-8ece-8929d41ebfd6 {:customer_id=>1054819, :customer_type=>:appointment, :call_started=>1392570302.678, :answered?=>false}
[2014-02-16 12:05:02] DEBUG QueueWorker: confirmation_calls worker received request via Redis with JSON: {"customer_type":"appointment","to":"9735190906","from":"908-603-2360","customer_id":728532,"name":"Mario Cueva"}
[2014-02-16 12:05:02] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/9735190906", from="9086032360", timeout=30000, join=nil>
[2014-02-16 12:05:02] DEBUG CallInformation: PUSH ee03d59b-f7d8-48a9-a9d1-a7645b2d2116 {:customer_id=>728532, :customer_type=>:appointment, :call_started=>1392570302.729, :answered?=>false}
[2014-02-16 12:05:02] DEBUG QueueWorker: confirmation_calls worker received request via Redis with JSON: {"customer_type":"appointment","to":"3014376484","from":"240-965-8500","customer_id":1060815,"name":"Joe Waiter"}
[2014-02-16 12:05:02] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/3014376484", from="2409658500", timeout=30000, join=nil>
[2014-02-16 12:05:02] DEBUG CallInformation: PUSH 51c9f153-49aa-4dde-8bdf-438d6279186a {:customer_id=>1060815, :customer_type=>:appointment, :call_started=>1392570302.778, :answered?=>false}
[2014-02-16 12:05:02] DEBUG QueueWorker: Looking for 1 inside_sales_prospect calls...
[2014-02-16 12:05:02] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"5088180393","from":"508-263-9010","customer_id":25376550,"name":"Katie Gendron"}
[2014-02-16 12:05:02] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/5088180393", from="5082639010", timeout=30000, join=nil>
[2014-02-16 12:05:02] DEBUG CallInformation: PUSH d3833d8b-4ebb-472d-b311-82676e1bd7cc {:customer_id=>25376550, :customer_type=>:inside_sales, :call_started=>1392570302.831, :answered?=>false}
[2014-02-16 12:05:04] INFO Roster: Updating 357@10.1.2.87/browser to available
[2014-02-16 12:05:05] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:05:05] DEBUG QueueWorker: Looking for 3 inside_sales_prospect calls...
[2014-02-16 12:05:05] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"2019989708","from":"908-603-2360","customer_id":25376551,"name":"John Prebula"}
[2014-02-16 12:05:05] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/2019989708", from="9086032360", timeout=30000, join=nil>
[2014-02-16 12:05:05] DEBUG CallInformation: PUSH ae48b6e1-827e-43e4-a64d-3be57fc42b58 {:customer_id=>25376551, :customer_type=>:inside_sales, :call_started=>1392570305.687, :answered?=>false}
[2014-02-16 12:05:05] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"6095850196","from":"484-482-2479","customer_id":25376552,"name":"James Meisner"}
[2014-02-16 12:05:05] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/6095850196", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:05:05] DEBUG CallInformation: PUSH dec27f93-afe1-4072-902f-31c75618e12b {:customer_id=>25376552, :customer_type=>:inside_sales, :call_started=>1392570305.732, :answered?=>false}
[2014-02-16 12:05:05] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"6316555538","from":"631-339-7578","customer_id":25376553,"name":"Gil Joyce"}
[2014-02-16 12:05:05] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/6316555538", from="6313397578", timeout=30000, join=nil>
[2014-02-16 12:05:05] DEBUG CallInformation: PUSH c30e1089-281a-430c-8578-63525657d629 {:customer_id=>25376553, :customer_type=>:inside_sales, :call_started=>1392570305.779, :answered?=>false}
[2014-02-16 12:05:08] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::Ringing target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:08] DEBUG QueueWorker: Looking for 3 confirmation_calls calls...
[2014-02-16 12:05:08] DEBUG QueueWorker: confirmation_calls worker received request via Redis with JSON: {"customer_type":"appointment","to":"8568858035","from":"484-482-2479","customer_id":1060119,"name":"Edna Butts"}
[2014-02-16 12:05:08] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/8568858035", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:05:08] DEBUG CallInformation: PUSH d61e0b8b-5dc3-4e56-9685-811d4c5aa21f {:customer_id=>1060119, :customer_type=>:appointment, :call_started=>1392570308.943, :answered?=>false}
[2014-02-16 12:05:08] DEBUG QueueWorker: confirmation_calls worker received request via Redis with JSON: {"customer_type":"appointment","to":"4135944476","from":"508-263-9010","customer_id":1058322,"name":"Pamela Marcus"}
[2014-02-16 12:05:08] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Ringing target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:09] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/4135944476", from="5082639010", timeout=30000, join=nil>
[2014-02-16 12:05:09] DEBUG CallInformation: PUSH e997b984-ecb4-4213-b304-3b3dcbe923c5 {:customer_id=>1058322, :customer_type=>:appointment, :call_started=>1392570309.183, :answered?=>false}
[2014-02-16 12:05:09] DEBUG QueueWorker: confirmation_calls worker received request via Redis with JSON: {"customer_type":"appointment","to":"6312395832","from":"631-339-7578","customer_id":1060288,"name":"Lori Basel"}
[2014-02-16 12:05:09] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/6312395832", from="6313397578", timeout=30000, join=nil>
[2014-02-16 12:05:09] DEBUG CallInformation: PUSH b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0 {:customer_id=>1060288, :customer_type=>:appointment, :call_started=>1392570309.223, :answered?=>false}
[2014-02-16 12:05:09] DEBUG QueueWorker: Looking for 2 inside_sales_prospect calls...
[2014-02-16 12:05:09] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"9146311563","from":"203-659-1220","customer_id":25376554,"name":"James Walter"}
[2014-02-16 12:05:09] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/9146311563", from="2036591220", timeout=30000, join=nil>
[2014-02-16 12:05:09] DEBUG CallInformation: PUSH 426706ec-1a7d-48d4-bd96-1d987d8e286b {:customer_id=>25376554, :customer_type=>:inside_sales, :call_started=>1392570309.309, :answered?=>false}
[2014-02-16 12:05:09] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"3018734403","from":"240-965-8500","customer_id":25376555,"name":"Phil Miller"}
[2014-02-16 12:05:09] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/3018734403", from="2409658500", timeout=30000, join=nil>
[2014-02-16 12:05:09] DEBUG CallInformation: PUSH 0db9cfbc-de85-4d1f-99a1-6d1dd5cd70f0 {:customer_id=>25376555, :customer_type=>:inside_sales, :call_started=>1392570309.351, :answered?=>false}
[2014-02-16 12:05:11] DEBUG Adhearsion::OutboundCall: 426706ec-1a7d-48d4-bd96-1d987d8e286b@: Receiving message: #<Punchblock::Event::Ringing target_call_id="426706ec-1a7d-48d4-bd96-1d987d8e286b", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:11] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:05:11] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:14] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:05:14] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Receiving message: #<Punchblock::Event::Answered target_call_id="7767e53f-2123-4a91-8ece-8929d41ebfd6", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:15] INFO Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Executing controller #<ConfirmationCallsController call=7767e53f-2123-4a91-8ece-8929d41ebfd6, metadata={:customer_id=>1054819, :customer_type=>"appointment", :call_started=>1392570302.678, :answered?=>false}>
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Outgoing call beginning for appointment 1054819
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Receiving message: #<Punchblock::Event::Complete target_call_id="7767e53f-2123-4a91-8ece-8929d41ebfd6", target_mixer_name=nil, component_id="1c6df78e-f6ab-46e3-9267-ad66a5f8fc04", source_uri="1c6df78e-f6ab-46e3-9267-ad66a5f8fc04", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Receiving message: #<Punchblock::Event::Complete target_call_id="7767e53f-2123-4a91-8ece-8929d41ebfd6", target_mixer_name=nil, component_id="1f42f358-71cd-4155-92fe-c24bd2d20767", source_uri="1f42f358-71cd-4155-92fe-c24bd2d20767", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:15] INFO Roster: Updating 354@10.1.2.87/browser to unavailable
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["1054819_327854.wav"]>
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Receiving message: #<Punchblock::Event::Complete target_call_id="7767e53f-2123-4a91-8ece-8929d41ebfd6", target_mixer_name=nil, component_id="241d7dcc-44c7-4699-acee-6b7dedce6134", source_uri="241d7dcc-44c7-4699-acee-6b7dedce6134", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:15] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:05:16] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Receiving message: #<Punchblock::Event::Complete target_call_id="7767e53f-2123-4a91-8ece-8929d41ebfd6", target_mixer_name=nil, component_id="26a508f2-7b8f-45d0-bcfd-28facfe309fb", source_uri="26a508f2-7b8f-45d0-bcfd-28facfe309fb", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:16] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:05:16] INFO Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Call to appointment 1054819 detected a machine
[2014-02-16 12:05:16] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Receiving message: #<Punchblock::Event::Complete target_call_id="7767e53f-2123-4a91-8ece-8929d41ebfd6", target_mixer_name=nil, component_id="0906c84c-fba0-4856-8983-b0f4945215c2", source_uri="0906c84c-fba0-4856-8983-b0f4945215c2", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="MACHINE">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:16] INFO Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Hanging up
[2014-02-16 12:05:16] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:16] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Receiving message: #<Punchblock::Event::End target_call_id="7767e53f-2123-4a91-8ece-8929d41ebfd6", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:16] INFO Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Call ended due to hangup
[2014-02-16 12:05:16] DEBUG Adhearsion::OutboundCall: 7767e53f-2123-4a91-8ece-8929d41ebfd6@: Finished executing controller #<ConfirmationCallsController call=7767e53f-2123-4a91-8ece-8929d41ebfd6, metadata={:customer_id=>1054819, :customer_type=>"appointment", :call_started=>1392570302.678, :answered?=>true}>
[2014-02-16 12:05:16] INFO Adhearsion::Router::Route: Call routing completed. Hanging up now...
[2014-02-16 12:05:16] DEBUG CallInformation: pop 7767e53f-2123-4a91-8ece-8929d41ebfd6
[2014-02-16 12:05:17] DEBUG AMI: #disconnect_agent_extension(373)
[2014-02-16 12:05:17] INFO Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Hanging up
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Receiving message: #<Punchblock::Event::End target_call_id="307e291c-ae90-4754-b128-21e6237b4eda", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:17] INFO Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Call ended due to hangup
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: 307e291c-ae90-4754-b128-21e6237b4eda@: Agent 373 call ended
[2014-02-16 12:05:17] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:05:17] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:17] INFO Roster: Updating 373@10.1.2.87/browser to unavailable
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Answered target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:17] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Executing controller #<SalesCallsController call=ae48b6e1-827e-43e4-a64d-3be57fc42b58, metadata={:customer_id=>25376551, :customer_type=>"inside_sales", :call_started=>1392570305.687, :answered?=>false}>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Outgoing call beginning for inside_sales 25376551
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Complete target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id="6c3653fd-54f2-4745-92fe-a2bc3687ba0f", source_uri="6c3653fd-54f2-4745-92fe-a2bc3687ba0f", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Complete target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id="fecb60e5-d606-48b8-8bf7-fd5579a5ab06", source_uri="fecb60e5-d606-48b8-8bf7-fd5579a5ab06", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376551_673808.wav"]>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:05:17] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Complete target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id="d06d4fc4-1399-418f-b466-dd1954673394", source_uri="d06d4fc4-1399-418f-b466-dd1954673394", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Complete target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id="123133c9-1c01-42ae-b0b6-06affc46054c", source_uri="123133c9-1c01-42ae-b0b6-06affc46054c", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:05:19] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Call to inside_sales 25376551 detected a human
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Complete target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id="8f94840d-34f0-4c89-8455-cc414b468ea5", source_uri="8f94840d-34f0-4c89-8455-cc414b468ea5", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="HUMAN">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Finished executing controller #<SalesCallsController call=ae48b6e1-827e-43e4-a64d-3be57fc42b58, metadata={:customer_id=>25376551, :customer_type=>"inside_sales", :call_started=>1392570305.687, :answered?=>true}>
[2014-02-16 12:05:19] DEBUG Roster: Selecting 371@10.1.2.87/browser for inside_sales_prospect
[2014-02-16 12:05:19] INFO Roster: Updating 371@10.1.2.87/browser to composing
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Finished executing controller #<OutboundConnectingController call=ae48b6e1-827e-43e4-a64d-3be57fc42b58, metadata={}>
[2014-02-16 12:05:19] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Connecting customer inside_sales 25376551 to extension 371
[2014-02-16 12:05:19] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: connecting inside_sales 25376551 to extension 371
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/usr/share/asterisk/sounds/en/confbridge-join"]>
[2014-02-16 12:05:19] INFO Roster: Updating 371@10.1.2.87/browser to unavailable
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Receiving message: #<Punchblock::Event::Complete target_call_id="59405a12-2be2-4c14-9425-bd994884a380", target_mixer_name=nil, component_id="0c861d6b-8c26-4b61-b2ff-01e2324668c5", source_uri="0c861d6b-8c26-4b61-b2ff-01e2324668c5", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:19] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Joining to #<Celluloid::ActorProxy:0x483123bc>
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Executing command #<Punchblock::Command::Join target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="59405a12-2be2-4c14-9425-bd994884a380", mixer_name=nil, direction=nil, media=nil>
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Joined target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="59405a12-2be2-4c14-9425-bd994884a380", mixer_name=nil>
[2014-02-16 12:05:19] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Joined to call 59405a12-2be2-4c14-9425-bd994884a380
[2014-02-16 12:05:19] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Receiving message: #<Punchblock::Event::Joined target_call_id="59405a12-2be2-4c14-9425-bd994884a380", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="ae48b6e1-827e-43e4-a64d-3be57fc42b58", mixer_name=nil>
[2014-02-16 12:05:19] INFO Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Joined to call ae48b6e1-827e-43e4-a64d-3be57fc42b58
[2014-02-16 12:05:20] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:05:20] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::Answered target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:21] INFO Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Executing controller #<ConfirmationCallsController call=b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0, metadata={:customer_id=>1060288, :customer_type=>"appointment", :call_started=>1392570309.223, :answered?=>false}>
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Outgoing call beginning for appointment 1060288
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::Complete target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id="faa66dd2-6f0c-422e-84fc-8b80b48c26b1", source_uri="faa66dd2-6f0c-422e-84fc-8b80b48c26b1", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::Complete target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id="8ac79593-7b4d-49de-bc56-573c0312f0d4", source_uri="8ac79593-7b4d-49de-bc56-573c0312f0d4", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["1060288_860619.wav"]>
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::Complete target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id="97ef9c2d-3e60-4b57-8a30-a4c98779520b", source_uri="97ef9c2d-3e60-4b57-8a30-a4c98779520b", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:21] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:05:23] DEBUG QueueWorker: Looking for 0 confirmation_calls calls...
[2014-02-16 12:05:23] DEBUG QueueWorker: Looking for 1 inside_sales_prospect calls...
[2014-02-16 12:05:23] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"6092941221","from":"484-482-2479","customer_id":25376556,"name":"Jerry Geary"}
[2014-02-16 12:05:23] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/6092941221", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:05:23] DEBUG CallInformation: PUSH 0f310f63-4ae4-4408-8f5f-e34a627abafa {:customer_id=>25376556, :customer_type=>:inside_sales, :call_started=>1392570323.681, :answered?=>false}
[2014-02-16 12:05:23] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::Complete target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id="2f14fb6c-f349-41a9-b6db-6d733f28ba5c", source_uri="2f14fb6c-f349-41a9-b6db-6d733f28ba5c", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:23] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:05:23] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::Complete target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id="7903e0c8-4b9d-42d6-935d-ece9052c5235", source_uri="7903e0c8-4b9d-42d6-935d-ece9052c5235", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="HUMAN">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:23] INFO Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Call to appointment 1060288 detected a human
[2014-02-16 12:05:23] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Finished executing controller #<ConfirmationCallsController call=b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0, metadata={:customer_id=>1060288, :customer_type=>"appointment", :call_started=>1392570309.223, :answered?=>true}>
[2014-02-16 12:05:23] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, voice=nil, interrupt_on=nil, start_offset=nil, start_paused=nil, repeat_interval=nil, repeat_times=nil, max_time=nil, renderer=nil, render_documents=[#<Punchblock::Component::Output::Document target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, url=nil, content_type="application/ssml+xml", value=<speak version="1.0" xml:lang="en-US" xmlns="http://www.w3.org/2001/10/synthesis"><audio src="/var/lib/asterisk/sounds/custom/no_agent_today_msg"/></speak>>]>
[2014-02-16 12:05:26] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:26] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:27] DEBUG Adhearsion::OutboundCall: 0f310f63-4ae4-4408-8f5f-e34a627abafa@: Receiving message: #<Punchblock::Event::Ringing target_call_id="0f310f63-4ae4-4408-8f5f-e34a627abafa", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:29] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:29] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:30] DEBUG AMI: #disconnect_homeowner_extension(374)
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::Answered target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:30] INFO Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Executing controller #<SalesCallsController call=dec27f93-afe1-4072-902f-31c75618e12b, metadata={:customer_id=>25376552, :customer_type=>"inside_sales", :call_started=>1392570305.732, :answered?=>false}>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Outgoing call beginning for inside_sales 25376552
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::Complete target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id="a993519a-7917-4d89-9ad8-7e89f912206a", source_uri="a993519a-7917-4d89-9ad8-7e89f912206a", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::Complete target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id="29668368-7fc4-42b4-b9fe-e61173b4b4a5", source_uri="29668368-7fc4-42b4-b9fe-e61173b4b4a5", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376552_491020.wav"]>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::Complete target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id="37c15786-64d1-4a39-b6de-c40c4defa87b", source_uri="37c15786-64d1-4a39-b6de-c40c4defa87b", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:30] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::Complete target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id="e4ff6417-a823-4a8a-bfd1-d3b8894074a0", source_uri="e4ff6417-a823-4a8a-bfd1-d3b8894074a0", domain=nil, transport=nil, reason=#<Punchblock::Component::Output::Complete::Finish target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:finish>, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::Complete target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id="e0cfa9d9-0099-48cd-8ce9-e278a3326c60", source_uri="e0cfa9d9-0099-48cd-8ce9-e278a3326c60", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:05:32] INFO Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Call to inside_sales 25376552 detected a machine
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::Complete target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id="353a1495-341a-4ca5-a449-5459feda771b", source_uri="353a1495-341a-4ca5-a449-5459feda771b", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="MACHINE">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:32] INFO Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Hung up on answering machine at inside_sales 25376552
[2014-02-16 12:05:32] INFO Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Hanging up
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Finished executing controller #<SalesCallsController call=dec27f93-afe1-4072-902f-31c75618e12b, metadata={:customer_id=>25376552, :customer_type=>"inside_sales", :call_started=>1392570305.732, :answered?=>true}>
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Receiving message: #<Punchblock::Event::End target_call_id="dec27f93-afe1-4072-902f-31c75618e12b", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:32] INFO Adhearsion::OutboundCall: dec27f93-afe1-4072-902f-31c75618e12b@: Call ended due to hangup
[2014-02-16 12:05:32] INFO Adhearsion::Router::Route: Call routing completed. Hanging up now...
[2014-02-16 12:05:32] DEBUG CallInformation: pop dec27f93-afe1-4072-902f-31c75618e12b
[2014-02-16 12:05:32] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:32] DEBUG QueueWorker: Looking for 1 inside_sales_prospect calls...
[2014-02-16 12:05:32] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"4048197183","from":"240-965-8500","customer_id":25376557,"name":"Mary To"}
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/4048197183", from="2409658500", timeout=30000, join=nil>
[2014-02-16 12:05:32] DEBUG CallInformation: PUSH 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676 {:customer_id=>25376557, :customer_type=>:inside_sales, :call_started=>1392570332.724, :answered?=>false}
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: ee03d59b-f7d8-48a9-a9d1-a7645b2d2116@: Receiving message: #<Punchblock::Event::End target_call_id="ee03d59b-f7d8-48a9-a9d1-a7645b2d2116", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:32] INFO Adhearsion::OutboundCall: ee03d59b-f7d8-48a9-a9d1-a7645b2d2116@: Call ended due to hangup
[2014-02-16 12:05:32] DEBUG CallInformation: pop ee03d59b-f7d8-48a9-a9d1-a7645b2d2116
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: 51c9f153-49aa-4dde-8bdf-438d6279186a@: Receiving message: #<Punchblock::Event::End target_call_id="51c9f153-49aa-4dde-8bdf-438d6279186a", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:32] INFO Adhearsion::OutboundCall: 51c9f153-49aa-4dde-8bdf-438d6279186a@: Call ended due to hangup
[2014-02-16 12:05:32] DEBUG CallInformation: pop 51c9f153-49aa-4dde-8bdf-438d6279186a
[2014-02-16 12:05:32] DEBUG Adhearsion::OutboundCall: d3833d8b-4ebb-472d-b311-82676e1bd7cc@: Receiving message: #<Punchblock::Event::End target_call_id="d3833d8b-4ebb-472d-b311-82676e1bd7cc", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:32] INFO Adhearsion::OutboundCall: d3833d8b-4ebb-472d-b311-82676e1bd7cc@: Call ended due to hangup
[2014-02-16 12:05:32] DEBUG CallInformation: pop d3833d8b-4ebb-472d-b311-82676e1bd7cc
[2014-02-16 12:05:33] INFO Roster: Updating 374@10.1.2.87/browser to available
[2014-02-16 12:05:33] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/331", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:05:33] DEBUG Adhearsion::OutboundCall: 986a7d79-911d-43ec-bde3-089595a1560d@: Receiving message: #<Punchblock::Event::Ringing target_call_id="986a7d79-911d-43ec-bde3-089595a1560d", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:33] DEBUG Adhearsion::OutboundCall: 986a7d79-911d-43ec-bde3-089595a1560d@: Receiving message: #<Punchblock::Event::Answered target_call_id="986a7d79-911d-43ec-bde3-089595a1560d", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:33] INFO Adhearsion::OutboundCall: 986a7d79-911d-43ec-bde3-089595a1560d@: Executing controller #<AgentWaitingController call=986a7d79-911d-43ec-bde3-089595a1560d, metadata={}>
[2014-02-16 12:05:33] DEBUG Adhearsion::OutboundCall: 986a7d79-911d-43ec-bde3-089595a1560d@: Finished executing controller #<AgentWaitingController call=986a7d79-911d-43ec-bde3-089595a1560d, metadata={}>
[2014-02-16 12:05:33] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:05:35] INFO Roster: Updating 331@10.1.2.87/browser to available
[2014-02-16 12:05:35] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:35] DEBUG QueueWorker: Looking for 6 inside_sales_prospect calls...
[2014-02-16 12:05:35] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"9087821780","from":"908-603-2360","customer_id":25376558,"name":"Jackie Majorossy"}
[2014-02-16 12:05:35] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/9087821780", from="9086032360", timeout=30000, join=nil>
[2014-02-16 12:05:35] DEBUG CallInformation: PUSH a561288e-7651-4273-9fda-44d46b70208e {:customer_id=>25376558, :customer_type=>:inside_sales, :call_started=>1392570335.668, :answered?=>false}
[2014-02-16 12:05:35] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"4105263586","from":"240-965-8500","customer_id":25376559,"name":"Joe Eline"}
[2014-02-16 12:05:35] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/4105263586", from="2409658500", timeout=30000, join=nil>
[2014-02-16 12:05:35] DEBUG CallInformation: PUSH dae44ffe-61c1-4965-8ac7-a418e898d33c {:customer_id=>25376559, :customer_type=>:inside_sales, :call_started=>1392570335.707, :answered?=>false}
[2014-02-16 12:05:35] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"9739030107","from":"908-603-2360","customer_id":25376560,"name":"Basil Holowach"}
[2014-02-16 12:05:35] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/9739030107", from="9086032360", timeout=30000, join=nil>
[2014-02-16 12:05:35] DEBUG CallInformation: PUSH ca89adc0-dd94-43b0-87c3-a13b2932717c {:customer_id=>25376560, :customer_type=>:inside_sales, :call_started=>1392570335.745, :answered?=>false}
[2014-02-16 12:05:35] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"7327878407","from":"908-603-2360","customer_id":25376561,"name":"Josie XXX"}
[2014-02-16 12:05:35] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/7327878407", from="9086032360", timeout=30000, join=nil>
[2014-02-16 12:05:35] DEBUG CallInformation: PUSH dc61fd24-4ccf-451c-a441-677f40cb817d {:customer_id=>25376561, :customer_type=>:inside_sales, :call_started=>1392570335.785, :answered?=>false}
[2014-02-16 12:05:35] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"6093156234","from":"484-482-2479","customer_id":25376562,"name":"Todd Frankowski"}
[2014-02-16 12:05:35] DEBUG Adhearsion::OutboundCall: c30e1089-281a-430c-8578-63525657d629@: Receiving message: #<Punchblock::Event::End target_call_id="c30e1089-281a-430c-8578-63525657d629", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:35] INFO Adhearsion::OutboundCall: c30e1089-281a-430c-8578-63525657d629@: Call ended due to hangup
[2014-02-16 12:05:35] DEBUG CallInformation: pop c30e1089-281a-430c-8578-63525657d629
[2014-02-16 12:05:35] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/6093156234", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:05:35] DEBUG CallInformation: PUSH b186328c-07f6-4d30-beae-9984ea8ca7e0 {:customer_id=>25376562, :customer_type=>:inside_sales, :call_started=>1392570335.824, :answered?=>false}
[2014-02-16 12:05:35] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"8604544068","from":"203-659-1220","customer_id":25376563,"name":"Jenna Miller"}
[2014-02-16 12:05:36] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/8604544068", from="2036591220", timeout=30000, join=nil>
[2014-02-16 12:05:36] DEBUG CallInformation: PUSH b708d6ba-129e-4921-a3c7-92d1a2ca039e {:customer_id=>25376563, :customer_type=>:inside_sales, :call_started=>1392570336.154, :answered?=>false}
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: 232d2f70-566d-47ae-82d9-eb2a4d532e0a@: Receiving message: #<Punchblock::Event::End target_call_id="232d2f70-566d-47ae-82d9-eb2a4d532e0a", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="0">
[2014-02-16 12:05:37] INFO Adhearsion::OutboundCall: 232d2f70-566d-47ae-82d9-eb2a4d532e0a@: Call ended due to hangup
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Receiving message: #<Punchblock::Event::Unjoined target_call_id="6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="232d2f70-566d-47ae-82d9-eb2a4d532e0a", mixer_name=nil>
[2014-02-16 12:05:37] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Unjoined from call 232d2f70-566d-47ae-82d9-eb2a4d532e0a
[2014-02-16 12:05:37] WARN Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 232d2f70-566d-47ae-82d9-eb2a4d532e0a: #<Punchblock::Event::Unjoined target_call_id="232d2f70-566d-47ae-82d9-eb2a4d532e0a", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d", mixer_name=nil>
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: 232d2f70-566d-47ae-82d9-eb2a4d532e0a@: Agent 363 call ended
[2014-02-16 12:05:37] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Call between inside_sales 25376541 and extension 363 ended
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/363", from="PHRG <1234>", timeout=nil, join=nil>
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Finished executing controller #<OutboundCustomersController call=6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d, metadata={}>
[2014-02-16 12:05:37] INFO Adhearsion::Router::Route: Call routing completed. Hanging up now...
[2014-02-16 12:05:37] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Hanging up
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Receiving message: #<Punchblock::Event::End target_call_id="6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:37] INFO Adhearsion::OutboundCall: 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d@: Call ended due to hangup
[2014-02-16 12:05:37] DEBUG CallInformation: pop 6d0cd0bd-83bb-4a84-9eab-82f43aef9e7d
[2014-02-16 12:05:37] DEBUG Adhearsion::OutboundCall: 470971c5-d012-4ab4-803c-15a2e263f209@: Receiving message: #<Punchblock::Event::Ringing target_call_id="470971c5-d012-4ab4-803c-15a2e263f209", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:38] DEBUG Adhearsion::OutboundCall: 470971c5-d012-4ab4-803c-15a2e263f209@: Receiving message: #<Punchblock::Event::Answered target_call_id="470971c5-d012-4ab4-803c-15a2e263f209", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:38] INFO Adhearsion::OutboundCall: 470971c5-d012-4ab4-803c-15a2e263f209@: Executing controller #<AgentWaitingController call=470971c5-d012-4ab4-803c-15a2e263f209, metadata={}>
[2014-02-16 12:05:38] DEBUG Adhearsion::OutboundCall: 470971c5-d012-4ab4-803c-15a2e263f209@: Finished executing controller #<AgentWaitingController call=470971c5-d012-4ab4-803c-15a2e263f209, metadata={}>
[2014-02-16 12:05:38] INFO Adhearsion::Router::Route: Call routing completed, keeping the call alive at controller/router request.
[2014-02-16 12:05:38] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Receiving message: #<Punchblock::Event::Ringing target_call_id="a561288e-7651-4273-9fda-44d46b70208e", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:38] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:38] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Receiving message: #<Punchblock::Event::Ringing target_call_id="dae44ffe-61c1-4965-8ac7-a418e898d33c", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:38] DEBUG QueueWorker: Looking for 1 inside_sales_prospect calls...
[2014-02-16 12:05:38] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"2673494214","from":"484-482-2479","customer_id":25376564,"name":"Ada Torres"}
[2014-02-16 12:05:38] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/2673494214", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:05:38] DEBUG Adhearsion::OutboundCall: d61e0b8b-5dc3-4e56-9685-811d4c5aa21f@: Receiving message: #<Punchblock::Event::End target_call_id="d61e0b8b-5dc3-4e56-9685-811d4c5aa21f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:38] INFO Adhearsion::OutboundCall: d61e0b8b-5dc3-4e56-9685-811d4c5aa21f@: Call ended due to hangup
[2014-02-16 12:05:38] DEBUG CallInformation: pop d61e0b8b-5dc3-4e56-9685-811d4c5aa21f
[2014-02-16 12:05:38] DEBUG CallInformation: PUSH a2e0749a-2d5e-4255-b37f-7726029dc3ae {:customer_id=>25376564, :customer_type=>:inside_sales, :call_started=>1392570338.956, :answered?=>false}
[2014-02-16 12:05:39] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Receiving message: #<Punchblock::Event::Ringing target_call_id="dc61fd24-4ccf-451c-a441-677f40cb817d", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:39] DEBUG Adhearsion::OutboundCall: e997b984-ecb4-4213-b304-3b3dcbe923c5@: Receiving message: #<Punchblock::Event::End target_call_id="e997b984-ecb4-4213-b304-3b3dcbe923c5", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:39] INFO Adhearsion::OutboundCall: e997b984-ecb4-4213-b304-3b3dcbe923c5@: Call ended due to hangup
[2014-02-16 12:05:39] DEBUG CallInformation: pop e997b984-ecb4-4213-b304-3b3dcbe923c5
[2014-02-16 12:05:39] DEBUG AMI: #disconnect_homeowner_extension(363)
[2014-02-16 12:05:39] DEBUG Adhearsion::OutboundCall: 426706ec-1a7d-48d4-bd96-1d987d8e286b@: Receiving message: #<Punchblock::Event::End target_call_id="426706ec-1a7d-48d4-bd96-1d987d8e286b", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:39] INFO Adhearsion::OutboundCall: 426706ec-1a7d-48d4-bd96-1d987d8e286b@: Call ended due to hangup
[2014-02-16 12:05:39] DEBUG CallInformation: pop 426706ec-1a7d-48d4-bd96-1d987d8e286b
[2014-02-16 12:05:39] DEBUG Adhearsion::OutboundCall: 0db9cfbc-de85-4d1f-99a1-6d1dd5cd70f0@: Receiving message: #<Punchblock::Event::End target_call_id="0db9cfbc-de85-4d1f-99a1-6d1dd5cd70f0", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:39] INFO Adhearsion::OutboundCall: 0db9cfbc-de85-4d1f-99a1-6d1dd5cd70f0@: Call ended due to hangup
[2014-02-16 12:05:39] DEBUG CallInformation: pop 0db9cfbc-de85-4d1f-99a1-6d1dd5cd70f0
[2014-02-16 12:05:40] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Receiving message: #<Punchblock::Event::Ringing target_call_id="b708d6ba-129e-4921-a3c7-92d1a2ca039e", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:41] INFO Roster: Updating 363@10.1.2.87/browser to available
[2014-02-16 12:05:41] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:41] DEBUG QueueWorker: Looking for 4 inside_sales_prospect calls...
[2014-02-16 12:05:41] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"9084286520","from":"908-603-2360","customer_id":25376565,"name":"Janki Patel"}
[2014-02-16 12:05:41] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/9084286520", from="9086032360", timeout=30000, join=nil>
[2014-02-16 12:05:41] DEBUG CallInformation: PUSH 8abe2d1b-3a75-43ac-9ef8-3bc484392796 {:customer_id=>25376565, :customer_type=>:inside_sales, :call_started=>1392570341.675, :answered?=>false}
[2014-02-16 12:05:41] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"9147601124","from":"203-659-1220","customer_id":25376566,"name":"Joe Pandolfo"}
[2014-02-16 12:05:41] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/9147601124", from="2036591220", timeout=30000, join=nil>
[2014-02-16 12:05:41] DEBUG CallInformation: PUSH 5d1a5041-e4bb-4d5b-9a71-768936151efb {:customer_id=>25376566, :customer_type=>:inside_sales, :call_started=>1392570341.717, :answered?=>false}
[2014-02-16 12:05:41] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"4843211132","from":"484-482-2479","customer_id":25376567,"name":"Oge Moronco"}
[2014-02-16 12:05:41] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/4843211132", from="4844822479", timeout=30000, join=nil>
[2014-02-16 12:05:41] DEBUG CallInformation: PUSH de4791cf-1329-48ea-9ac5-e866a9be6b75 {:customer_id=>25376567, :customer_type=>:inside_sales, :call_started=>1392570341.759, :answered?=>false}
[2014-02-16 12:05:41] DEBUG QueueWorker: inside_sales_prospect worker received request via Redis with JSON: {"customer_type":"inside_sales","to":"9087555983","from":"908-603-2360","customer_id":25376568,"name":"Mike Flaherty"}
[2014-02-16 12:05:41] DEBUG Adhearsion::OutboundCall: @: Executing command #<Punchblock::Command::Dial target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, to="SIP/sip01/9087555983", from="9086032360", timeout=30000, join=nil>
[2014-02-16 12:05:41] DEBUG CallInformation: PUSH 39185a54-f9ce-4fad-84f8-9c04372736ef {:customer_id=>25376568, :customer_type=>:inside_sales, :call_started=>1392570341.799, :answered?=>false}
[2014-02-16 12:05:44] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:44] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:46] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Receiving message: #<Punchblock::Event::Ringing target_call_id="39185a54-f9ce-4fad-84f8-9c04372736ef", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:47] DEBUG QueueWorker: Looking for -1 confirmation_calls calls...
[2014-02-16 12:05:47] DEBUG QueueWorker: Looking for 0 inside_sales_prospect calls...
[2014-02-16 12:05:53] DEBUG Adhearsion::OutboundCall: 0f310f63-4ae4-4408-8f5f-e34a627abafa@: Receiving message: #<Punchblock::Event::End target_call_id="0f310f63-4ae4-4408-8f5f-e34a627abafa", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:53] INFO Adhearsion::OutboundCall: 0f310f63-4ae4-4408-8f5f-e34a627abafa@: Call ended due to hangup
[2014-02-16 12:05:53] DEBUG CallInformation: pop 0f310f63-4ae4-4408-8f5f-e34a627abafa
[2014-02-16 12:05:55] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Receiving message: #<Punchblock::Event::Answered target_call_id="b708d6ba-129e-4921-a3c7-92d1a2ca039e", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:55] INFO Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Executing controller #<SalesCallsController call=b708d6ba-129e-4921-a3c7-92d1a2ca039e, metadata={:customer_id=>25376563, :customer_type=>"inside_sales", :call_started=>1392570336.154, :answered?=>false}>
[2014-02-16 12:05:55] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Outgoing call beginning for inside_sales 25376563
[2014-02-16 12:05:55] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Receiving message: #<Punchblock::Event::Complete target_call_id="b708d6ba-129e-4921-a3c7-92d1a2ca039e", target_mixer_name=nil, component_id="dbcc771a-254b-4ccc-b126-f8cd0e1c16c7", source_uri="dbcc771a-254b-4ccc-b126-f8cd0e1c16c7", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Receiving message: #<Punchblock::Event::Complete target_call_id="b708d6ba-129e-4921-a3c7-92d1a2ca039e", target_mixer_name=nil, component_id="d39553f9-8ed4-43c6-bc20-c3c226cec12e", source_uri="d39553f9-8ed4-43c6-bc20-c3c226cec12e", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Receiving message: #<Punchblock::Event::Answered target_call_id="5d1a5041-e4bb-4d5b-9a71-768936151efb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:56] INFO Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Executing controller #<SalesCallsController call=5d1a5041-e4bb-4d5b-9a71-768936151efb, metadata={:customer_id=>25376566, :customer_type=>"inside_sales", :call_started=>1392570341.717, :answered?=>false}>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Outgoing call beginning for inside_sales 25376566
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Receiving message: #<Punchblock::Event::Complete target_call_id="5d1a5041-e4bb-4d5b-9a71-768936151efb", target_mixer_name=nil, component_id="78bd4fb0-2074-4066-b350-b46fdf780d57", source_uri="78bd4fb0-2074-4066-b350-b46fdf780d57", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Receiving message: #<Punchblock::Event::Complete target_call_id="5d1a5041-e4bb-4d5b-9a71-768936151efb", target_mixer_name=nil, component_id="fbfb9748-8079-4f7a-af66-55ee1955eff2", source_uri="fbfb9748-8079-4f7a-af66-55ee1955eff2", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Receiving message: #<Punchblock::Event::Answered target_call_id="dc61fd24-4ccf-451c-a441-677f40cb817d", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:56] INFO Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Executing controller #<SalesCallsController call=dc61fd24-4ccf-451c-a441-677f40cb817d, metadata={:customer_id=>25376561, :customer_type=>"inside_sales", :call_started=>1392570335.785, :answered?=>false}>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Outgoing call beginning for inside_sales 25376561
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Receiving message: #<Punchblock::Event::Complete target_call_id="dc61fd24-4ccf-451c-a441-677f40cb817d", target_mixer_name=nil, component_id="ee2518e8-3db9-4a38-b34e-1dd99260dc8f", source_uri="ee2518e8-3db9-4a38-b34e-1dd99260dc8f", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:56] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Receiving message: #<Punchblock::Event::Complete target_call_id="dc61fd24-4ccf-451c-a441-677f40cb817d", target_mixer_name=nil, component_id="73b56460-aa0a-4585-9ed4-2aa34d3ba368", source_uri="73b56460-aa0a-4585-9ed4-2aa34d3ba368", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Receiving message: #<Punchblock::Event::Answered target_call_id="39185a54-f9ce-4fad-84f8-9c04372736ef", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:57] INFO Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Executing controller #<SalesCallsController call=39185a54-f9ce-4fad-84f8-9c04372736ef, metadata={:customer_id=>25376568, :customer_type=>"inside_sales", :call_started=>1392570341.799, :answered?=>false}>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Outgoing call beginning for inside_sales 25376568
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Receiving message: #<Punchblock::Event::Complete target_call_id="39185a54-f9ce-4fad-84f8-9c04372736ef", target_mixer_name=nil, component_id="bbf50781-a95d-4991-a144-9f68c8a77fa2", source_uri="bbf50781-a95d-4991-a144-9f68c8a77fa2", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Receiving message: #<Punchblock::Event::Complete target_call_id="39185a54-f9ce-4fad-84f8-9c04372736ef", target_mixer_name=nil, component_id="fb52a54d-3738-4beb-938d-5d4ca1c2ab41", source_uri="fb52a54d-3738-4beb-938d-5d4ca1c2ab41", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Receiving message: #<Punchblock::Event::Answered target_call_id="a561288e-7651-4273-9fda-44d46b70208e", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:57] INFO Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Executing controller #<SalesCallsController call=a561288e-7651-4273-9fda-44d46b70208e, metadata={:customer_id=>25376558, :customer_type=>"inside_sales", :call_started=>1392570335.668, :answered?=>false}>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Outgoing call beginning for inside_sales 25376558
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: Receiving message: #<Punchblock::Event::End target_call_id="b91a082e-9f4b-4bc4-8933-5c91695902cb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="0">
[2014-02-16 12:05:57] INFO Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: Call ended due to hangup
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::Unjoined target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="b91a082e-9f4b-4bc4-8933-5c91695902cb", mixer_name=nil>
[2014-02-16 12:05:57] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Unjoined from call b91a082e-9f4b-4bc4-8933-5c91695902cb
[2014-02-16 12:05:57] WARN Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call b91a082e-9f4b-4bc4-8933-5c91695902cb: #<Punchblock::Event::Unjoined target_call_id="b91a082e-9f4b-4bc4-8933-5c91695902cb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="d2b588b8-6ec8-4842-92ef-3426fe53e74f", mixer_name=nil>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: Agent 301 call ended
[2014-02-16 12:05:57] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Call between inside_sales 25376547 and extension 301 ended
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Receiving message: #<Punchblock::Event::Complete target_call_id="a561288e-7651-4273-9fda-44d46b70208e", target_mixer_name=nil, component_id="65eb45cb-62c9-419c-b070-a27be6268de8", source_uri="65eb45cb-62c9-419c-b070-a27be6268de8", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:57] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Receiving message: #<Punchblock::Event::Complete target_call_id="a561288e-7651-4273-9fda-44d46b70208e", target_mixer_name=nil, component_id="4ae2c03b-52c0-4171-a347-571a79123130", source_uri="4ae2c03b-52c0-4171-a347-571a79123130", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:58] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::End target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:58] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Call ended due to hangup
[2014-02-16 12:05:58] DEBUG Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Receiving message: #<Punchblock::Event::Unjoined target_call_id="22e2606b-ed31-479e-85f2-830cdbb2bdc7", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="25a13720-e1d8-4f5c-9963-a8ffc5714216", mixer_name=nil>
[2014-02-16 12:05:58] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Unjoined from call 25a13720-e1d8-4f5c-9963-a8ffc5714216
[2014-02-16 12:05:58] DEBUG Adhearsion::OutboundCall: 25a13720-e1d8-4f5c-9963-a8ffc5714216@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/usr/share/asterisk/sounds/en/confbridge-leave"]>
[2014-02-16 12:05:58] DEBUG CallInformation: pop 22e2606b-ed31-479e-85f2-830cdbb2bdc7
[2014-02-16 12:05:59] INFO Adhearsion::OutboundCall: 22e2606b-ed31-479e-85f2-830cdbb2bdc7@: Call between inside_sales 25376546 and extension 360 ended
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 25a13720-e1d8-4f5c-9963-a8ffc5714216@: Receiving message: #<Punchblock::Event::Complete target_call_id="25a13720-e1d8-4f5c-9963-a8ffc5714216", target_mixer_name=nil, component_id="34f41298-cfe5-470f-86e7-8b521a7e1bc4", source_uri="34f41298-cfe5-470f-86e7-8b521a7e1bc4", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Receiving message: #<Punchblock::Event::Answered target_call_id="5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:05:59] INFO Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Executing controller #<SalesCallsController call=5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676, metadata={:customer_id=>25376557, :customer_type=>"inside_sales", :call_started=>1392570332.724, :answered?=>false}>
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Outgoing call beginning for inside_sales 25376557
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Receiving message: #<Punchblock::Event::Complete target_call_id="5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676", target_mixer_name=nil, component_id="f18e348d-ed74-49bf-a5ca-28fdf38a6886", source_uri="f18e348d-ed74-49bf-a5ca-28fdf38a6886", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Receiving message: #<Punchblock::Event::Complete target_call_id="5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676", target_mixer_name=nil, component_id="9c7a7cb9-9b9d-4496-bcb5-5819861522fb", source_uri="9c7a7cb9-9b9d-4496-bcb5-5819861522fb", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 9aea85be-7ecb-4aeb-a8dc-5e3ce2c52f4e@: Receiving message: #<Punchblock::Event::End target_call_id="9aea85be-7ecb-4aeb-a8dc-5e3ce2c52f4e", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:05:59] INFO Adhearsion::OutboundCall: 9aea85be-7ecb-4aeb-a8dc-5e3ce2c52f4e@: Call ended due to hangup
[2014-02-16 12:05:59] DEBUG Adhearsion::OutboundCall: 9aea85be-7ecb-4aeb-a8dc-5e3ce2c52f4e@: Agent 374 call ended
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Receiving message: #<Punchblock::Event::Answered target_call_id="dae44ffe-61c1-4965-8ac7-a418e898d33c", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:06:01] INFO Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Executing controller #<SalesCallsController call=dae44ffe-61c1-4965-8ac7-a418e898d33c, metadata={:customer_id=>25376559, :customer_type=>"inside_sales", :call_started=>1392570335.707, :answered?=>false}>
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Outgoing call beginning for inside_sales 25376559
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Receiving message: #<Punchblock::Event::Complete target_call_id="dae44ffe-61c1-4965-8ac7-a418e898d33c", target_mixer_name=nil, component_id="fad1fa15-a1c4-4cbd-b139-92f28db1a415", source_uri="fad1fa15-a1c4-4cbd-b139-92f28db1a415", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Receiving message: #<Punchblock::Event::Complete target_call_id="dae44ffe-61c1-4965-8ac7-a418e898d33c", target_mixer_name=nil, component_id="85450f6d-1061-46ff-809f-10a226681c03", source_uri="85450f6d-1061-46ff-809f-10a226681c03", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Receiving message: #<Punchblock::Event::End target_call_id="d2b588b8-6ec8-4842-92ef-3426fe53e74f", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:01] INFO Adhearsion::OutboundCall: d2b588b8-6ec8-4842-92ef-3426fe53e74f@: Call ended due to hangup
[2014-02-16 12:06:01] DEBUG CallInformation: pop d2b588b8-6ec8-4842-92ef-3426fe53e74f
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Receiving message: #<Punchblock::Event::End target_call_id="59405a12-2be2-4c14-9425-bd994884a380", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="0">
[2014-02-16 12:06:01] INFO Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Call ended due to hangup
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::Unjoined target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="59405a12-2be2-4c14-9425-bd994884a380", mixer_name=nil>
[2014-02-16 12:06:01] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Unjoined from call 59405a12-2be2-4c14-9425-bd994884a380
[2014-02-16 12:06:01] WARN Adhearsion::PunchblockPlugin::Initializer: Event received for inactive call 59405a12-2be2-4c14-9425-bd994884a380: #<Punchblock::Event::Unjoined target_call_id="59405a12-2be2-4c14-9425-bd994884a380", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, call_uri="ae48b6e1-827e-43e4-a64d-3be57fc42b58", mixer_name=nil>
[2014-02-16 12:06:01] DEBUG Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: Agent 371 call ended
[2014-02-16 12:06:01] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Call between inside_sales 25376551 and extension 371 ended
[2014-02-16 12:06:02] DEBUG Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Receiving message: #<Punchblock::Event::Answered target_call_id="ca89adc0-dd94-43b0-87c3-a13b2932717c", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}>
[2014-02-16 12:06:02] INFO Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Executing controller #<SalesCallsController call=ca89adc0-dd94-43b0-87c3-a13b2932717c, metadata={:customer_id=>25376560, :customer_type=>"inside_sales", :call_started=>1392570335.745, :answered?=>false}>
[2014-02-16 12:06:02] DEBUG Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Outgoing call beginning for inside_sales 25376560
[2014-02-16 12:06:02] DEBUG Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC Playback", params=["/var/lib/asterisk/sounds/custom/50ms"]>
[2014-02-16 12:06:02] DEBUG Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["PLAYBACKSTATUS"]>
[2014-02-16 12:06:02] DEBUG Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Receiving message: #<Punchblock::Event::Complete target_call_id="ca89adc0-dd94-43b0-87c3-a13b2932717c", target_mixer_name=nil, component_id="63f7ed10-3bb2-4889-a0e7-4be936547d2d", source_uri="63f7ed10-3bb2-4889-a0e7-4be936547d2d", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:02] DEBUG Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Receiving message: #<Punchblock::Event::Complete target_call_id="ca89adc0-dd94-43b0-87c3-a13b2932717c", target_mixer_name=nil, component_id="36455e73-89a5-4db2-8031-5afe040cd747", source_uri="36455e73-89a5-4db2-8031-5afe040cd747", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=1, data="SUCCESS">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:05] DEBUG Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Receiving message: #<Punchblock::Event::End target_call_id="b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:05] INFO Adhearsion::OutboundCall: b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0@: Call ended due to hangup
[2014-02-16 12:06:05] DEBUG CallInformation: pop b5eb70e0-7e1b-4b68-a9ea-944fb65bfcd0
[2014-02-16 12:06:05] INFO PowerSlide::PowerQueuedCall: Waiting customer appointment 1060288 hungup after 41568 ms...
[2014-02-16 12:06:05] DEBUG Adhearsion::OutboundCall: b186328c-07f6-4d30-beae-9984ea8ca7e0@: Receiving message: #<Punchblock::Event::End target_call_id="b186328c-07f6-4d30-beae-9984ea8ca7e0", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:05] INFO Adhearsion::OutboundCall: b186328c-07f6-4d30-beae-9984ea8ca7e0@: Call ended due to hangup
[2014-02-16 12:06:05] DEBUG CallInformation: pop b186328c-07f6-4d30-beae-9984ea8ca7e0
[2014-02-16 12:06:07] DEBUG PeerStatusEventHandler: #<Punchblock::Event::Asterisk::AMI::Event target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="PeerStatus", headers={"Privilege"=>"system,all", "Timestamp"=>"1392570367.886559", "ChannelType"=>"SIP", "Peer"=>"SIP/347", "PeerStatus"=>"Registered", "Address"=>"10.10.0.184:5060"}>
[2014-02-16 12:06:07] DEBUG PeerStatusEventHandler: The SIP peer SIP/347 is Registered
[2014-02-16 12:06:08] DEBUG Adhearsion::OutboundCall: a2e0749a-2d5e-4255-b37f-7726029dc3ae@: Receiving message: #<Punchblock::Event::End target_call_id="a2e0749a-2d5e-4255-b37f-7726029dc3ae", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:08] INFO Adhearsion::OutboundCall: a2e0749a-2d5e-4255-b37f-7726029dc3ae@: Call ended due to hangup
[2014-02-16 12:06:08] DEBUG CallInformation: pop a2e0749a-2d5e-4255-b37f-7726029dc3ae
[2014-02-16 12:06:11] DEBUG Adhearsion::OutboundCall: 8abe2d1b-3a75-43ac-9ef8-3bc484392796@: Receiving message: #<Punchblock::Event::End target_call_id="8abe2d1b-3a75-43ac-9ef8-3bc484392796", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:11] INFO Adhearsion::OutboundCall: 8abe2d1b-3a75-43ac-9ef8-3bc484392796@: Call ended due to hangup
[2014-02-16 12:06:11] DEBUG CallInformation: pop 8abe2d1b-3a75-43ac-9ef8-3bc484392796
[2014-02-16 12:06:11] DEBUG Adhearsion::OutboundCall: de4791cf-1329-48ea-9ac5-e866a9be6b75@: Receiving message: #<Punchblock::Event::End target_call_id="de4791cf-1329-48ea-9ac5-e866a9be6b75", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:11] INFO Adhearsion::OutboundCall: de4791cf-1329-48ea-9ac5-e866a9be6b75@: Call ended due to hangup
[2014-02-16 12:06:11] DEBUG CallInformation: pop de4791cf-1329-48ea-9ac5-e866a9be6b75
[2014-02-16 12:06:12] DEBUG Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Receiving message: #<Punchblock::Event::End target_call_id="ca89adc0-dd94-43b0-87c3-a13b2932717c", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:12] INFO Adhearsion::OutboundCall: ca89adc0-dd94-43b0-87c3-a13b2932717c@: Call ended due to hangup
[2014-02-16 12:06:12] DEBUG CallInformation: pop ca89adc0-dd94-43b0-87c3-a13b2932717c
[2014-02-16 12:06:14] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Receiving message: #<Punchblock::Event::End target_call_id="ae48b6e1-827e-43e4-a64d-3be57fc42b58", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
[2014-02-16 12:06:14] INFO Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Call ended due to hangup
[2014-02-16 12:06:14] DEBUG CallInformation: pop ae48b6e1-827e-43e4-a64d-3be57fc42b58
[2014-02-16 12:06:15] WARN Celluloid: Terminating task: type=:call, meta={:method_name=>:deliver_message}, status=:callwait
[2014-02-16 12:06:15] WARN Celluloid: Terminating task: type=:call, meta={:method_name=>:deliver_message}, status=:callwait
[2014-02-16 12:06:15] ERROR Adhearsion::OutboundCall: 59405a12-2be2-4c14-9425-bd994884a380@: <Celluloid::Task::TerminatedError> task was terminated
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/tasks/task_fiber.rb:32:in `terminate'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `cleanup'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `each'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `cleanup'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:375:in `shutdown'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:185:in `run'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:157:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:12:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
--
[2014-02-16 12:06:15] ERROR Adhearsion::OutboundCall: b91a082e-9f4b-4bc4-8933-5c91695902cb@: <Celluloid::Task::TerminatedError> task was terminated
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/tasks/task_fiber.rb:32:in `terminate'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `cleanup'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `each'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `cleanup'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:375:in `shutdown'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:185:in `run'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:157:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:12:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
--
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376566_588309.wav"]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376558_103134.wav"]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376557_613459.wav"]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Receiving message: #<Punchblock::Event::Complete target_call_id="5d1a5041-e4bb-4d5b-9a71-768936151efb", target_mixer_name=nil, component_id="89ad46ea-d341-4fab-a871-2d208cfb3a6e", source_uri="89ad46ea-d341-4fab-a871-2d208cfb3a6e", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: a561288e-7651-4273-9fda-44d46b70208e@: Receiving message: #<Punchblock::Event::Complete target_call_id="a561288e-7651-4273-9fda-44d46b70208e", target_mixer_name=nil, component_id="71e662dd-e540-484c-a9c5-d9b0c558f9b7", source_uri="71e662dd-e540-484c-a9c5-d9b0c558f9b7", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676@: Receiving message: #<Punchblock::Event::Complete target_call_id="5b28aa0d-f5bf-49bd-8f7a-e43e24bc6676", target_mixer_name=nil, component_id="6bfb1522-5795-4d94-9c48-327d4d1c9166", source_uri="6bfb1522-5795-4d94-9c48-327d4d1c9166", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: ae48b6e1-827e-43e4-a64d-3be57fc42b58@: Finished executing controller #<OutboundCustomersController call=ae48b6e1-827e-43e4-a64d-3be57fc42b58, metadata={}>
[2014-02-16 12:06:15] INFO Adhearsion::Router::Route: Call routing completed. Hanging up now...
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376563_156143.wav"]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376568_252648.wav"]>
[2014-02-16 12:06:15] WARN Celluloid: Terminating task: type=:call, meta={:method_name=>:deliver_message}, status=:callwait
[2014-02-16 12:06:15] DEBUG OutboundCustomersController: Finished executing controller #<OutboundCustomersController call=, metadata={}>
[2014-02-16 12:06:15] DEBUG OutboundCustomersController: Finished executing controller #<OutboundCustomersController call=, metadata={}>
[2014-02-16 12:06:15] ERROR Adhearsion::OutboundCall: 9aea85be-7ecb-4aeb-a8dc-5e3ce2c52f4e@: <Celluloid::Task::TerminatedError> task was terminated
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/tasks/task_fiber.rb:32:in `terminate'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `cleanup'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `each'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:404:in `cleanup'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:375:in `shutdown'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:185:in `run'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/actor.rb:157:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:13:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/thread_handle.rb:12:in `initialize'
/data/apps/talkbox/shared/bundle/jruby/1.9/gems/celluloid-0.15.2/lib/celluloid/internal_pool.rb:100:in `call'
--
[2014-02-16 12:06:15] DEBUG AMI: #disconnect_agent_extension(374)
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Receiving message: #<Punchblock::Event::Complete target_call_id="39185a54-f9ce-4fad-84f8-9c04372736ef", target_mixer_name=nil, component_id="b3b99547-8f10-4cd7-90a5-dfca7d2bb755", source_uri="b3b99547-8f10-4cd7-90a5-dfca7d2bb755", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: 39185a54-f9ce-4fad-84f8-9c04372736ef@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: b708d6ba-129e-4921-a3c7-92d1a2ca039e@: Receiving message: #<Punchblock::Event::Complete target_call_id="b708d6ba-129e-4921-a3c7-92d1a2ca039e", target_mixer_name=nil, component_id="d7769ef0-d758-468c-aee5-8699f7c2ed8b", source_uri="d7769ef0-d758-468c-aee5-8699f7c2ed8b", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:15] DEBUG AMI: #disconnect_agent_extension(374)
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376561_777026.wav"]>
[2014-02-16 12:06:15] INFO SalesCallsController: Call was hung up while executing a controller
[2014-02-16 12:06:15] DEBUG SalesCallsController: Finished executing controller #<SalesCallsController call=, metadata={:customer_id=>25376560, :customer_type=>"inside_sales", :call_started=>1392570335.745, :answered?=>true}>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC MixMonitor", params=["25376559_345765.wav"]>
[2014-02-16 12:06:15] WARN Celluloid: Terminating task: type=:call, meta={:method_name=>:deliver_message}, status=:callwait
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: dc61fd24-4ccf-451c-a441-677f40cb817d@: Receiving message: #<Punchblock::Event::Complete target_call_id="dc61fd24-4ccf-451c-a441-677f40cb817d", target_mixer_name=nil, component_id="02039f89-1910-44c5-a867-e00b19a425ff", source_uri="02039f89-1910-44c5-a867-e00b19a425ff", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Receiving message: #<Punchblock::Event::Complete target_call_id="dae44ffe-61c1-4965-8ac7-a418e898d33c", target_mixer_name=nil, component_id="8158e884-8e65-4bbd-9471-406157d39dcb", source_uri="8158e884-8e65-4bbd-9471-406157d39dcb", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:15] DEBUG Adhearsion::OutboundCall: dae44ffe-61c1-4965-8ac7-a418e898d33c@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="EXEC AMD", params=[""]>
[2014-02-16 12:06:15] DEBUG AMI: #disconnect_agent_extension(374)
[2014-02-16 12:06:16] INFO Roster: Updating 374@10.1.2.87/browser to unavailable
[2014-02-16 12:06:16] DEBUG AMI: #disconnect_homeowner_extension(301)
[2014-02-16 12:06:16] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Receiving message: #<Punchblock::Event::Complete target_call_id="5d1a5041-e4bb-4d5b-9a71-768936151efb", target_mixer_name=nil, component_id="40d8e49c-37bd-423f-931e-c48e99fd66c9", source_uri="40d8e49c-37bd-423f-931e-c48e99fd66c9", domain=nil, transport=nil, reason=#<Punchblock::Component::Asterisk::AGI::Command::Complete::Success target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name=:success, code=200, result=0, data="">, recording=nil, fax=nil, fax_metadata={}>
[2014-02-16 12:06:16] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Executing command #<Punchblock::Component::Asterisk::AGI::Command target_call_id=nil, target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, name="GET VARIABLE", params=["AMDSTATUS"]>
[2014-02-16 12:06:16] DEBUG Adhearsion::OutboundCall: 5d1a5041-e4bb-4d5b-9a71-768936151efb@: Receiving message: #<Punchblock::Event::End target_call_id="5d1a5041-e4bb-4d5b-9a71-768936151efb", target_mixer_name=nil, component_id=nil, source_uri=nil, domain=nil, transport=nil, headers={}, reason=:hangup, platform_code="16">
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment