callisto:ahn_liquid_call jsgoecke$ ahn -
Starting Adhearsion server at /Users/jsgoecke/Dropbox/Development/ahn_liquid_call
[2012-02-09 19:52:03] WARN Adhearsion::LinuxProcName: Error while attaching libc function prctl: Function 'prctl' not found in [libc.dylib]
[2012-02-09 19:52:03] INFO Adhearsion::Initializer: Defining RAILS_ENV variable to <development>
[2012-02-09 19:52:03] INFO Adhearsion::Console: Starting up...
AHN> [2012-02-09 19:52:04] INFO Adhearsion::PunchblockPlugin::Initializer: Starting connection to server
[2012-02-09 19:52:15] DEBUG Punchblock::Client: Handling event #<Punchblock::Connection::Connected:0x007f9119021780> with source .
[2012-02-09 19:52:15] INFO Punchblock::Connection::XMPP: Connected to XMPP as usera@pb.tfoundry.com
[2012-02-09 19:52:15] INFO Adhearsion::PunchblockPlugin::Initializer: Connected to Punchblock server
[2012-02-09 19:52:15] INFO Adhearsion::Initializer: Adhearsion v2.0.0.alpha2 initialized with environment <development>!
[2012-02-09 19:52:15] INFO Adhearsion::Process: Transitioning from booting to running with 0 active calls due to booted event.
[2012-02-09 19:52:25] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-069kpalmfwoab
[2012-02-09 19:52:25] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="4c34790c-880a-421b-97a8-c7ec174e0065" to="[email protected]">
<offer xmlns="urn:xmpp:rayo:1" from="sip:[email protected]" to="sip:[email protected]">
<header name="Record-Route" value="<sip:67.231.8.195;lr=on;ftag=gK084ff247>"/>
<header name="Content-Length" value="328"/>
<header name="To" value="<sip:[email protected]>"/>
<header name="Contact" value="<sip:[email protected]:5060>"/>
<header name="Max-Forwards" value="53"/>
<header name="Allow" value="INVITE"/>
<header name="Content-Disposition" value="session; handling=required"/>
<header name="CSeq" value="23288 INVITE"/>
<header name="Via" value="SIP/2.0/UDP 67.231.8.195;branch=z9hG4bKc97f.51605685.0"/>
<header name="Call-ID" value="[email protected]"/>
<header name="Content-Type" value="application/sdp"/>
<header name="Accept" value="application/sdp"/>
<header name="remote-party-id" value="<sip:[email protected]:5060>;privacy=off;screen=no"/>
<header name="From" value="<sip:[email protected]>;tag=gK084ff247"/>
</offer>
</presence>
[2012-02-09 19:52:25] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::Offer to="sip:[email protected]", from="sip:[email protected]", headers_hash={:record_route=>"<sip:67.231.8.195;lr=on;ftag=gK084ff247>", :content_length=>"328", :to=>"<sip:[email protected]>", :contact=>"<sip:[email protected]:5060>", :max_forwards=>"53", :allow=>"INVITE", :content_disposition=>"session; handling=required", :cseq=>"23288 INVITE", :via=>"SIP/2.0/UDP 67.231.8.195;branch=z9hG4bKc97f.51605685.0", :call_id=>"[email protected]", :content_type=>"application/sdp", :accept=>"application/sdp", :remote_party_id=>"<sip:[email protected]:5060>;privacy=off;screen=no", :from=>"<sip:[email protected]>;tag=gK084ff247"}, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil> with source .
[2012-02-09 19:52:25] DEBUG Punchblock::Client: Executing command: #<Punchblock::Command::Accept headers_hash={}, call_id=nil, component_id=nil> with options {:call_id=>"xejfqve-lx7-069kpalmfwoab"}
[2012-02-09 19:52:25] DEBUG Punchblock::Connection::XMPP: Sending IQ ID blather0008 #<Punchblock::Command::Accept headers_hash={}, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil> to [email protected]/1llrm18ey6sws
[2012-02-09 19:52:25] DEBUG Punchblock::Connection::XMPP: Command blather0008 completed successfully
[2012-02-09 19:52:25] DEBUG Adhearsion::Router: Call xejfqve-lx7-069kpalmfwoab passing through router matched route #<Adhearsion::Router::Route:70130583272480 name=default target=AhnLiquidCall guards=[]>
[2012-02-09 19:52:25] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-069kpalmfwoab: #<Punchblock::Event::Offer to="sip:[email protected]", from="sip:[email protected]", headers_hash={:record_route=>"<sip:67.231.8.195;lr=on;ftag=gK084ff247>", :content_length=>"328", :to=>"<sip:[email protected]>", :contact=>"<sip:[email protected]:5060>", :max_forwards=>"53", :allow=>"INVITE", :content_disposition=>"session; handling=required", :cseq=>"23288 INVITE", :via=>"SIP/2.0/UDP 67.231.8.195;branch=z9hG4bKc97f.51605685.0", :call_id=>"[email protected]", :content_type=>"application/sdp", :accept=>"application/sdp", :remote_party_id=>"<sip:[email protected]:5060>;privacy=off;screen=no", :from=>"<sip:[email protected]>;tag=gK084ff247"}, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil>
[2012-02-09 19:52:26] DEBUG Adhearsion::Call: xejfqve-lx7-069kpalmfwoab: Routing call to: sip:95260079*14153675082@sip.flowroute.com
[2012-02-09 19:52:26] DEBUG Adhearsion::Call: xejfqve-lx7-069kpalmfwoab: Caller is: nil
[2012-02-09 19:52:26] DEBUG Punchblock::Client: Executing command: #<Punchblock::Command::Dial to="sip:95260079*[email protected]", from="tel:+16508683271", join=nil, headers_hash={}, call_id=nil, component_id=nil> with options {:call_id=>nil}
[2012-02-09 19:52:26] DEBUG Punchblock::Connection::XMPP: Sending IQ ID blather000a #<Punchblock::Command::Dial to="sip:95260079*[email protected]", from="tel:+16508683271", join=nil, headers_hash={}, call_id=nil, component_id=nil> to [email protected]/1llrm18ey6sws
[2012-02-09 19:52:26] DEBUG Punchblock::Connection::XMPP: Command blather000a completed successfully
[2012-02-09 19:52:33] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-ttgy6wkk6p80
[2012-02-09 19:52:33] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="49c9f3bf-b68d-493e-900d-68c6b03139ca" to="[email protected]">
<ringing xmlns="urn:xmpp:rayo:1">
<header name="Record-Route" value="<sip:216.115.69.133;lr>"/>
<header name="Content-Length" value="187"/>
<header name="Contact" value="<sip:[email protected]:5060;transport=udp>"/>
<header name="To" value="<sip:95260079*[email protected]>;tag=SDiosce99-gK0cccc946"/>
<header name="CSeq" value="1 INVITE"/>
<header name="Via" value="SIP/2.0/UDP 12.208.178.148:5060;branch=z9hG4bKbhtxfdz89xyx;rport=5060"/>
<header name="Call-ID" value="1i9rblip3e79u"/>
<header name="Content-Type" value="application/sdp"/>
<header name="From" value="<tel:+16508683271>;tag=sbg657457z9f"/>
</ringing>
</presence>
[2012-02-09 19:52:33] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::Ringing headers_hash={:record_route=>"<sip:216.115.69.133;lr>", :content_length=>"187", :contact=>"<sip:[email protected]:5060;transport=udp>", :to=>"<sip:95260079*[email protected]>;tag=SDiosce99-gK0cccc946", :cseq=>"1 INVITE", :via=>"SIP/2.0/UDP 12.208.178.148:5060;branch=z9hG4bKbhtxfdz89xyx;rport=5060", :call_id=>"1i9rblip3e79u", :content_type=>"application/sdp", :from=>"<tel:+16508683271>;tag=sbg657457z9f"}, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil> with source .
[2012-02-09 19:52:33] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-ttgy6wkk6p80: #<Punchblock::Event::Ringing headers_hash={:record_route=>"<sip:216.115.69.133;lr>", :content_length=>"187", :contact=>"<sip:[email protected]:5060;transport=udp>", :to=>"<sip:95260079*[email protected]>;tag=SDiosce99-gK0cccc946", :cseq=>"1 INVITE", :via=>"SIP/2.0/UDP 12.208.178.148:5060;branch=z9hG4bKbhtxfdz89xyx;rport=5060", :call_id=>"1i9rblip3e79u", :content_type=>"application/sdp", :from=>"<tel:+16508683271>;tag=sbg657457z9f"}, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil>
[2012-02-09 19:52:38] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-ttgy6wkk6p80
[2012-02-09 19:52:38] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="9d1dc341-f147-4665-95f6-45c03d9206ab" to="[email protected]">
<answered xmlns="urn:xmpp:rayo:1"/>
</presence>
[2012-02-09 19:52:38] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::Answered headers_hash={}, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil> with source .
[2012-02-09 19:52:38] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-ttgy6wkk6p80: #<Punchblock::Event::Answered headers_hash={}, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil>
[2012-02-09 19:52:38] DEBUG Punchblock::Client: Executing command: #<Punchblock::Command::Join other_call_id="xejfqve-lx7-069kpalmfwoab", mixer_name=nil, direction=nil, media=nil, call_id=nil, component_id=nil> with options {:call_id=>"xejfqve-lx7-ttgy6wkk6p80"}
[2012-02-09 19:52:38] DEBUG Punchblock::Connection::XMPP: Sending IQ ID blather000c #<Punchblock::Command::Join other_call_id="xejfqve-lx7-069kpalmfwoab", mixer_name=nil, direction=nil, media=nil, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil> to [email protected]/1llrm18ey6sws
[2012-02-09 19:52:39] DEBUG Punchblock::Connection::XMPP: Command blather000c completed successfully
[2012-02-09 19:52:39] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-ttgy6wkk6p80
[2012-02-09 19:52:39] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="8507ff8b-773d-427b-83ae-42f88913906c" to="[email protected]">
<joined xmlns="urn:xmpp:rayo:1" call-id="xejfqve-lx7-069kpalmfwoab"/>
</presence>
[2012-02-09 19:52:39] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::Joined other_call_id="xejfqve-lx7-069kpalmfwoab", mixer_name=nil, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil> with source .
[2012-02-09 19:52:39] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-ttgy6wkk6p80: #<Punchblock::Event::Joined other_call_id="xejfqve-lx7-069kpalmfwoab", mixer_name=nil, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil>
[2012-02-09 19:52:39] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-069kpalmfwoab
[2012-02-09 19:52:39] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="5e9de775-abe4-46f0-8ec8-75b04cf7583c" to="[email protected]">
<joined xmlns="urn:xmpp:rayo:1" call-id="xejfqve-lx7-ttgy6wkk6p80"/>
</presence>
[2012-02-09 19:52:39] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::Joined other_call_id="xejfqve-lx7-ttgy6wkk6p80", mixer_name=nil, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil> with source .
[2012-02-09 19:52:39] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-069kpalmfwoab: #<Punchblock::Event::Joined other_call_id="xejfqve-lx7-ttgy6wkk6p80", mixer_name=nil, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil>
[2012-02-09 19:52:40] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-ttgy6wkk6p80
[2012-02-09 19:52:40] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="8ae26a7a-8cea-4414-831f-128df0df7f59" to="[email protected]">
<unjoined xmlns="urn:xmpp:rayo:1" call-id="xejfqve-lx7-069kpalmfwoab"/>
</presence>
[2012-02-09 19:52:40] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::Unjoined other_call_id="xejfqve-lx7-069kpalmfwoab", mixer_name=nil, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil> with source .
[2012-02-09 19:52:40] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-ttgy6wkk6p80: #<Punchblock::Event::Unjoined other_call_id="xejfqve-lx7-069kpalmfwoab", mixer_name=nil, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil>
[2012-02-09 19:52:40] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-069kpalmfwoab
[2012-02-09 19:52:40] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="7ea6073f-ebbe-471e-9281-494da99f3432" to="[email protected]">
<unjoined xmlns="urn:xmpp:rayo:1" call-id="xejfqve-lx7-ttgy6wkk6p80"/>
</presence>
[2012-02-09 19:52:40] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::Unjoined other_call_id="xejfqve-lx7-ttgy6wkk6p80", mixer_name=nil, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil> with source .
[2012-02-09 19:52:40] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-069kpalmfwoab: #<Punchblock::Event::Unjoined other_call_id="xejfqve-lx7-ttgy6wkk6p80", mixer_name=nil, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil>
[2012-02-09 19:52:40] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-069kpalmfwoab
[2012-02-09 19:52:40] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="9acbde40-1b9f-457b-a08e-3387e036ab77" to="[email protected]">
<end xmlns="urn:xmpp:rayo:1">
<hangup/>
<header name="Max-Forwards" value="68"/>
<header name="Record-Route" value="<sip:67.231.8.195;lr=on;ftag=gK084ff247>"/>
<header name="Content-Length" value="0"/>
<header name="To" value="<sip:[email protected]>;tag=1hmm2uxa248ei"/>
<header name="CSeq" value="23289 BYE"/>
<header name="Via" value="SIP/2.0/UDP 67.231.8.195;branch=z9hG4bKb97f.a0b474d5.0"/>
<header name="Call-ID" value="[email protected]"/>
<header name="From" value="<sip:[email protected]>;tag=gK084ff247"/>
</end>
</presence>
[2012-02-09 19:52:40] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::End reason=:hangup, headers_hash={:max_forwards=>"68", :record_route=>"<sip:67.231.8.195;lr=on;ftag=gK084ff247>", :content_length=>"0", :to=>"<sip:[email protected]>;tag=1hmm2uxa248ei", :cseq=>"23289 BYE", :via=>"SIP/2.0/UDP 67.231.8.195;branch=z9hG4bKb97f.a0b474d5.0", :call_id=>"[email protected]", :from=>"<sip:[email protected]>;tag=gK084ff247"}, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil> with source .
[2012-02-09 19:52:40] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-069kpalmfwoab: #<Punchblock::Event::End reason=:hangup, headers_hash={:max_forwards=>"68", :record_route=>"<sip:67.231.8.195;lr=on;ftag=gK084ff247>", :content_length=>"0", :to=>"<sip:[email protected]>;tag=1hmm2uxa248ei", :cseq=>"23289 BYE", :via=>"SIP/2.0/UDP 67.231.8.195;branch=z9hG4bKb97f.a0b474d5.0", :call_id=>"[email protected]", :from=>"<sip:[email protected]>;tag=gK084ff247"}, call_id="xejfqve-lx7-069kpalmfwoab", component_id=nil>
[2012-02-09 19:52:43] INFO Punchblock::Connection::XMPP: Receiving event for call ID xejfqve-lx7-ttgy6wkk6p80
[2012-02-09 19:52:43] DEBUG Punchblock::Connection::XMPP: <presence from="[email protected]" id="a8547c73-93b5-4278-9609-8fedbffcd4ac" to="[email protected]">
<end xmlns="urn:xmpp:rayo:1">
<hangup/>
<header name="Max-Forwards" value="67"/>
<header name="Record-Route" value="<sip:216.115.69.144;lr>"/>
<header name="Content-Length" value="0"/>
<header name="To" value="<tel:+16508683271>;tag=sbg657457z9f"/>
<header name="CSeq" value="31347 BYE"/>
<header name="Via" value="SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKfb12.33ead2d0ba467ed3594425517503e00b.0"/>
<header name="Call-ID" value="1i9rblip3e79u"/>
<header name="From" value="<sip:95260079*[email protected]>;tag=SDiosce99-gK0cccc946"/>
</end>
</presence>
[2012-02-09 19:52:43] DEBUG Punchblock::Client: Handling event #<Punchblock::Event::End reason=:hangup, headers_hash={:max_forwards=>"67", :record_route=>"<sip:216.115.69.144;lr>", :content_length=>"0", :to=>"<tel:+16508683271>;tag=sbg657457z9f", :cseq=>"31347 BYE", :via=>"SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKfb12.33ead2d0ba467ed3594425517503e00b.0", :call_id=>"1i9rblip3e79u", :from=>"<sip:95260079*[email protected]>;tag=SDiosce99-gK0cccc946"}, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil> with source .
[2012-02-09 19:52:43] DEBUG Adhearsion::PunchblockPlugin::Initializer: Event received for call xejfqve-lx7-ttgy6wkk6p80: #<Punchblock::Event::End reason=:hangup, headers_hash={:max_forwards=>"67", :record_route=>"<sip:216.115.69.144;lr>", :content_length=>"0", :to=>"<tel:+16508683271>;tag=sbg657457z9f", :cseq=>"31347 BYE", :via=>"SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKfb12.33ead2d0ba467ed3594425517503e00b.0", :call_id=>"1i9rblip3e79u", :from=>"<sip:95260079*[email protected]>;tag=SDiosce99-gK0cccc946"}, call_id="xejfqve-lx7-ttgy6wkk6p80", component_id=nil>
[2012-02-09 19:52:43] ERROR Adhearsion::Initializer: <NoMethodError> undefined method `join' for 772:Fixnum
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/foundation/object.rb:19:in `method_missing'
/Users/jsgoecke/Dropbox/Development/ahn_liquid_call/lib/ahn_liquid_call.rb:12:in `run'
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/call_controller.rb:67:in `execute!'
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/call_controller.rb:41:in `block in exec'
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/call_controller.rb:40:in `catch'
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/call_controller.rb:40:in `exec'
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/call.rb:211:in `block (2 levels) in execute_controller'
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/foundation/exception_handler.rb:4:in `catching_standard_errors'
/Users/jsgoecke/.rvm/gems/ruby-1.9.3-p0/bundler/gems/adhearsion-4afe46bd3e19/lib/adhearsion/call.rb:209:in `block in execute_controller'