Created
August 29, 2013 23:30
-
-
Save neerajnagi/6384677 to your computer and use it in GitHub Desktop.
ahn + fs + trace
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
</stream:features> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/stream/features/sasl.rb:177:in `authenticate') <auth xmlns="urn:ietf:params:xml:ns:xmpp-sasl" mechanism="PLAIN">MUA1NC4yMTMuNTguOTQAMQAx</auth> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: RECEIVING (success) <success xmlns="urn:ietf:params:xml:ns:xmpp-sasl"/> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/stream/client.rb:12:in `start') <stream:stream to='54.213.58.94' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0' xml:lang='en'> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: RECEIVING (stream) <stream:stream xmlns:db="jabber:server:dialback" xmlns:stream="http://etherx.jabber.org/streams" from="54.213.58.94" id="b152d344-1102-11e3-9940-5d997994877d" lang="en" version="1.0"/> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: RECEIVING (features) <stream:features xmlns:stream="http://etherx.jabber.org/streams"> | |
<bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"/> | |
<session xmlns="urn:ietf:params:xml:ns:xmpp-session"/> | |
</stream:features> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/stream/features/resource.rb:39:in `bind') <iq type="set" id="blather000a"> | |
<bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"> | |
<resource>ip-172-31-41-127.us-west-2.compute.internal-2670</resource> | |
</bind> | |
</iq> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq type="result" id="blather000a"> | |
<bind xmlns="urn:ietf:params:xml:ns:xmpp-bind"> | |
<jid>1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670</jid> | |
</bind> | |
</iq> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: USING JID: 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/stream/features/session.rb:40:in `session') <iq type="set" id="blather000c" to="54.213.58.94"> | |
<session xmlns="urn:ietf:params:xml:ns:xmpp-session"/> | |
</iq> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="54.213.58.94" type="result" id="blather000c"/> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="get" id="blather000e"> | |
<query xmlns="jabber:iq:roster"/> | |
</iq> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq type="error" id="blather000e" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670" from="54.213.58.94"> | |
<query xmlns="jabber:iq:roster"/> | |
<error type="modify"> | |
<feature-not-implemented xmlns="urn:ietf:params:xml:ns:xmpp-stanzas"/> | |
</error> | |
</iq> | |
[2013-08-29 19:28:21] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <presence/> | |
[2013-08-29 19:28:21] INFO Punchblock::Connection::XMPP: Connected to XMPP as 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 | |
[2013-08-29 19:28:21] INFO Adhearsion::PunchblockPlugin::Initializer: Connected to Punchblock server | |
[2013-08-29 19:28:21] INFO Adhearsion::Process: Transitioning from booting to running with 0 active calls due to booted event. | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: RECEIVING (presence) <presence from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670"> | |
<offer xmlns="urn:xmpp:rayo:1" from="sip:1001@54.213.58.94" to="sip:1002@54.213.58.94"> | |
<header name="from" value="<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe"/> | |
<header name="to" value="<sip:1002@54.213.58.94>"/> | |
<header name="via" value="SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2"/> | |
</offer> | |
</presence> | |
[2013-08-29 19:28:39] DEBUG Adhearsion::Call: @: Receiving message: #<Punchblock::Event::Offer target_call_id="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5", target_mixer_name=nil, component_id=nil, domain="54.213.58.94", transport="xmpp", headers={"from"=>"<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe", "to"=>"<sip:1002@54.213.58.94>", "via"=>"SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2"}, to="sip:1002@54.213.58.94", from="sip:1001@54.213.58.94"> | |
[2013-08-29 19:28:39] INFO Adhearsion::Router: Call 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5 selected route "default" (Incomming) | |
[2013-08-29 19:28:39] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Executing command #<Punchblock::Command::Accept target_call_id=nil, target_mixer_name=nil, component_id=nil, domain=nil, transport=nil, headers={}> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0010"> | |
<accept xmlns="urn:xmpp:rayo:1"/> | |
</iq> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670" type="result" id="blather0010"/> | |
[2013-08-29 19:28:39] INFO Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Executing controller #<Incomming call=43db6fba-343c-6c55-0de0-8c3e2fe6b8c5, metadata={}> | |
[2013-08-29 19:28:39] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Receiving message: #<Punchblock::Event::Offer target_call_id="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5", target_mixer_name=nil, component_id=nil, domain="54.213.58.94", transport="xmpp", headers={"from"=>"<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe", "to"=>"<sip:1002@54.213.58.94>", "via"=>"SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2"}, to="sip:1002@54.213.58.94", from="sip:1001@54.213.58.94"> | |
[2013-08-29 19:28:39] INFO Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: incoming call from 1001 --> 1002 | |
[2013-08-29 19:28:39] INFO Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: #<Adhearsion::Call:43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 offer=#<Punchblock::Event::Offer target_call_id="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5", target_mixer_name=nil, component_id=nil, domain="54.213.58.94", transport="xmpp", headers={"from"=>"<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe", "to"=>"<sip:1002@54.213.58.94>", "via"=>"SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2"}, to="sip:1002@54.213.58.94", from="sip:1001@54.213.58.94">, end_reason=nil, commands=[], variables={"from"=>"<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe", "to"=>"<sip:1002@54.213.58.94>", "via"=>"SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2"}, controllers=[#<Incomming call=43db6fba-343c-6c55-0de0-8c3e2fe6b8c5, metadata={}>], to="sip:1002@54.213.58.94", from="sip:1001@54.213.58.94"> | |
[2013-08-29 19:28:39] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Executing command #<Punchblock::Command::Answer target_call_id=nil, target_mixer_name=nil, component_id=nil, domain=nil, transport=nil, headers={}> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0012"> | |
<answer xmlns="urn:xmpp:rayo:1"/> | |
</iq> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670" type="result" id="blather0012"/> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: RECEIVING (presence) <presence from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670"> | |
<answered xmlns="urn:xmpp:rayo:1"/> | |
</presence> | |
[2013-08-29 19:28:39] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Receiving message: #<Punchblock::Event::Answered target_call_id="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5", target_mixer_name=nil, component_id=nil, domain="54.213.58.94", transport="xmpp", headers={}> | |
[2013-08-29 19:28:39] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Executing command #<Punchblock::Component::Output target_call_id=nil, target_mixer_name=nil, component_id=nil, domain=nil, transport=nil, voice=nil, interrupt_on=nil, start_offset=nil, start_paused=false, 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, domain=nil, transport=nil, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US"> | |
<audio src="/var/punchblock/record/welcome.mp3"/> | |
</speak>>]> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0014"> | |
<output xmlns="urn:xmpp:rayo:output:1" start-paused="false"> | |
<document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US"> | |
<audio src="/var/punchblock/record/welcome.mp3"/> | |
</speak>]]></document> | |
</output> | |
</iq> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670" type="result" id="blather0014"> | |
<ref xmlns="urn:xmpp:rayo:1" uri="xmpp:43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1"/> | |
</iq> | |
[2013-08-29 19:28:39] TRACE Punchblock::Connection::XMPP: RECEIVING (presence) <presence from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670" type="unavailable"> | |
<complete xmlns="urn:xmpp:rayo:ext:1"> | |
<error xmlns="urn:xmpp:rayo:ext:complete:1"/> | |
</complete> | |
</presence> | |
[2013-08-29 19:28:39] ERROR Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: <Adhearsion::CallController::Output::PlaybackError> Output failed due to #<Adhearsion::Error: : #<Punchblock::Component::Output target_call_id="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5", target_mixer_name=nil, component_id="output-1", domain="54.213.58.94", transport=nil, voice=nil, interrupt_on=nil, start_offset=nil, start_paused=false, 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, domain=nil, transport=nil, url=nil, content_type="application/ssml+xml", value=<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US"> | |
<audio src="/var/punchblock/record/welcome.mp3"/> | |
</speak>>]>> | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller/output/player.rb:23:in `rescue in output' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller/output/player.rb:13:in `output' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller/output/abstract_player.rb:18:in `play_ssml' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller/output.rb:141:in `play_audio' | |
/root/clicktocall_rayo/lib/incomming.rb:31:in `run' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller.rb:119:in `execute!' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller.rb:94:in `block in exec' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller.rb:93:in `catch' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller.rb:93:in `exec' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller.rb:110:in `exec_with_callback' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller.rb:104:in `block (2 levels) in bg_exec' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/foundation/exception_handler.rb:5:in `catching_standard_errors' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/adhearsion-2.4.0/lib/adhearsion/call_controller.rb:103:in `block in bg_exec' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/celluloid-0.14.1/lib/celluloid/thread_handle.rb:13:in `block in initialize' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/celluloid-0.14.1/lib/celluloid/internal_pool.rb:59:in `call' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/celluloid-0.14.1/lib/celluloid/internal_pool.rb:59:in `block in create' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/logging-1.8.1/lib/logging/diagnostic_context.rb:323:in `call' | |
/usr/local/rvm/gems/ruby-2.0.0-p247/gems/logging-1.8.1/lib/logging/diagnostic_context.rb:323:in `block in create_with_logging_context' | |
[2013-08-29 19:28:40] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Receiving message: #<Punchblock::Event::Complete target_call_id="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5", target_mixer_name=nil, component_id="output-1", domain="54.213.58.94", transport="xmpp", reason=#<Punchblock::Event::Complete::Error target_call_id=nil, target_mixer_name=nil, component_id=nil, domain=nil, transport=nil, name=:error, details="">, recording=nil> | |
[2013-08-29 19:28:40] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Finished executing controller #<Incomming call=43db6fba-343c-6c55-0de0-8c3e2fe6b8c5, metadata={}> | |
[2013-08-29 19:28:40] INFO Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Hanging up | |
[2013-08-29 19:28:40] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Executing command #<Punchblock::Command::Hangup target_call_id=nil, target_mixer_name=nil, component_id=nil, domain=nil, transport=nil, headers={}> | |
[2013-08-29 19:28:40] TRACE Punchblock::Connection::XMPP: SENDING: (/usr/local/rvm/gems/ruby-2.0.0-p247/gems/blather-0.8.7/lib/blather/client/client.rb:149:in `write') <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0016"> | |
<hangup xmlns="urn:xmpp:rayo:1"/> | |
</iq> | |
[2013-08-29 19:28:40] TRACE Punchblock::Connection::XMPP: RECEIVING (iq) <iq from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670" type="result" id="blather0016"/> | |
[2013-08-29 19:28:40] TRACE Punchblock::Connection::XMPP: RECEIVING (presence) <presence from="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" to="1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670" type="unavailable"> | |
<end xmlns="urn:xmpp:rayo:1"> | |
<hangup-command/> | |
</end> | |
</presence> | |
[2013-08-29 19:28:40] DEBUG Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Receiving message: #<Punchblock::Event::End target_call_id="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5", target_mixer_name=nil, component_id=nil, domain="54.213.58.94", transport="xmpp", headers={}, reason=:"hangup-command", platform_code=nil> | |
[2013-08-29 19:28:40] INFO Adhearsion::Call: 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: Call ended due to hangup-command | |
freeswitch trace | |
ype = NODE_NORMAL | |
2013-08-29 19:28:22.072005 [DEBUG] xmpp_streams.c:287 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, presence, state = READY | |
2013-08-29 19:28:22.072005 [DEBUG] mod_rayo.c:3010 Lock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:22.072005 [DEBUG] mod_rayo.c:2310 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 got empty presence | |
2013-08-29 19:28:22.072005 [DEBUG] mod_rayo.c:2331 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 is ONLINE | |
2013-08-29 19:28:22.072005 [DEBUG] mod_rayo.c:3076 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:39.870964 [NOTICE] switch_channel.c:1030 New Channel sofia/internal/1001@54.213.58.94 [43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_session.c:1006 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_session.c:1006 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/1001@54.213.58.94) Running State Change CS_NEW | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/1001@54.213.58.94) State NEW | |
2013-08-29 19:28:39.870964 [DEBUG] sofia.c:7906 IP 202.157.93.2 Approved by acl "domains[]". Access Granted. | |
2013-08-29 19:28:39.870964 [DEBUG] sofia.c:8925 Setting NAT mode based on via received | |
2013-08-29 19:28:39.870964 [DEBUG] sofia.c:5793 Channel sofia/internal/1001@54.213.58.94 entering state [received][100] | |
2013-08-29 19:28:39.870964 [DEBUG] sofia.c:5802 Remote SDP: | |
v=0 | |
o=- 477315452371321340 2 IN IP4 127.0.0.1 | |
s=Doubango Telecom - chrome | |
t=0 0 | |
a=group:BUNDLE audio video | |
a=msid-semantic: WMS lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjg | |
m=audio 54247 RTP/SAVPF 111 103 104 0 8 107 106 105 13 126 | |
c=IN IP4 202.157.93.2 | |
a=rtpmap:111 opus/48000/2 | |
a=fmtp:111 minptime=10 | |
a=rtpmap:103 ISAC/16000 | |
a=rtpmap:104 ISAC/32000 | |
a=rtpmap:0 PCMU/8000 | |
a=rtpmap:8 PCMA/8000 | |
a=rtpmap:107 CN/48000 | |
a=rtpmap:106 CN/32000 | |
a=rtpmap:105 CN/16000 | |
a=rtpmap:13 CN/8000 | |
a=rtpmap:126 telephone-event/8000 | |
a=rtcp:54247 IN IP4 202.157.93.2 | |
a=candidate:524234378 1 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:524234378 2 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:3551662361 1 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:3551662361 2 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:1371329146 1 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=candidate:1371329146 2 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=ice-ufrag:QVIet8IdJ4Epb7NW | |
a=ice-pwd:4W9ibRyVCJCqXPb5hXxuZfsm | |
a=ice-options:google-ice | |
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level | |
a=mid:audio | |
a=rtcp-mux | |
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:zehWqlzYkkQlk6A+AHatyKdq2MT0of39bu+mugj7 | |
a=maxptime:60 | |
a=ssrc:1152824513 cname:98i9IjPECDDuI7UZ | |
a=ssrc:1152824513 msid:lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjg lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjga0 | |
a=ssrc:1152824513 mslabel:lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjg | |
a=ssrc:1152824513 label:lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjga0 | |
m=video 54247 RTP/SAVPF 100 116 117 | |
c=IN IP4 202.157.93.2 | |
a=rtpmap:100 VP8/90000 | |
a=rtpmap:116 red/90000 | |
a=rtpmap:117 ulpfec/90000 | |
a=rtcp:54247 IN IP4 202.157.93.2 | |
a=candidate:524234378 1 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:524234378 2 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:3551662361 1 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:3551662361 2 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:1371329146 1 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=candidate:1371329146 2 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=ice-ufrag:QVIet8IdJ4Epb7NW | |
a=ice-pwd:4W9ibRyVCJCqXPb5hXxuZfsm | |
a=ice-options:google-ice | |
a=extmap:2 urn:ietf:params:rtp-hdrext:toffset | |
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time | |
a=mid:video | |
a=rtcp-mux | |
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:zehWqlzYkkQlk6A+AHatyKdq2MT0of39bu+mugj7 | |
a=rtcp-fb:100 ccm fir | |
a=rtcp-fb:100 nack | |
a=rtcp-fb:100 goog-remb | |
2013-08-29 19:28:39.870964 [DEBUG] sofia.c:6015 (sofia/internal/1001@54.213.58.94) State Change CS_NEW -> CS_INIT | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_session.c:1341 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/1001@54.213.58.94) Running State Change CS_INIT | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:455 (sofia/internal/1001@54.213.58.94) State INIT | |
2013-08-29 19:28:39.870964 [DEBUG] mod_sofia.c:87 sofia/internal/1001@54.213.58.94 SOFIA INIT | |
2013-08-29 19:28:39.870964 [DEBUG] mod_sofia.c:114 (sofia/internal/1001@54.213.58.94) State Change CS_INIT -> CS_ROUTING | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_session.c:1341 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:455 (sofia/internal/1001@54.213.58.94) State INIT going to sleep | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/1001@54.213.58.94) Running State Change CS_ROUTING | |
2013-08-29 19:28:39.870964 [DEBUG] switch_channel.c:2116 (sofia/internal/1001@54.213.58.94) Callstate Change DOWN -> RINGING | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:471 (sofia/internal/1001@54.213.58.94) State ROUTING | |
2013-08-29 19:28:39.870964 [DEBUG] mod_sofia.c:137 sofia/internal/1001@54.213.58.94 SOFIA ROUTING | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:117 sofia/internal/1001@54.213.58.94 Standard ROUTING | |
2013-08-29 19:28:39.870964 [INFO] mod_dialplan_xml.c:558 Processing 1001 <1001>->1002 in context public | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->public_did] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [public_did] destination_number(1002) =~ /^(5551212)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_10000] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_10000] destination_number(1002) =~ /^(10000)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_123123123123] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_123123123123] destination_number(1002) =~ /^(123123123123)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_2354] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_2354] destination_number(1002) =~ /^(2354)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_2355] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_2355] destination_number(1002) =~ /^(2355)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_2356] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_2356] destination_number(1002) =~ /^(2356)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_2722249814] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_2722249814] destination_number(1002) =~ /^(2722249814)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_800] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_800] destination_number(1002) =~ /^(800)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_800100] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_800100] destination_number(1002) =~ /^(800100)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_Raj2] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_Raj2] destination_number(1002) =~ /^(Raj2)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_RajRoom] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_RajRoom] destination_number(1002) =~ /^(RajRoom)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->conferences_monu_ki_conference] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [conferences_monu_ki_conference] destination_number(1002) =~ /^(conference_monu_ki_conference)$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->sunil_conferences] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (FAIL) [sunil_conferences] destination_number(1002) =~ /^2353$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 parsing [public->Adhearsion] continue=false | |
Dialplan: sofia/internal/1001@54.213.58.94 Regex (PASS) [Adhearsion] destination_number(1002) =~ /.*$/ break=on-false | |
Dialplan: sofia/internal/1001@54.213.58.94 Action info() | |
Dialplan: sofia/internal/1001@54.213.58.94 Action set(is_parent_leg=yes) | |
Dialplan: sofia/internal/1001@54.213.58.94 Action set(hangup_after_bridge=false) | |
Dialplan: sofia/internal/1001@54.213.58.94 Action set(park_after_bridge=false) | |
Dialplan: sofia/internal/1001@54.213.58.94 Action set(domain_name=54.213.58.94) | |
Dialplan: sofia/internal/1001@54.213.58.94 Action rayo() | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/1001@54.213.58.94) State Change CS_ROUTING -> CS_EXECUTE | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_session.c:1341 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:471 (sofia/internal/1001@54.213.58.94) State ROUTING going to sleep | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/1001@54.213.58.94) Running State Change CS_EXECUTE | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:478 (sofia/internal/1001@54.213.58.94) State EXECUTE | |
2013-08-29 19:28:39.870964 [DEBUG] mod_sofia.c:230 sofia/internal/1001@54.213.58.94 SOFIA EXECUTE | |
2013-08-29 19:28:39.870964 [DEBUG] switch_core_state_machine.c:209 sofia/internal/1001@54.213.58.94 Standard EXECUTE | |
EXECUTE sofia/internal/1001@54.213.58.94 info() | |
2013-08-29 19:28:39.870964 [INFO] mod_dptools.c:1586 CHANNEL_DATA: | |
Channel-State: [CS_EXECUTE] | |
Channel-Call-State: [RINGING] | |
Channel-State-Number: [4] | |
Channel-Name: [sofia/internal/1001@54.213.58.94] | |
Unique-ID: [43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
Call-Direction: [inbound] | |
Presence-Call-Direction: [inbound] | |
Channel-HIT-Dialplan: [true] | |
Channel-Presence-ID: [1001@54.213.58.94] | |
Channel-Call-UUID: [43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
Answer-State: [ringing] | |
Caller-Direction: [inbound] | |
Caller-Username: [1001] | |
Caller-Dialplan: [XML] | |
Caller-Caller-ID-Name: [1001] | |
Caller-Caller-ID-Number: [1001] | |
Caller-Orig-Caller-ID-Name: [1001] | |
Caller-Orig-Caller-ID-Number: [1001] | |
Caller-Network-Addr: [202.157.93.2] | |
Caller-ANI: [1001] | |
Caller-Destination-Number: [1002] | |
Caller-Unique-ID: [43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
Caller-Source: [mod_sofia] | |
Caller-Context: [public] | |
Caller-Channel-Name: [sofia/internal/1001@54.213.58.94] | |
Caller-Profile-Index: [1] | |
Caller-Profile-Created-Time: [1377818919870964] | |
Caller-Channel-Created-Time: [1377818919870964] | |
Caller-Channel-Answered-Time: [0] | |
Caller-Channel-Progress-Time: [0] | |
Caller-Channel-Progress-Media-Time: [0] | |
Caller-Channel-Hangup-Time: [0] | |
Caller-Channel-Transfer-Time: [0] | |
Caller-Channel-Resurrect-Time: [0] | |
Caller-Channel-Bridged-Time: [0] | |
Caller-Channel-Last-Hold: [0] | |
Caller-Channel-Hold-Accum: [0] | |
Caller-Screen-Bit: [true] | |
Caller-Privacy-Hide-Name: [false] | |
Caller-Privacy-Hide-Number: [false] | |
variable_direction: [inbound] | |
variable_uuid: [43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
variable_session_id: [23] | |
variable_sip_from_user: [1001] | |
variable_sip_from_uri: [1001@54.213.58.94] | |
variable_sip_from_host: [54.213.58.94] | |
variable_channel_name: [sofia/internal/1001@54.213.58.94] | |
variable_sip_call_id: [43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
variable_sip_local_network_addr: [54.213.58.94] | |
variable_sip_network_ip: [202.157.93.2] | |
variable_sip_network_port: [62159] | |
variable_sip_received_ip: [202.157.93.2] | |
variable_sip_received_port: [62159] | |
variable_sip_via_protocol: [ws] | |
variable_sip_authorized: [true] | |
variable_sip_acl_authed_by: [domains] | |
variable_sip_from_user_stripped: [1001] | |
variable_sip_from_tag: [b5efyFyBmzy0z43a1JQe] | |
variable_sofia_profile_name: [internal] | |
variable_recovery_profile_name: [internal] | |
variable_sip_full_via: [SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2] | |
variable_sip_full_from: [<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe] | |
variable_sip_full_to: [<sip:1002@54.213.58.94>] | |
variable_sip_req_user: [1002] | |
variable_sip_req_uri: [1002@54.213.58.94] | |
variable_sip_req_host: [54.213.58.94] | |
variable_sip_to_user: [1002] | |
variable_sip_to_uri: [1002@54.213.58.94] | |
variable_sip_to_host: [54.213.58.94] | |
variable_sip_contact_params: [rtcweb-breaker=no;click2call=no;transport=ws] | |
variable_sip_contact_user: [1001] | |
variable_sip_contact_uri: [1001@df7jal23ls0d.invalid] | |
variable_sip_contact_host: [df7jal23ls0d.invalid] | |
variable_rtp_use_codec_string: [G722,PCMU,PCMA,GSM] | |
variable_sip_user_agent: [IM-client/OMA1.0 sipML5-v1.2013.05.24] | |
variable_sip_via_host: [df7jal23ls0d.invalid] | |
variable_sip_via_rport: [62159] | |
variable_max_forwards: [70] | |
variable_presence_id: [1001@54.213.58.94] | |
variable_sip_nat_detected: [true] | |
variable_switch_r_sdp: [v=0 | |
o=- 477315452371321340 2 IN IP4 127.0.0.1 | |
s=Doubango Telecom - chrome | |
t=0 0 | |
a=group:BUNDLE audio video | |
a=msid-semantic: WMS lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjg | |
m=audio 54247 RTP/SAVPF 111 103 104 0 8 107 106 105 13 126 | |
c=IN IP4 202.157.93.2 | |
a=rtpmap:111 opus/48000/2 | |
a=fmtp:111 minptime=10 | |
a=rtpmap:103 ISAC/16000 | |
a=rtpmap:104 ISAC/32000 | |
a=rtpmap:0 PCMU/8000 | |
a=rtpmap:8 PCMA/8000 | |
a=rtpmap:107 CN/48000 | |
a=rtpmap:106 CN/32000 | |
a=rtpmap:105 CN/16000 | |
a=rtpmap:13 CN/8000 | |
a=rtpmap:126 telephone-event/8000 | |
a=rtcp:54247 IN IP4 202.157.93.2 | |
a=candidate:524234378 1 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:524234378 2 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:3551662361 1 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:3551662361 2 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:1371329146 1 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=candidate:1371329146 2 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=ice-ufrag:QVIet8IdJ4Epb7NW | |
a=ice-pwd:4W9ibRyVCJCqXPb5hXxuZfsm | |
a=ice-options:google-ice | |
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level | |
a=mid:audio | |
a=rtcp-mux | |
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:zehWqlzYkkQlk6A+AHatyKdq2MT0of39bu+mugj7 | |
a=maxptime:60 | |
a=ssrc:1152824513 cname:98i9IjPECDDuI7UZ | |
a=ssrc:1152824513 msid:lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjg lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjga0 | |
a=ssrc:1152824513 mslabel:lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjg | |
a=ssrc:1152824513 label:lV9LezmjQyGbf1Fwh3mMbL9dMGYfAU0fVzjga0 | |
m=video 54247 RTP/SAVPF 100 116 117 | |
c=IN IP4 202.157.93.2 | |
a=rtpmap:100 VP8/90000 | |
a=rtpmap:116 red/90000 | |
a=rtpmap:117 ulpfec/90000 | |
a=rtcp:54247 IN IP4 202.157.93.2 | |
a=candidate:524234378 1 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:524234378 2 udp 2113937151 10.100.101.254 54247 typ host generation 0 | |
a=candidate:3551662361 1 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:3551662361 2 udp 1845501695 202.157.93.2 54247 typ srflx raddr 10.100.101.254 rport 54247 generation 0 | |
a=candidate:1371329146 1 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=candidate:1371329146 2 tcp 1509957375 10.100.101.254 0 typ host generation 0 | |
a=ice-ufrag:QVIet8IdJ4Epb7NW | |
a=ice-pwd:4W9ibRyVCJCqXPb5hXxuZfsm | |
a=ice-options:google-ice | |
a=extmap:2 urn:ietf:params:rtp-hdrext:toffset | |
a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time | |
a=mid:video | |
a=rtcp-mux | |
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:zehWqlzYkkQlk6A+AHatyKdq2MT0of39bu+mugj7 | |
a=rtcp-fb:100 ccm fir | |
a=rtcp-fb:100 nack | |
a=rtcp-fb:100 goog-remb | |
] | |
variable_ep_codec_string: [PCMU@8000h@20i@64000b,PCMA@8000h@20i@64000b] | |
variable_endpoint_disposition: [DELAYED NEGOTIATION] | |
variable_call_uuid: [43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
variable_current_application: [info] | |
EXECUTE sofia/internal/1001@54.213.58.94 set(is_parent_leg=yes) | |
2013-08-29 19:28:39.870964 [DEBUG] mod_dptools.c:1374 sofia/internal/1001@54.213.58.94 SET [is_parent_leg]=[yes] | |
EXECUTE sofia/internal/1001@54.213.58.94 set(hangup_after_bridge=false) | |
2013-08-29 19:28:39.870964 [DEBUG] mod_dptools.c:1374 sofia/internal/1001@54.213.58.94 SET [hangup_after_bridge]=[false] | |
EXECUTE sofia/internal/1001@54.213.58.94 set(park_after_bridge=false) | |
2013-08-29 19:28:39.870964 [DEBUG] mod_dptools.c:1374 sofia/internal/1001@54.213.58.94 SET [park_after_bridge]=[false] | |
EXECUTE sofia/internal/1001@54.213.58.94 set(domain_name=54.213.58.94) | |
2013-08-29 19:28:39.870964 [DEBUG] mod_dptools.c:1374 sofia/internal/1001@54.213.58.94 SET [domain_name]=[54.213.58.94] | |
EXECUTE sofia/internal/1001@54.213.58.94 rayo() | |
2013-08-29 19:28:39.870964 [DEBUG] mod_rayo.c:2938 Init 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 | |
2013-08-29 19:28:39.870964 [DEBUG] mod_rayo.c:2943 Offering call for Rayo 3PCC | |
2013-08-29 19:28:39.870964 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:39.870964 [DEBUG] mod_rayo.c:2961 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><offer xmlns='urn:xmpp:rayo:1' from='sip:1001@54.213.58.94' to='sip:1002@54.213.58.94'><header name='from' value='<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe'/><header name='to' value='<sip:1002@54.213.58.94>'/><header name='via' value='SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2'/></offer></presence> | |
2013-08-29 19:28:39.870964 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:39.892696 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><offer xmlns='urn:xmpp:rayo:1' from='sip:1001@54.213.58.94' to='sip:1002@54.213.58.94'><header name='from' value='<sip:1001@54.213.58.94>;tag=b5efyFyBmzy0z43a1JQe'/><header name='to' value='<sip:1002@54.213.58.94>'/><header name='via' value='SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgwhqgOF9NAbFgnSlNI4o5TiQhuT59Nm8;rport=62159;received=202.157.93.2'/></offer></presence> | |
2013-08-29 19:28:40.026346 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 RECV <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0010"><accept xmlns="urn:xmpp:rayo:1"/></iq> | |
2013-08-29 19:28:40.026346 [DEBUG] xmpp_streams.c:1058 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, state = READY, node type = NODE_NORMAL | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:3010 Lock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:2365 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, recv iq, availability = ONLINE | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:3069 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:624 Locate 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 2 | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:2368 Deliver 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 => 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 <iq type='set' to='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' id='blather0010' from='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><accept xmlns='urn:xmpp:rayo:1'/></iq> | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:505 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, looking for CALL::set:urn:xmpp:rayo:1:accept command | |
2013-08-29 19:28:40.026346 [INFO] mod_rayo.c:1314 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 has control of call | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:1471 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, executing command | |
2013-08-29 19:28:40.026346 [INFO] mod_rayo.c:1609 Sending early media | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:881 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:zehWqlzYkkQlk6A+AHatyKdq2MT0of39bu+mugj7] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:682 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:EBuUUIkpIDx41zFyocTulRMHHtpNYk44wrOW0yvN] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [opus:111:48000:60:0]/[G722:9:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [opus:111:48000:60:0]/[PCMU:0:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [opus:111:48000:60:0]/[PCMA:8:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [opus:111:48000:60:0]/[GSM:3:8000:20:13200] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:103:16000:30:32000]/[G722:9:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:103:16000:30:32000]/[PCMU:0:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:103:16000:30:32000]/[PCMA:8:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:103:16000:30:32000]/[GSM:3:8000:20:13200] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:104:32000:30:32000]/[G722:9:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:104:32000:30:32000]/[PCMU:0:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:104:32000:30:32000]/[PCMA:8:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [ISAC:104:32000:30:32000]/[GSM:3:8000:20:13200] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [PCMU:0:8000:60:64000]/[G722:9:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2787 Audio Codec Compare [PCMU:0:8000:60:64000]/[PCMU:0:8000:20:64000] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:1823 Set Codec sofia/internal/1001@54.213.58.94 PCMU/8000 20 ms 160 samples 64000 bits | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@54.213.58.94 Original read codec set to PCMU:0 | |
2013-08-29 19:28:40.026346 [WARNING] switch_core_media.c:2002 NO candidate ACL defined, Defaulting to wan.auto | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2026 Checking Candidate cid: 1 proto: udp type: host addr: 10.100.101.254:54247 | |
2013-08-29 19:28:40.026346 [NOTICE] switch_core_media.c:2040 Save audio Candidate cid: 1 proto: udp type: host addr: 10.100.101.254:54247 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2026 Checking Candidate cid: 2 proto: udp type: host addr: 10.100.101.254:54247 | |
2013-08-29 19:28:40.026346 [NOTICE] switch_core_media.c:2040 Save audio Candidate cid: 2 proto: udp type: host addr: 10.100.101.254:54247 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2026 Checking Candidate cid: 1 proto: udp type: srflx addr: 202.157.93.2:54247 | |
2013-08-29 19:28:40.026346 [NOTICE] switch_core_media.c:2035 Choose audio Candidate cid: 1 proto: udp type: srflx addr: 202.157.93.2:54247 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2026 Checking Candidate cid: 2 proto: udp type: srflx addr: 202.157.93.2:54247 | |
2013-08-29 19:28:40.026346 [NOTICE] switch_core_media.c:2035 Choose audio Candidate cid: 2 proto: udp type: srflx addr: 202.157.93.2:54247 | |
2013-08-29 19:28:40.026346 [NOTICE] switch_core_media.c:2149 setting remote audio ice addr to 202.157.93.2:54247 based on candidate | |
2013-08-29 19:28:40.026346 [NOTICE] switch_core_media.c:2167 setting remote rtcp audio addr to 202.157.93.2:54247 based on candidate | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:2958 Set 2833 dtmf send/recv payload to 126 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:881 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:zehWqlzYkkQlk6A+AHatyKdq2MT0of39bu+mugj7] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:682 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:wTQE0zQnzJvZ3NWGitOeQ0Ee57+Jaa6AOwRDlAsH] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:3986 AUDIO RTP [sofia/internal/1001@54.213.58.94] 172.31.41.127 port 25898 -> 202.157.93.2 port 54247 codec: 0 ms: 20 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_rtp.c:2885 Starting timer [soft] 160 bytes per 20ms | |
2013-08-29 19:28:40.026346 [INFO] switch_core_media.c:4149 Activating Audio ICE | |
2013-08-29 19:28:40.026346 [NOTICE] switch_rtp.c:3317 Activating RTP audio ICE: QVIet8IdJ4Epb7NW:eGlFy1CUaKO44QCx 202.157.93.2:54247 | |
2013-08-29 19:28:40.026346 [INFO] switch_core_media.c:4192 Activating RTCP PORT 54247 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_rtp.c:3222 RTCP send rate is: 10000 and packet rate is: 20000 Remote Port: 54247 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_rtp.c:1858 Setting RTCP remote addr to 202.157.93.2:54247 | |
2013-08-29 19:28:40.026346 [INFO] switch_core_media.c:4200 Skipping RTCP ICE (Same as RTP) | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:4320 Set 2833 dtmf send payload to 126 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:4326 Set 2833 dtmf receive payload to 126 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_media.c:4359 Set comfort noise payload to 107 | |
2013-08-29 19:28:40.026346 [INFO] switch_rtp.c:2705 Activating Audio Secure RTP SEND | |
2013-08-29 19:28:40.026346 [INFO] switch_rtp.c:2683 Activating Audio Secure RTP RECV | |
2013-08-29 19:28:40.026346 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@54.213.58.94! | |
2013-08-29 19:28:40.026346 [DEBUG] switch_channel.c:3316 (sofia/internal/1001@54.213.58.94) Callstate Change RINGING -> EARLY | |
2013-08-29 19:28:40.026346 [DEBUG] mod_sofia.c:2105 Ring SDP: | |
v=0 | |
o=FreeSWITCH 1377793022 1377793023 IN IP4 54.213.58.94 | |
s=FreeSWITCH | |
c=IN IP4 54.213.58.94 | |
t=0 0 | |
a=msid-semantic: WMS OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcH | |
m=audio 25898 RTP/SAVPF 0 126 107 | |
a=rtpmap:0 PCMU/8000 | |
a=rtpmap:126 telephone-event/8000 | |
a=rtpmap:107 CN/8000 | |
a=ptime:20 | |
a=sendrecv | |
a=rtcp-mux | |
a=rtcp:25898 IN IP4 54.213.58.94 | |
a=ssrc:1620024959 cname:DZuCT2XWqmRzSrkt | |
a=ssrc:1620024959 msid:OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcH a0 | |
a=ssrc:1620024959 mslabel:OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcH | |
a=ssrc:1620024959 label:OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcHa0 | |
a=ice-ufrag:eGlFy1CUaKO44QCx | |
a=ice-pwd:FW0pPOb50TvJaF2e | |
a=candidate:8553901727 1 udp 659136 54.213.58.94 25898 typ host generation 0 | |
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:EBuUUIkpIDx41zFyocTulRMHHtpNYk44wrOW0yvN | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_session.c:861 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:1473 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, done executing command | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:631 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 1 | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:1480 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0010'/> | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_sqldb.c:2352 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80 | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_session.c:1006 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:40.026346 [DEBUG] switch_core_sqldb.c:2352 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80 | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:2776 got event CHANNEL_PROGRESS_MEDIA | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:2781 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 rayo event CHANNEL_PROGRESS_MEDIA | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:2783 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.026346 [DEBUG] mod_rayo.c:2791 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.031170 [DEBUG] sofia.c:5793 Channel sofia/internal/1001@54.213.58.94 entering state [early][183] | |
2013-08-29 19:28:40.031170 [DEBUG] switch_ivr.c:951 Codec Activated L16@8000hz 1 channels 20ms | |
2013-08-29 19:28:40.031170 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0010'/> | |
2013-08-29 19:28:40.453714 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 RECV <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0012"><answer xmlns="urn:xmpp:rayo:1"/></iq> | |
2013-08-29 19:28:40.453714 [DEBUG] xmpp_streams.c:1058 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, state = READY, node type = NODE_NORMAL | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:3010 Lock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2365 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, recv iq, availability = ONLINE | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:3069 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:624 Locate 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 2 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2368 Deliver 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 => 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 <iq type='set' to='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' id='blather0012' from='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><answer xmlns='urn:xmpp:rayo:1'/></iq> | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:505 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, looking for CALL::set:urn:xmpp:rayo:1:answer command | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:1471 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, executing command | |
2013-08-29 19:28:40.453714 [DEBUG] mod_sofia.c:822 Local SDP sofia/internal/1001@54.213.58.94: | |
v=0 | |
o=FreeSWITCH 1377793022 1377793024 IN IP4 54.213.58.94 | |
s=FreeSWITCH | |
c=IN IP4 54.213.58.94 | |
t=0 0 | |
a=msid-semantic: WMS OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcH | |
m=audio 25898 RTP/SAVPF 0 126 107 | |
a=rtpmap:0 PCMU/8000 | |
a=rtpmap:126 telephone-event/8000 | |
a=rtpmap:107 CN/8000 | |
a=ptime:20 | |
a=sendrecv | |
a=rtcp-mux | |
a=rtcp:25898 IN IP4 54.213.58.94 | |
a=ssrc:1620024959 cname:DZuCT2XWqmRzSrkt | |
a=ssrc:1620024959 msid:OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcH a0 | |
a=ssrc:1620024959 mslabel:OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcH | |
a=ssrc:1620024959 label:OjWX94Hvcyy5iFJUKXw2B2P7wjh33tcHa0 | |
a=ice-ufrag:eGlFy1CUaKO44QCx | |
a=ice-pwd:FW0pPOb50TvJaF2e | |
a=candidate:7105405301 1 udp 659136 54.213.58.94 25898 typ host generation 0 | |
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:EBuUUIkpIDx41zFyocTulRMHHtpNYk44wrOW0yvN | |
2013-08-29 19:28:40.453714 [DEBUG] switch_core_session.c:861 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:40.453714 [NOTICE] mod_rayo.c:1628 Channel [sofia/internal/1001@54.213.58.94] has been answered | |
2013-08-29 19:28:40.453714 [DEBUG] switch_channel.c:3595 (sofia/internal/1001@54.213.58.94) Callstate Change EARLY -> ACTIVE | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:1473 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, done executing command | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:631 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 1 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:1480 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0012'/> | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2776 got event CHANNEL_ANSWER | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2781 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 rayo event CHANNEL_ANSWER | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2783 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2616 Locate 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 2 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2622 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 1 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2791 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:2621 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><answered xmlns='urn:xmpp:rayo:1'/></presence> | |
2013-08-29 19:28:40.453714 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.453714 [DEBUG] switch_core_session.c:1006 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:40.453714 [DEBUG] sofia.c:5793 Channel sofia/internal/1001@54.213.58.94 entering state [completed][200] | |
2013-08-29 19:28:40.453714 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0012'/> | |
2013-08-29 19:28:40.453714 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><answered xmlns='urn:xmpp:rayo:1'/></presence> | |
2013-08-29 19:28:40.621673 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 RECV <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0014"><output xmlns="urn:xmpp:rayo:output:1" start-paused="false"><document content-type="application/ssml+xml"><![CDATA[<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US"> | |
<audio src="/var/punchblock/record/welcome.mp3"/> | |
</speak>]]></document></output></iq> | |
2013-08-29 19:28:40.621673 [DEBUG] xmpp_streams.c:1058 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, state = READY, node type = NODE_NORMAL | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:3010 Lock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:2365 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, recv iq, availability = ONLINE | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:3069 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:624 Locate 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 2 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:2368 Deliver 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 => 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 <iq type='set' to='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' id='blather0014' from='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><output xmlns='urn:xmpp:rayo:output:1' start-paused='false'><document content-type='application/ssml+xml'><speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US"> | |
<audio src="/var/punchblock/record/welcome.mp3"/> | |
</speak></document></output></iq> | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:505 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, looking for CALL::set:urn:xmpp:rayo:output:1:output command | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:1471 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, executing command | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_output_component.c:67 Init 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:1151 Lock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 3 | |
2013-08-29 19:28:40.621673 [DEBUG] switch_event.c:1615 Parsing variable [id]=[43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1] | |
2013-08-29 19:28:40.621673 [DEBUG] switch_event.c:1615 Parsing variable [session]=[43db6fba-343c-6c55-0de0-8c3e2fe6b8c5] | |
2013-08-29 19:28:40.621673 [DEBUG] switch_event.c:1615 Parsing variable [pause]=[false] | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_output_component.c:624 Got path rayo://43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_output_component.c:458 Got path 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_output_component.c:460 Locate 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1: ref count = 2 | |
2013-08-29 19:28:40.621673 [ERR] switch_core_file.c:150 Invalid file format [ssml] for [<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US"> | |
<audio src="/var/punchblock/record/welcome.mp3"/> | |
</speak>]! | |
2013-08-29 19:28:40.621673 [INFO] rayo_output_component.c:425 Failed to open ssml://<speak xmlns="http://www.w3.org/2001/10/synthesis" version="1.0" xml:lang="en-US"> | |
<audio src="/var/punchblock/record/welcome.mp3"/> | |
</speak> | |
2013-08-29 19:28:40.621673 [INFO] rayo_output_component.c:386 Done playing | |
2013-08-29 19:28:40.621673 [INFO] rayo_output_component.c:474 Status = 1 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_output_component.c:475 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1: ref count = 1 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_components.c:116 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1: ref count = 0 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_components.c:117 Destroy 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1 requested: ref_count = 0 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_components.c:117 Destroying 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:1129 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 2 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:1473 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, done executing command | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:631 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 1 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_components.c:62 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0014'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1'/></iq> | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.621673 [DEBUG] rayo_components.c:115 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='unavailable'><complete xmlns='urn:xmpp:rayo:ext:1'><error xmlns='urn:xmpp:rayo:ext:complete:1'/></complete></presence> | |
2013-08-29 19:28:40.621673 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.621673 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0014'><ref xmlns='urn:xmpp:rayo:1' uri='xmpp:43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1'/></iq> | |
2013-08-29 19:28:40.621673 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94/output-1' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='unavailable'><complete xmlns='urn:xmpp:rayo:ext:1'><error xmlns='urn:xmpp:rayo:ext:complete:1'/></complete></presence> | |
2013-08-29 19:28:40.733340 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 RECV <iq type="set" to="43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94" id="blather0016"><hangup xmlns="urn:xmpp:rayo:1"/></iq> | |
2013-08-29 19:28:40.733340 [DEBUG] xmpp_streams.c:1058 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, state = READY, node type = NODE_NORMAL | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:3010 Lock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:2365 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, recv iq, availability = ONLINE | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:3069 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:624 Locate 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 2 | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:2368 Deliver 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 => 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 <iq type='set' to='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' id='blather0016' from='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><hangup xmlns='urn:xmpp:rayo:1'/></iq> | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:505 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, looking for CALL::set:urn:xmpp:rayo:1:hangup command | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:1471 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, executing command | |
2013-08-29 19:28:40.733340 [NOTICE] switch_ivr.c:3701 Hangup sofia/internal/1001@54.213.58.94 [CS_EXECUTE] [NORMAL_CLEARING] | |
2013-08-29 19:28:40.733340 [DEBUG] switch_channel.c:3135 Send signal sofia/internal/1001@54.213.58.94 [KILL] | |
2013-08-29 19:28:40.733340 [DEBUG] switch_core_session.c:1341 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:1473 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94, done executing command | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:631 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 1 | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:1480 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0016'/> | |
2013-08-29 19:28:40.733340 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.733340 [DEBUG] switch_core_session.c:2743 sofia/internal/1001@54.213.58.94 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) | |
2013-08-29 19:28:40.733340 [DEBUG] switch_core_state_machine.c:478 (sofia/internal/1001@54.213.58.94) State EXECUTE going to sleep | |
2013-08-29 19:28:40.733340 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/1001@54.213.58.94) Running State Change CS_HANGUP | |
2013-08-29 19:28:40.733340 [DEBUG] switch_core_state_machine.c:679 (sofia/internal/1001@54.213.58.94) State HANGUP | |
2013-08-29 19:28:40.733340 [DEBUG] mod_sofia.c:463 Channel sofia/internal/1001@54.213.58.94 hanging up, cause: NORMAL_CLEARING | |
2013-08-29 19:28:40.759078 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <iq from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='result' id='blather0016'/> | |
2013-08-29 19:28:40.759078 [DEBUG] mod_sofia.c:515 Sending BYE to sofia/internal/1001@54.213.58.94 | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:48 sofia/internal/1001@54.213.58.94 Standard HANGUP, cause: NORMAL_CLEARING | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:679 (sofia/internal/1001@54.213.58.94) State HANGUP going to sleep | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:692 (sofia/internal/1001@54.213.58.94) Callstate Change ACTIVE -> HANGUP | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:447 (sofia/internal/1001@54.213.58.94) State Change CS_HANGUP -> CS_REPORTING | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_session.c:1341 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:416 (sofia/internal/1001@54.213.58.94) Running State Change CS_REPORTING | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/1001@54.213.58.94) State REPORTING | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:92 sofia/internal/1001@54.213.58.94 Standard REPORTING, cause: NORMAL_CLEARING | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:764 (sofia/internal/1001@54.213.58.94) State REPORTING going to sleep | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:441 (sofia/internal/1001@54.213.58.94) State Change CS_REPORTING -> CS_DESTROY | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_session.c:1341 Send signal sofia/internal/1001@54.213.58.94 [BREAK] | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_session.c:1549 Session 23 (sofia/internal/1001@54.213.58.94) Locked, Waiting on external entities | |
2013-08-29 19:28:40.759078 [NOTICE] switch_core_session.c:1567 Session 23 (sofia/internal/1001@54.213.58.94) Ended | |
2013-08-29 19:28:40.759078 [NOTICE] switch_core_session.c:1571 Close Channel sofia/internal/1001@54.213.58.94 [CS_DESTROY] | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:566 (sofia/internal/1001@54.213.58.94) Callstate Change HANGUP -> DOWN | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/1001@54.213.58.94) Running State Change CS_DESTROY | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/1001@54.213.58.94) State DESTROY | |
2013-08-29 19:28:40.759078 [DEBUG] mod_sofia.c:373 sofia/internal/1001@54.213.58.94 SOFIA DESTROY | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:99 sofia/internal/1001@54.213.58.94 Standard DESTROY | |
2013-08-29 19:28:40.759078 [DEBUG] switch_core_state_machine.c:579 (sofia/internal/1001@54.213.58.94) State DESTROY going to sleep | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:2592 Locate 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 2 | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:2603 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 1 | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:2604 Destroy 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 requested: ref_count = 1 | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:2605 Unlock 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94: ref count = 0 | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:2605 Destroying 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:929 Sending <end> to offered client 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:930 Deliver 43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='unavailable'><end xmlns='urn:xmpp:rayo:1'><hangup-command/></end></presence> | |
2013-08-29 19:28:40.759078 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:28:40.770739 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <presence from='43db6fba-343c-6c55-0de0-8c3e2fe6b8c5@54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' type='unavailable'><end xmlns='urn:xmpp:rayo:1'><hangup-command/></end></presence> | |
2013-08-29 19:29:22.069305 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 RECV <iq type="get" to="54.213.58.94" id="blather0018"><ping xmlns="urn:xmpp:ping"/></iq> | |
2013-08-29 19:29:22.069305 [DEBUG] xmpp_streams.c:1058 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, state = READY, node type = NODE_NORMAL | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:3010 Lock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:2365 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670, recv iq, availability = ONLINE | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:3069 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:624 Locate 54.213.58.94: ref count = 2 | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:2368 Deliver 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 => 54.213.58.94 <iq type='get' to='54.213.58.94' id='blather0018' from='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670'><ping xmlns='urn:xmpp:ping'/></iq> | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:505 54.213.58.94, looking for SERVER::get:urn:xmpp:ping:ping command | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:631 Unlock 54.213.58.94: ref count = 1 | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:624 Locate 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 2 | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:1432 Deliver 54.213.58.94 => 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 <iq type='result' from='54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' id='blather0018'/> | |
2013-08-29 19:29:22.069305 [DEBUG] mod_rayo.c:631 Unlock 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670: ref count = 1 | |
2013-08-29 19:29:22.069305 [DEBUG] xmpp_streams.c:190 c2s_in 1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670 SEND <iq type='result' from='54.213.58.94' to='1@54.213.58.94/ip-172-31-41-127.us-west-2.compute.internal-2670' id='blather0018'/> |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment