Skip to content

Instantly share code, notes, and snippets.

@krakatoa
Created March 6, 2013 12:56
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save krakatoa/daf1051a085b3512ee56 to your computer and use it in GitHub Desktop.
Save krakatoa/daf1051a085b3512ee56 to your computer and use it in GitHub Desktop.
Dial issue log
[2013-03-06 09:48:56] INFO Adhearsion::Initializer: Adhearsion v2.2.1 initialized in "development"!
[2013-03-06 09:49:40] DEBUG Punchblock::Connection::Freeswitch: Starting the RubyFS stream
[2013-03-06 09:50:05] DEBUG RubyFS::Stream: Starting up...
[2013-03-06 09:50:05] INFO Adhearsion::PunchblockPlugin::Initializer: Connected to Punchblock server
[2013-03-06 09:50:05] INFO Adhearsion::Process: Transitioning from booting to running with 0 active calls due to booted event.
[2013-03-06 09:50:27] DEBUG Adhearsion::Call: : Receiving message: #<Punchblock::Event::Offer to="3@10.0.0.12", from=" <1000@10.0.0.10>", headers={:x_variable_direction=>"inbound", :x_variable_uuid=>"6b6a3a56-865c-11e2-b717-db70ade1bfb6", :x_variable_session_id=>"1", :x_variable_sip_from_user=>"1000", :x_variable_sip_from_uri=>"1000@10.0.0.10", :x_variable_sip_from_host=>"10.0.0.10", :x_variable_channel_name=>"sofia/internal/1000@10.0.0.10", :x_variable_sip_call_id=>"78ab630c-00ff-1231-978b-08002715f81e", :x_variable_sip_local_network_addr=>"10.0.0.12", :x_variable_sip_network_ip=>"10.0.0.10", :x_variable_sip_network_port=>"5080", :x_variable_sip_received_ip=>"10.0.0.10", :x_variable_sip_received_port=>"5080", :x_variable_sip_via_protocol=>"udp", :x_variable_sip_authorized=>"true", :x_variable_sip_acl_authed_by=>"domains", :x_variable_sip_from_user_stripped=>"1000", :x_variable_sip_from_tag=>"t7tg48mrt859c", :x_variable_sofia_profile_name=>"internal", :x_variable_recovery_profile_name=>"internal", :x_variable_sip_remote_party_id=>"\"Extension 1000\" <sip:1000@10.0.0.10>;party=calling;screen=yes;privacy=off", :x_variable_sip_cid_type=>"rpid", :x_variable_sip_full_via=>"SIP/2.0/UDP 10.0.0.10:5080;rport=5080;branch=z9hG4bK1ZZ733U1vc2KN", :x_variable_sip_from_display=>"Extension 1000", :x_variable_sip_full_from=>"\"Extension 1000\" <sip:1000@10.0.0.10>;tag=t7tg48mrt859c", :x_variable_sip_full_to=>"<sip:3@10.0.0.12>", :x_variable_sip_req_user=>"3", :x_variable_sip_req_uri=>"3@10.0.0.12", :x_variable_sip_req_host=>"10.0.0.12", :x_variable_sip_to_user=>"3", :x_variable_sip_to_uri=>"3@10.0.0.12", :x_variable_sip_to_host=>"10.0.0.12", :x_variable_sip_contact_params=>"transport=udp;gw=ivr_engine_out", :x_variable_sip_contact_user=>"gw+ivr_engine_out", :x_variable_sip_contact_port=>"5080", :x_variable_sip_contact_uri=>"gw+ivr_engine_out@10.0.0.10:5080", :x_variable_sip_contact_host=>"10.0.0.10", :x_variable_sip_user_agent=>"FreeSWITCH-mod_sofia/1.3.10b+git~20121210T195225Z~7ba3dadcd8", :x_variable_sip_via_host=>"10.0.0.10", :x_variable_sip_via_port=>"5080", :x_variable_sip_via_rport=>"5080", :x_variable_max_forwards=>"69", :x_variable_presence_id=>"1000@10.0.0.10", :x_variable_switch_r_sdp=>"v=0\r\no=- 3571563091 3571563091 IN IP4 10.0.0.3\r\ns=pjmedia\r\nc=IN IP4 10.0.0.3\r\nt=0 0\r\nm=audio 4022 RTP/AVP 18 102 105 8 101\r\nc=IN IP4 10.0.0.3\r\na=rtpmap:18 G729/8000\r\na=fmtp:18 annexb=no\r\na=rtpmap:102 ILBC/8000\r\na=fmtp:102 mode=30\r\na=rtpmap:105 speex/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=rtcp:4023 IN IP4 10.0.0.3\r\n", :x_variable_ep_codec_string=>"PCMA@8000h@20i@64000b", :x_variable_endpoint_disposition=>"DELAYED NEGOTIATION", :x_variable_dp_match=>"DELAYED NEGOTIATIONDELAYED NEGOTIATION", :x_variable_call_uuid=>"6b6a3a56-865c-11e2-b717-db70ade1bfb6", :x_variable_open=>"true", :x_variable_rfc2822_date=>"Wed, 06 Mar 2013 09:50:27 -0300", :x_variable_export_vars=>"RFC2822_DATE", :x_variable_current_application=>"park"}, call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6"
[2013-03-06 09:50:27] INFO Adhearsion::Router: Call 6b6a3a56-865c-11e2-b717-db70ade1bfb6 selected route "Inbound Call" (IvrController)
[2013-03-06 09:50:27] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Executing command #<Punchblock::Command::Accept headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-03-06 09:50:27] INFO Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Executing controller #<IvrController call=6b6a3a56-865c-11e2-b717-db70ade1bfb6, metadata={}>
[2013-03-06 09:50:27] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Receiving message: #<Punchblock::Event::Offer to="3@10.0.0.12", from=" <1000@10.0.0.10>", headers={:x_variable_direction=>"inbound", :x_variable_uuid=>"6b6a3a56-865c-11e2-b717-db70ade1bfb6", :x_variable_session_id=>"1", :x_variable_sip_from_user=>"1000", :x_variable_sip_from_uri=>"1000@10.0.0.10", :x_variable_sip_from_host=>"10.0.0.10", :x_variable_channel_name=>"sofia/internal/1000@10.0.0.10", :x_variable_sip_call_id=>"78ab630c-00ff-1231-978b-08002715f81e", :x_variable_sip_local_network_addr=>"10.0.0.12", :x_variable_sip_network_ip=>"10.0.0.10", :x_variable_sip_network_port=>"5080", :x_variable_sip_received_ip=>"10.0.0.10", :x_variable_sip_received_port=>"5080", :x_variable_sip_via_protocol=>"udp", :x_variable_sip_authorized=>"true", :x_variable_sip_acl_authed_by=>"domains", :x_variable_sip_from_user_stripped=>"1000", :x_variable_sip_from_tag=>"t7tg48mrt859c", :x_variable_sofia_profile_name=>"internal", :x_variable_recovery_profile_name=>"internal", :x_variable_sip_remote_party_id=>"\"Extension 1000\" <sip:1000@10.0.0.10>;party=calling;screen=yes;privacy=off", :x_variable_sip_cid_type=>"rpid", :x_variable_sip_full_via=>"SIP/2.0/UDP 10.0.0.10:5080;rport=5080;branch=z9hG4bK1ZZ733U1vc2KN", :x_variable_sip_from_display=>"Extension 1000", :x_variable_sip_full_from=>"\"Extension 1000\" <sip:1000@10.0.0.10>;tag=t7tg48mrt859c", :x_variable_sip_full_to=>"<sip:3@10.0.0.12>", :x_variable_sip_req_user=>"3", :x_variable_sip_req_uri=>"3@10.0.0.12", :x_variable_sip_req_host=>"10.0.0.12", :x_variable_sip_to_user=>"3", :x_variable_sip_to_uri=>"3@10.0.0.12", :x_variable_sip_to_host=>"10.0.0.12", :x_variable_sip_contact_params=>"transport=udp;gw=ivr_engine_out", :x_variable_sip_contact_user=>"gw+ivr_engine_out", :x_variable_sip_contact_port=>"5080", :x_variable_sip_contact_uri=>"gw+ivr_engine_out@10.0.0.10:5080", :x_variable_sip_contact_host=>"10.0.0.10", :x_variable_sip_user_agent=>"FreeSWITCH-mod_sofia/1.3.10b+git~20121210T195225Z~7ba3dadcd8", :x_variable_sip_via_host=>"10.0.0.10", :x_variable_sip_via_port=>"5080", :x_variable_sip_via_rport=>"5080", :x_variable_max_forwards=>"69", :x_variable_presence_id=>"1000@10.0.0.10", :x_variable_switch_r_sdp=>"v=0\r\no=- 3571563091 3571563091 IN IP4 10.0.0.3\r\ns=pjmedia\r\nc=IN IP4 10.0.0.3\r\nt=0 0\r\nm=audio 4022 RTP/AVP 18 102 105 8 101\r\nc=IN IP4 10.0.0.3\r\na=rtpmap:18 G729/8000\r\na=fmtp:18 annexb=no\r\na=rtpmap:102 ILBC/8000\r\na=fmtp:102 mode=30\r\na=rtpmap:105 speex/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:101 telephone-event/8000\r\na=fmtp:101 0-15\r\na=rtcp:4023 IN IP4 10.0.0.3\r\n", :x_variable_ep_codec_string=>"PCMA@8000h@20i@64000b", :x_variable_endpoint_disposition=>"DELAYED NEGOTIATION", :x_variable_dp_match=>"DELAYED NEGOTIATIONDELAYED NEGOTIATION", :x_variable_call_uuid=>"6b6a3a56-865c-11e2-b717-db70ade1bfb6", :x_variable_open=>"true", :x_variable_rfc2822_date=>"Wed, 06 Mar 2013 09:50:27 -0300", :x_variable_export_vars=>"RFC2822_DATE", :x_variable_current_application=>"park"}, call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6"
[2013-03-06 09:50:27] DEBUG Adhearsion::ActiveRecord::Plugin::Service: Ivr Load (4.1ms) SELECT `ivrs`.* FROM `ivrs` WHERE `ivrs`.`id` = ? LIMIT 1 [["id", "3"]]
[2013-03-06 09:50:28] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Executing command #<Punchblock::Command::Answer headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-03-06 09:50:28] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Receiving message: #<Punchblock::Event::Answered headers_hash={}, target_call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6", component_id=nil, target_mixer_name=nil>
[2013-03-06 09:50:28] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Executing command #<Punchblock::Component::Input mode=:dtmf, terminator=nil, recognizer=nil, initial_timeout=30000, inter_digit_timeout=30000, sensitivity=nil, min_confidence=nil, grammar=#<Punchblock::Component::Input::Grammar content_type="application/srgs+xml", value=<grammar xmlns="http://www.w3.org/2001/06/grammar" version="1.0" xml:lang="en-US" mode="dtmf" root="inputdigits">
<rule id="inputdigits" scope="public">
<one-of>
<item>0</item>
<item>1</item>
<item>2</item>
<item>3</item>
<item>4</item>
<item>5</item>
<item>6</item>
<item>7</item>
<item>8</item>
<item>9</item>
<item>#</item>
<item>*</item>
</one-of>
</rule>
</grammar>, url=nil, target_call_id=nil, component_id=nil, target_mixer_name=nil>, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-03-06 09:50:30] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Receiving message: #<Punchblock::Event::Complete reason=#<Punchblock::Component::Input::Complete::Success mode=:dtmf, confidence=1.0, interpretation="dtmf-1", utterance="1", name=:success, target_call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6", component_id="61b6055e-fb5a-4a5a-9612-d66096d84394", target_mixer_name=nil>, recording=nil, target_call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6", component_id="61b6055e-fb5a-4a5a-9612-d66096d84394", target_mixer_name=nil>
[2013-03-06 09:50:30] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Menu received valid input (1). Calling the matching hook.
[2013-03-06 09:50:30] WARN Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: ["sofia/gateway/re_engine_out/1001"]
[2013-03-06 09:50:30] WARN Adhearsion::CallController::Dial::Dial: #dial call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6
[2013-03-06 09:50:30] WARN Adhearsion::CallController::Dial::Dial: #dial targets: ["sofia/gateway/re_engine_out/1001"]
[2013-03-06 09:50:30] WARN Adhearsion::CallController::Dial::Dial: #dial calls: [[#<Adhearsion::OutboundCall: offer=nil, end_reason=nil, commands=[], variables={}, controllers=[], to=nil, from=nil>, "sofia/gateway/re_engine_out/1001", nil]]
[2013-03-06 09:50:30] WARN Adhearsion::CallController::Dial::Dial: #dial calling: "sofia/gateway/re_engine_out/1001"
[2013-03-06 09:50:30] DEBUG Adhearsion::OutboundCall: : Executing command #<Punchblock::Command::Dial to="sofia/gateway/re_engine_out/1001", from="1000", join=nil, headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-03-06 09:50:30] WARN Adhearsion::CallController::Dial::Dial: #dial awaiting_completion
[2013-03-06 09:50:30] WARN Adhearsion::CallController::Dial::Dial: #dial awaiting_completion: no_answer
[2013-03-06 09:50:33] DEBUG Adhearsion::OutboundCall: dc12f524-0ff3-4d14-8e58-9e0487c6727b: Receiving message: #<Punchblock::Event::Answered headers_hash={}, target_call_id="dc12f524-0ff3-4d14-8e58-9e0487c6727b", component_id=nil, target_mixer_name=nil>
[2013-03-06 09:50:33] WARN Adhearsion::CallController::Dial::Dial: #dial joining call dc12f524-0ff3-4d14-8e58-9e0487c6727b to 6b6a3a56-865c-11e2-b717-db70ade1bfb6
[2013-03-06 09:50:33] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Executing command #<Punchblock::Command::Answer headers_hash={}, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-03-06 09:50:33] DEBUG Adhearsion::OutboundCall: dc12f524-0ff3-4d14-8e58-9e0487c6727b: Executing command #<Punchblock::Command::Join call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6", mixer_name=nil, direction=nil, media=nil, target_call_id=nil, component_id=nil, target_mixer_name=nil, state_name=:new>
[2013-03-06 09:50:33] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Receiving message: #<Punchblock::Event::Joined call_id="dc12f524-0ff3-4d14-8e58-9e0487c6727b", mixer_name=nil, target_call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6", component_id=nil, target_mixer_name=nil>
[2013-03-06 09:50:33] DEBUG Adhearsion::OutboundCall: dc12f524-0ff3-4d14-8e58-9e0487c6727b: Receiving message: #<Punchblock::Event::Joined call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6", mixer_name=nil, target_call_id="dc12f524-0ff3-4d14-8e58-9e0487c6727b", component_id=nil, target_mixer_name=nil>
[2013-03-06 09:50:34] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Receiving message: #<Punchblock::Event::End reason=:hangup, headers_hash={}, target_call_id="6b6a3a56-865c-11e2-b717-db70ade1bfb6", component_id=nil, target_mixer_name=nil>
[2013-03-06 09:50:34] INFO Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Call ended
[2013-03-06 09:50:34] DEBUG Adhearsion::OutboundCall: dc12f524-0ff3-4d14-8e58-9e0487c6727b: Receiving message: #<Punchblock::Event::End reason=:hangup, headers_hash={}, target_call_id="dc12f524-0ff3-4d14-8e58-9e0487c6727b", component_id=nil, target_mixer_name=nil>
[2013-03-06 09:50:34] INFO Adhearsion::OutboundCall: dc12f524-0ff3-4d14-8e58-9e0487c6727b: Call ended
[2013-03-06 09:50:34] WARN Adhearsion::CallController::Dial::Dial: #dial finished. Hanging up 1 outbound calls: dc12f524-0ff3-4d14-8e58-9e0487c6727b.
[2013-03-06 09:50:34] WARN Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: ANSWER: success: IvrPlan::Play
[2013-03-06 09:50:34] INFO Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Call was hung up
[2013-03-06 09:50:34] DEBUG Adhearsion::Call: 6b6a3a56-865c-11e2-b717-db70ade1bfb6: Finished executing controller #<IvrController call=6b6a3a56-865c-11e2-b717-db70ade1bfb6, metadata={}>
2013-03-06 09:50:02.391515 [NOTICE] switch_loadable_module.c:298 Adding API Function 'console'
2013-03-06 09:50:02.391836 [NOTICE] switch_loadable_module.c:214 Adding Dialplan 'enum'
2013-03-06 09:50:02.391836 [NOTICE] switch_loadable_module.c:254 Adding Application 'enum'
2013-03-06 09:50:02.391836 [NOTICE] switch_loadable_module.c:298 Adding API Function 'enum'
2013-03-06 09:50:02.391871 [NOTICE] switch_loadable_module.c:298 Adding API Function 'enum_auto'
2013-03-06 09:50:02.391982 [DEBUG] mod_cdr_csv.c:339 Adding default template.
2013-03-06 09:50:02.391995 [DEBUG] mod_cdr_csv.c:386 Adding template sql.
2013-03-06 09:50:02.392001 [DEBUG] mod_cdr_csv.c:386 Adding template example.
2013-03-06 09:50:02.392006 [DEBUG] mod_cdr_csv.c:386 Adding template snom.
2013-03-06 09:50:02.392010 [DEBUG] mod_cdr_csv.c:386 Adding template linksys.
2013-03-06 09:50:02.392017 [DEBUG] mod_cdr_csv.c:386 Adding template asterisk.
2013-03-06 09:50:02.392024 [DEBUG] mod_cdr_csv.c:386 Adding template opencdrrate.
2013-03-06 09:50:02.392047 [NOTICE] switch_loadable_module.c:298 Adding API Function 'cdr_csv'
2013-03-06 09:50:02.392249 [NOTICE] switch_loadable_module.c:254 Adding Application 'socket'
2013-03-06 09:50:02.392297 [NOTICE] switch_loadable_module.c:298 Adding API Function 'event_sink'
2013-03-06 09:50:02.392812 [INFO] mod_sofia.c:5727 Starting initial message thread.
2013-03-06 09:50:02.392937 [DEBUG] sofia.c:3600 debug [0]
2013-03-06 09:50:02.392945 [DEBUG] sofia.c:3600 sip-trace [no]
2013-03-06 09:50:02.392960 [DEBUG] sofia.c:3600 sip-capture [no]
2013-03-06 09:50:02.392966 [DEBUG] sofia.c:3600 rfc2833-pt [101]
2013-03-06 09:50:02.392971 [DEBUG] sofia.c:3600 sip-port [5080]
2013-03-06 09:50:02.392975 [DEBUG] sofia.c:3600 dialplan [XML]
2013-03-06 09:50:02.392981 [DEBUG] sofia.c:3600 context [public]
2013-03-06 09:50:02.392986 [DEBUG] sofia.c:3600 dtmf-duration [2000]
2013-03-06 09:50:02.392991 [DEBUG] sofia.c:3600 inbound-codec-prefs [G722,PCMU,PCMA,GSM]
2013-03-06 09:50:02.392996 [DEBUG] sofia.c:3600 outbound-codec-prefs [PCMU,PCMA,GSM]
2013-03-06 09:50:02.393001 [DEBUG] sofia.c:3600 hold-music [local_stream://moh]
2013-03-06 09:50:02.393008 [DEBUG] sofia.c:3600 rtp-timer-name [soft]
2013-03-06 09:50:02.393013 [DEBUG] sofia.c:3600 local-network-acl [localnet.auto]
2013-03-06 09:50:02.393018 [DEBUG] sofia.c:3600 manage-presence [false]
2013-03-06 09:50:02.393024 [DEBUG] sofia.c:3600 inbound-codec-negotiation [generous]
2013-03-06 09:50:02.393029 [DEBUG] sofia.c:3600 nonce-ttl [60]
2013-03-06 09:50:02.393034 [DEBUG] sofia.c:3600 auth-calls [false]
2013-03-06 09:50:02.393039 [DEBUG] sofia.c:3600 inbound-late-negotiation [true]
2013-03-06 09:50:02.393043 [DEBUG] sofia.c:3600 inbound-zrtp-passthru [true]
2013-03-06 09:50:02.393047 [DEBUG] sofia.c:3600 rtp-ip [10.0.0.12]
2013-03-06 09:50:02.393052 [DEBUG] sofia.c:3600 sip-ip [10.0.0.12]
2013-03-06 09:50:02.393057 [DEBUG] sofia.c:3600 ext-rtp-ip [10.0.0.12]
2013-03-06 09:50:02.393062 [DEBUG] sofia.c:3600 ext-sip-ip [10.0.0.12]
2013-03-06 09:50:02.393070 [DEBUG] sofia.c:3600 rtp-timeout-sec [300]
2013-03-06 09:50:02.393075 [DEBUG] sofia.c:3600 rtp-hold-timeout-sec [1800]
2013-03-06 09:50:02.393079 [DEBUG] sofia.c:3600 tls [false]
2013-03-06 09:50:02.393085 [DEBUG] sofia.c:3600 tls-only [false]
2013-03-06 09:50:02.393090 [DEBUG] sofia.c:3600 tls-bind-params [transport=tls]
2013-03-06 09:50:02.393095 [DEBUG] sofia.c:3600 tls-sip-port [5081]
2013-03-06 09:50:02.393100 [DEBUG] sofia.c:3600 tls-cert-dir [/usr/local/freeswitch/conf/ssl]
2013-03-06 09:50:02.393105 [DEBUG] sofia.c:3600 tls-passphrase []
2013-03-06 09:50:02.393110 [DEBUG] sofia.c:3600 tls-verify-date [true]
2013-03-06 09:50:02.393115 [DEBUG] sofia.c:3600 tls-verify-policy [none]
2013-03-06 09:50:02.393122 [DEBUG] sofia.c:3600 tls-verify-depth [2]
2013-03-06 09:50:02.393127 [DEBUG] sofia.c:3600 tls-verify-in-subjects []
2013-03-06 09:50:02.393133 [DEBUG] sofia.c:3600 tls-version [tlsv1]
2013-03-06 09:50:02.393204 [DEBUG] sofia.c:2322 Creating agent for external
2013-03-06 09:50:02.400013 [DEBUG] sofia.c:2412 Created agent for external
2013-03-06 09:50:02.400050 [DEBUG] sofia.c:2455 Set params for external
2013-03-06 09:50:02.400057 [DEBUG] sofia.c:2499 Activated db for external
2013-03-06 09:50:02.400556 [INFO] switch_core_sqldb.c:1423 sofia:external Starting SQL thread.
2013-03-06 09:50:02.400655 [DEBUG] sofia.c:2537 Starting thread for external
2013-03-06 09:50:02.400663 [DEBUG] sofia.c:2222 Launching worker thread for external
2013-03-06 09:50:02.400723 [NOTICE] sofia_reg.c:3007 Added gateway 're_engine_out' to profile 'external'
2013-03-06 09:50:02.400745 [NOTICE] sofia_reg.c:3007 Added gateway 'sansayPSTNLA_out' to profile 'external'
2013-03-06 09:50:02.400764 [NOTICE] sofia_reg.c:3007 Added gateway 'sansayPSTNNY_out' to profile 'external'
2013-03-06 09:50:02.400807 [NOTICE] sofia_reg.c:3007 Added gateway 'example.com' to profile 'external'
2013-03-06 09:50:02.401041 [NOTICE] sofia.c:4666 Started Profile external [sofia_reg_external]
2013-03-06 09:50:02.401057 [DEBUG] sofia.c:3600 debug [0]
2013-03-06 09:50:02.401061 [DEBUG] sofia.c:3600 sip-trace [no]
2013-03-06 09:50:02.401069 [DEBUG] sofia.c:3600 context [public]
2013-03-06 09:50:02.401073 [DEBUG] sofia.c:3600 rfc2833-pt [101]
2013-03-06 09:50:02.401076 [DEBUG] sofia.c:3600 sip-port [5060]
2013-03-06 09:50:02.401079 [DEBUG] sofia.c:3600 dialplan [XML]
2013-03-06 09:50:02.401083 [DEBUG] sofia.c:3600 dtmf-duration [2000]
2013-03-06 09:50:02.401087 [DEBUG] sofia.c:3600 inbound-codec-prefs [G722,PCMU,PCMA,GSM]
2013-03-06 09:50:02.401091 [DEBUG] sofia.c:3600 outbound-codec-prefs [G722,PCMU,PCMA,GSM]
2013-03-06 09:50:02.401095 [DEBUG] sofia.c:3600 use-rtp-timer [true]
2013-03-06 09:50:02.401099 [DEBUG] sofia.c:3600 rtp-timer-name [soft]
2013-03-06 09:50:02.401102 [DEBUG] sofia.c:3600 rtp-ip [::1]
2013-03-06 09:50:02.401105 [DEBUG] sofia.c:3600 sip-ip [::1]
2013-03-06 09:50:02.401109 [DEBUG] sofia.c:3600 hold-music [local_stream://moh]
2013-03-06 09:50:02.401112 [DEBUG] sofia.c:3600 apply-inbound-acl [domains]
2013-03-06 09:50:02.401116 [DEBUG] sofia.c:3600 record-template [/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav]
2013-03-06 09:50:02.401119 [DEBUG] sofia.c:3600 manage-presence [true]
2013-03-06 09:50:02.401123 [DEBUG] sofia.c:3600 inbound-codec-negotiation [generous]
2013-03-06 09:50:02.401127 [DEBUG] sofia.c:3600 tls [false]
2013-03-06 09:50:02.401131 [DEBUG] sofia.c:3600 tls-bind-params [transport=tls]
2013-03-06 09:50:02.401134 [DEBUG] sofia.c:3600 tls-sip-port [5061]
2013-03-06 09:50:02.401137 [DEBUG] sofia.c:3600 tls-cert-dir [/usr/local/freeswitch/conf/ssl]
2013-03-06 09:50:02.401141 [DEBUG] sofia.c:3600 tls-version [tlsv1]
2013-03-06 09:50:02.401144 [DEBUG] sofia.c:3600 inbound-late-negotiation [true]
2013-03-06 09:50:02.401147 [DEBUG] sofia.c:3600 inbound-zrtp-passthru [true]
2013-03-06 09:50:02.401150 [DEBUG] sofia.c:3600 nonce-ttl [60]
2013-03-06 09:50:02.401153 [DEBUG] sofia.c:3600 auth-calls [true]
2013-03-06 09:50:02.401156 [DEBUG] sofia.c:3600 auth-all-packets [false]
2013-03-06 09:50:02.401159 [DEBUG] sofia.c:3600 rtp-timeout-sec [300]
2013-03-06 09:50:02.401162 [DEBUG] sofia.c:3600 rtp-hold-timeout-sec [1800]
2013-03-06 09:50:02.401165 [DEBUG] sofia.c:3600 force-register-domain [10.0.0.12]
2013-03-06 09:50:02.401169 [DEBUG] sofia.c:3600 force-register-db-domain [10.0.0.12]
2013-03-06 09:50:02.401233 [DEBUG] sofia.c:2322 Creating agent for internal-ipv6
2013-03-06 09:50:02.404910 [DEBUG] sofia.c:2412 Created agent for internal-ipv6
2013-03-06 09:50:02.404935 [DEBUG] sofia.c:2455 Set params for internal-ipv6
2013-03-06 09:50:02.404939 [DEBUG] sofia.c:2499 Activated db for internal-ipv6
2013-03-06 09:50:02.405663 [INFO] switch_core_sqldb.c:1423 sofia:internal-ipv6 Starting SQL thread.
2013-03-06 09:50:02.405938 [DEBUG] sofia.c:2537 Starting thread for internal-ipv6
2013-03-06 09:50:02.405968 [DEBUG] sofia.c:2222 Launching worker thread for internal-ipv6
2013-03-06 09:50:02.406249 [NOTICE] sofia.c:4666 Started Profile internal-ipv6 [sofia_reg_internal-ipv6]
2013-03-06 09:50:02.406267 [DEBUG] sofia.c:3600 debug [3]
2013-03-06 09:50:02.406271 [DEBUG] sofia.c:3600 sip-trace [yes]
2013-03-06 09:50:02.406274 [DEBUG] sofia.c:3600 sip-capture [no]
2013-03-06 09:50:02.406283 [DEBUG] sofia.c:3600 watchdog-enabled [no]
2013-03-06 09:50:02.406287 [DEBUG] sofia.c:3600 watchdog-step-timeout [30000]
2013-03-06 09:50:02.406290 [DEBUG] sofia.c:3600 watchdog-event-timeout [30000]
2013-03-06 09:50:02.406293 [DEBUG] sofia.c:3600 log-auth-failures [false]
2013-03-06 09:50:02.406296 [DEBUG] sofia.c:3600 forward-unsolicited-mwi-notify [false]
2013-03-06 09:50:02.406299 [DEBUG] sofia.c:3600 context [default]
2013-03-06 09:50:02.406302 [DEBUG] sofia.c:3600 rfc2833-pt [101]
2013-03-06 09:50:02.406305 [DEBUG] sofia.c:3600 sip-port [5060]
2013-03-06 09:50:02.406308 [DEBUG] sofia.c:3600 dialplan [XML]
2013-03-06 09:50:02.406311 [DEBUG] sofia.c:3600 dtmf-duration [2000]
2013-03-06 09:50:02.406315 [DEBUG] sofia.c:3600 inbound-codec-prefs [G722,PCMU,PCMA,GSM]
2013-03-06 09:50:02.406319 [DEBUG] sofia.c:3600 outbound-codec-prefs [G722,PCMU,PCMA,GSM]
2013-03-06 09:50:02.406322 [DEBUG] sofia.c:3600 rtp-timer-name [soft]
2013-03-06 09:50:02.406326 [DEBUG] sofia.c:3600 rtp-ip [10.0.0.12]
2013-03-06 09:50:02.406329 [DEBUG] sofia.c:3600 sip-ip [10.0.0.12]
2013-03-06 09:50:02.406332 [DEBUG] sofia.c:3600 hold-music [local_stream://moh]
2013-03-06 09:50:02.406336 [DEBUG] sofia.c:3600 apply-nat-acl [nat.auto]
2013-03-06 09:50:02.406349 [ERR] sofia.c:4303 Not adding acl nat.auto because it's the local network
2013-03-06 09:50:02.406352 [DEBUG] sofia.c:3600 apply-inbound-acl [domains]
2013-03-06 09:50:02.406356 [DEBUG] sofia.c:3600 local-network-acl [localnet.auto]
2013-03-06 09:50:02.406359 [DEBUG] sofia.c:3600 record-path [/usr/local/freeswitch/recordings]
2013-03-06 09:50:02.406363 [DEBUG] sofia.c:3600 record-template [${caller_id_number}.${target_domain}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav]
2013-03-06 09:50:02.406366 [DEBUG] sofia.c:3600 manage-presence [true]
2013-03-06 09:50:02.406369 [DEBUG] sofia.c:3600 presence-hosts [10.0.0.12,10.0.0.12]
2013-03-06 09:50:02.406372 [DEBUG] sofia.c:3600 presence-privacy [false]
2013-03-06 09:50:02.406376 [DEBUG] sofia.c:3600 inbound-codec-negotiation [generous]
2013-03-06 09:50:02.406379 [DEBUG] sofia.c:3600 tls [false]
2013-03-06 09:50:02.406382 [DEBUG] sofia.c:3600 tls-only [false]
2013-03-06 09:50:02.406386 [DEBUG] sofia.c:3600 tls-bind-params [transport=tls]
2013-03-06 09:50:02.406389 [DEBUG] sofia.c:3600 tls-sip-port [5061]
2013-03-06 09:50:02.406393 [DEBUG] sofia.c:3600 tls-cert-dir [/usr/local/freeswitch/conf/ssl]
2013-03-06 09:50:02.406396 [DEBUG] sofia.c:3600 tls-passphrase []
2013-03-06 09:50:02.406400 [DEBUG] sofia.c:3600 tls-verify-date [true]
2013-03-06 09:50:02.406403 [DEBUG] sofia.c:3600 tls-verify-policy [none]
2013-03-06 09:50:02.406407 [DEBUG] sofia.c:3600 tls-verify-depth [2]
2013-03-06 09:50:02.406411 [DEBUG] sofia.c:3600 tls-verify-in-subjects []
2013-03-06 09:50:02.406414 [DEBUG] sofia.c:3600 tls-version [tlsv1]
2013-03-06 09:50:02.406417 [DEBUG] sofia.c:3600 inbound-late-negotiation [true]
2013-03-06 09:50:02.406420 [DEBUG] sofia.c:3600 inbound-zrtp-passthru [true]
2013-03-06 09:50:02.406423 [DEBUG] sofia.c:3600 nonce-ttl [60]
2013-03-06 09:50:02.406426 [DEBUG] sofia.c:3600 auth-calls [true]
2013-03-06 09:50:02.406429 [DEBUG] sofia.c:3600 inbound-reg-force-matching-username [true]
2013-03-06 09:50:02.406433 [DEBUG] sofia.c:3600 auth-all-packets [false]
2013-03-06 09:50:02.406436 [DEBUG] sofia.c:3600 ext-rtp-ip [10.0.0.12]
2013-03-06 09:50:02.406439 [DEBUG] sofia.c:3600 ext-sip-ip [10.0.0.12]
2013-03-06 09:50:02.406442 [DEBUG] sofia.c:3600 rtp-timeout-sec [300]
2013-03-06 09:50:02.406445 [DEBUG] sofia.c:3600 rtp-hold-timeout-sec [1800]
2013-03-06 09:50:02.406449 [DEBUG] sofia.c:3600 force-register-domain [10.0.0.12]
2013-03-06 09:50:02.406452 [DEBUG] sofia.c:3600 force-subscription-domain [10.0.0.12]
2013-03-06 09:50:02.406454 [DEBUG] sofia.c:3600 force-register-db-domain [10.0.0.12]
2013-03-06 09:50:02.406458 [DEBUG] sofia.c:3600 challenge-realm [auto_from]
2013-03-06 09:50:02.406523 [DEBUG] sofia.c:2322 Creating agent for internal
2013-03-06 09:50:02.410354 [DEBUG] sofia.c:2412 Created agent for internal
2013-03-06 09:50:02.410375 [DEBUG] sofia.c:2455 Set params for internal
2013-03-06 09:50:02.410379 [DEBUG] sofia.c:2499 Activated db for internal
2013-03-06 09:50:02.411010 [INFO] switch_core_sqldb.c:1423 sofia:internal Starting SQL thread.
2013-03-06 09:50:02.411258 [DEBUG] sofia.c:2537 Starting thread for internal
2013-03-06 09:50:02.411262 [DEBUG] sofia.c:2222 Launching worker thread for internal
2013-03-06 09:50:02.411310 [NOTICE] sofia.c:3249 Adding Alias [10.0.0.12] for profile [internal]
2013-03-06 09:50:02.411526 [NOTICE] sofia.c:4666 Started Profile internal [sofia_reg_internal]
2013-03-06 09:50:02.411538 [DEBUG] mod_sofia.c:5740 Waiting for profiles to start
2013-03-06 09:50:02.389246 [NOTICE] switch_scheduler.c:166 Starting task thread
2013-03-06 09:50:02.413148 [WARNING] sofia.c:1602 MSG Thread 0 Started
2013-03-06 09:50:03.912225 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'sofia'
2013-03-06 09:50:03.912318 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'rtp'
2013-03-06 09:50:03.912377 [NOTICE] switch_loadable_module.c:254 Adding Application 'sofia_sla'
2013-03-06 09:50:03.912437 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sofia'
2013-03-06 09:50:03.912495 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sofia_gateway_data'
2013-03-06 09:50:03.912556 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sofia_username_of'
2013-03-06 09:50:03.912615 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sofia_contact'
2013-03-06 09:50:03.912676 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sofia_count_reg'
2013-03-06 09:50:03.912735 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sofia_dig'
2013-03-06 09:50:03.912792 [NOTICE] switch_loadable_module.c:405 Adding Chat interface 'sip'
2013-03-06 09:50:03.912848 [NOTICE] switch_loadable_module.c:449 Adding Management interface 'mod_sofia' OID[.1.3.6.1.4.1.27880.1001]
2013-03-06 09:50:03.914038 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'loopback'
2013-03-06 09:50:03.914112 [NOTICE] switch_loadable_module.c:254 Adding Application 'unloop'
2013-03-06 09:50:03.915947 [NOTICE] switch_loadable_module.c:298 Adding API Function 'acl'
2013-03-06 09:50:03.916021 [NOTICE] switch_loadable_module.c:298 Adding API Function 'alias'
2013-03-06 09:50:03.916090 [NOTICE] switch_loadable_module.c:298 Adding API Function 'banner'
2013-03-06 09:50:03.916149 [NOTICE] switch_loadable_module.c:298 Adding API Function 'bgapi'
2013-03-06 09:50:03.916215 [NOTICE] switch_loadable_module.c:298 Adding API Function 'bg_system'
2013-03-06 09:50:03.916294 [NOTICE] switch_loadable_module.c:298 Adding API Function 'break'
2013-03-06 09:50:03.916356 [NOTICE] switch_loadable_module.c:298 Adding API Function 'complete'
2013-03-06 09:50:03.916409 [NOTICE] switch_loadable_module.c:298 Adding API Function 'cond'
2013-03-06 09:50:03.916409 [NOTICE] switch_loadable_module.c:298 Adding API Function 'console_complete'
2013-03-06 09:50:03.916536 [NOTICE] switch_loadable_module.c:298 Adding API Function 'console_complete_xml'
2013-03-06 09:50:03.916647 [NOTICE] switch_loadable_module.c:298 Adding API Function 'create_uuid'
2013-03-06 09:50:03.916713 [NOTICE] switch_loadable_module.c:298 Adding API Function 'db_cache'
2013-03-06 09:50:03.916771 [NOTICE] switch_loadable_module.c:298 Adding API Function 'domain_exists'
2013-03-06 09:50:03.916829 [NOTICE] switch_loadable_module.c:298 Adding API Function 'echo'
2013-03-06 09:50:03.916885 [NOTICE] switch_loadable_module.c:298 Adding API Function 'escape'
2013-03-06 09:50:03.916942 [NOTICE] switch_loadable_module.c:298 Adding API Function 'eval'
2013-03-06 09:50:03.916999 [NOTICE] switch_loadable_module.c:298 Adding API Function 'expand'
2013-03-06 09:50:03.917056 [NOTICE] switch_loadable_module.c:298 Adding API Function 'find_user_xml'
2013-03-06 09:50:03.917114 [NOTICE] switch_loadable_module.c:298 Adding API Function 'fsctl'
2013-03-06 09:50:03.917179 [NOTICE] switch_loadable_module.c:298 Adding API Function '...'
2013-03-06 09:50:03.917236 [NOTICE] switch_loadable_module.c:298 Adding API Function 'shutdown'
2013-03-06 09:50:03.917292 [NOTICE] switch_loadable_module.c:298 Adding API Function 'version'
2013-03-06 09:50:03.917355 [NOTICE] switch_loadable_module.c:298 Adding API Function 'global_getvar'
2013-03-06 09:50:03.917417 [NOTICE] switch_loadable_module.c:298 Adding API Function 'global_setvar'
2013-03-06 09:50:03.917477 [NOTICE] switch_loadable_module.c:298 Adding API Function 'group_call'
2013-03-06 09:50:03.917623 [NOTICE] switch_loadable_module.c:298 Adding API Function 'help'
2013-03-06 09:50:03.917985 [NOTICE] switch_loadable_module.c:298 Adding API Function 'host_lookup'
2013-03-06 09:50:03.918237 [NOTICE] switch_loadable_module.c:298 Adding API Function 'hostname'
2013-03-06 09:50:03.918432 [NOTICE] switch_loadable_module.c:298 Adding API Function 'switchname'
2013-03-06 09:50:03.918598 [NOTICE] switch_loadable_module.c:298 Adding API Function 'hupall'
2013-03-06 09:50:03.918748 [NOTICE] switch_loadable_module.c:298 Adding API Function 'in_group'
2013-03-06 09:50:03.918890 [NOTICE] switch_loadable_module.c:298 Adding API Function 'is_lan_addr'
2013-03-06 09:50:03.919021 [NOTICE] switch_loadable_module.c:298 Adding API Function 'limit_usage'
2013-03-06 09:50:03.919152 [NOTICE] switch_loadable_module.c:298 Adding API Function 'limit_hash_usage'
2013-03-06 09:50:03.919284 [NOTICE] switch_loadable_module.c:298 Adding API Function 'limit_status'
2013-03-06 09:50:03.919415 [NOTICE] switch_loadable_module.c:298 Adding API Function 'limit_reset'
2013-03-06 09:50:03.919545 [NOTICE] switch_loadable_module.c:298 Adding API Function 'limit_interval_reset'
2013-03-06 09:50:03.919707 [NOTICE] switch_loadable_module.c:298 Adding API Function 'list_users'
2013-03-06 09:50:03.919778 [NOTICE] switch_loadable_module.c:298 Adding API Function 'load'
2013-03-06 09:50:03.919838 [NOTICE] switch_loadable_module.c:298 Adding API Function 'log'
2013-03-06 09:50:03.919894 [NOTICE] switch_loadable_module.c:298 Adding API Function 'md5'
2013-03-06 09:50:03.919951 [NOTICE] switch_loadable_module.c:298 Adding API Function 'module_exists'
2013-03-06 09:50:03.920009 [NOTICE] switch_loadable_module.c:298 Adding API Function 'msleep'
2013-03-06 09:50:03.920066 [NOTICE] switch_loadable_module.c:298 Adding API Function 'nat_map'
2013-03-06 09:50:03.920124 [NOTICE] switch_loadable_module.c:298 Adding API Function 'originate'
2013-03-06 09:50:03.920127 [NOTICE] switch_loadable_module.c:298 Adding API Function 'pause'
2013-03-06 09:50:03.920220 [NOTICE] switch_loadable_module.c:298 Adding API Function 'regex'
2013-03-06 09:50:03.920352 [NOTICE] switch_loadable_module.c:298 Adding API Function 'reloadacl'
2013-03-06 09:50:03.920481 [NOTICE] switch_loadable_module.c:298 Adding API Function 'reload'
2013-03-06 09:50:03.920612 [NOTICE] switch_loadable_module.c:298 Adding API Function 'reloadxml'
2013-03-06 09:50:03.920742 [NOTICE] switch_loadable_module.c:298 Adding API Function 'replace'
2013-03-06 09:50:03.920872 [NOTICE] switch_loadable_module.c:298 Adding API Function 'say_string'
2013-03-06 09:50:03.921003 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sched_api'
2013-03-06 09:50:03.921135 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sched_broadcast'
2013-03-06 09:50:03.921278 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sched_del'
2013-03-06 09:50:03.921415 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sched_hangup'
2013-03-06 09:50:03.921548 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sched_transfer'
2013-03-06 09:50:03.921682 [NOTICE] switch_loadable_module.c:298 Adding API Function 'show'
2013-03-06 09:50:03.921819 [NOTICE] switch_loadable_module.c:298 Adding API Function 'sql_escape'
2013-03-06 09:50:03.921951 [NOTICE] switch_loadable_module.c:298 Adding API Function 'status'
2013-03-06 09:50:03.922076 [NOTICE] switch_loadable_module.c:298 Adding API Function 'strftime_tz'
2013-03-06 09:50:03.922140 [NOTICE] switch_loadable_module.c:298 Adding API Function 'stun'
2013-03-06 09:50:03.922198 [NOTICE] switch_loadable_module.c:298 Adding API Function 'system'
2013-03-06 09:50:03.922255 [NOTICE] switch_loadable_module.c:298 Adding API Function 'time_test'
2013-03-06 09:50:03.922311 [NOTICE] switch_loadable_module.c:298 Adding API Function 'timer_test'
2013-03-06 09:50:03.922369 [NOTICE] switch_loadable_module.c:298 Adding API Function 'tone_detect'
2013-03-06 09:50:03.922428 [NOTICE] switch_loadable_module.c:298 Adding API Function 'unload'
2013-03-06 09:50:03.922485 [NOTICE] switch_loadable_module.c:298 Adding API Function 'unsched_api'
2013-03-06 09:50:03.922542 [NOTICE] switch_loadable_module.c:298 Adding API Function 'reg_url'
2013-03-06 09:50:03.922598 [NOTICE] switch_loadable_module.c:298 Adding API Function 'url_decode'
2013-03-06 09:50:03.922655 [NOTICE] switch_loadable_module.c:298 Adding API Function 'url_encode'
2013-03-06 09:50:03.922711 [NOTICE] switch_loadable_module.c:298 Adding API Function 'user_data'
2013-03-06 09:50:03.922769 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_early_ok'
2013-03-06 09:50:03.922827 [NOTICE] switch_loadable_module.c:298 Adding API Function 'user_exists'
2013-03-06 09:50:03.922884 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_answer'
2013-03-06 09:50:03.922940 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_audio'
2013-03-06 09:50:03.922998 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_break'
2013-03-06 09:50:03.923071 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_bridge'
2013-03-06 09:50:03.923137 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_broadcast'
2013-03-06 09:50:03.923197 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_buglist'
2013-03-06 09:50:03.923255 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_chat'
2013-03-06 09:50:03.923316 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_debug_audio'
2013-03-06 09:50:03.923748 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_deflect'
2013-03-06 09:50:03.923854 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_displace'
2013-03-06 09:50:03.923954 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_display'
2013-03-06 09:50:03.924049 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_dump'
2013-03-06 09:50:03.924138 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_exists'
2013-03-06 09:50:03.924203 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_fileman'
2013-03-06 09:50:03.924255 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_flush_dtmf'
2013-03-06 09:50:03.924398 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_getvar'
2013-03-06 09:50:03.924531 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_hold'
2013-03-06 09:50:03.924663 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_kill'
2013-03-06 09:50:03.924795 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_send_message'
2013-03-06 09:50:03.924928 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_send_info'
2013-03-06 09:50:03.925061 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_video_refresh'
2013-03-06 09:50:03.925194 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_outgoing_answer'
2013-03-06 09:50:03.925327 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_limit'
2013-03-06 09:50:03.925460 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_limit_release'
2013-03-06 09:50:03.925594 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_limit_release'
2013-03-06 09:50:03.925726 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_loglevel'
2013-03-06 09:50:03.925859 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_media'
2013-03-06 09:50:03.925990 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_media_reneg'
2013-03-06 09:50:03.926127 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_park'
2013-03-06 09:50:03.926296 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_pause'
2013-03-06 09:50:03.926361 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_phone_event'
2013-03-06 09:50:03.926421 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_pre_answer'
2013-03-06 09:50:03.926479 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_preprocess'
2013-03-06 09:50:03.926537 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_record'
2013-03-06 09:50:03.926595 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_recovery_refresh'
2013-03-06 09:50:03.926654 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_recv_dtmf'
2013-03-06 09:50:03.926712 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_send_dtmf'
2013-03-06 09:50:03.926793 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_session_heartbeat'
2013-03-06 09:50:03.926855 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_setvar_multi'
2013-03-06 09:50:03.926915 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_setvar'
2013-03-06 09:50:03.926972 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_transfer'
2013-03-06 09:50:03.927036 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_dual_transfer'
2013-03-06 09:50:03.927100 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_simplify'
2013-03-06 09:50:03.927262 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_jitterbuffer'
2013-03-06 09:50:03.927300 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_zombie_exec'
2013-03-06 09:50:03.927337 [NOTICE] switch_loadable_module.c:298 Adding API Function 'xml_flush_cache'
2013-03-06 09:50:03.927365 [NOTICE] switch_loadable_module.c:298 Adding API Function 'xml_locate'
2013-03-06 09:50:03.927391 [NOTICE] switch_loadable_module.c:298 Adding API Function 'xml_wrap'
2013-03-06 09:50:03.927416 [NOTICE] switch_loadable_module.c:298 Adding API Function 'file_exists'
2013-03-06 09:50:03.927910 [NOTICE] switch_loadable_module.c:254 Adding Application 'conference'
2013-03-06 09:50:03.927924 [NOTICE] switch_loadable_module.c:254 Adding Application 'conference_set_auto_outcall'
2013-03-06 09:50:03.927938 [NOTICE] switch_loadable_module.c:298 Adding API Function 'conference'
2013-03-06 09:50:03.927950 [NOTICE] switch_loadable_module.c:405 Adding Chat interface 'conf'
2013-03-06 09:50:03.929898 [NOTICE] switch_loadable_module.c:254 Adding Application 'db'
2013-03-06 09:50:03.929961 [NOTICE] switch_loadable_module.c:254 Adding Application 'group'
2013-03-06 09:50:03.930009 [NOTICE] switch_loadable_module.c:298 Adding API Function 'db'
2013-03-06 09:50:03.930054 [NOTICE] switch_loadable_module.c:298 Adding API Function 'group'
2013-03-06 09:50:03.930100 [NOTICE] switch_loadable_module.c:475 Adding Limit interface 'db'
2013-03-06 09:50:04.431021 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'error'
2013-03-06 09:50:04.431101 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'group'
2013-03-06 09:50:04.431166 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'user'
2013-03-06 09:50:04.431222 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'pickup'
2013-03-06 09:50:04.431278 [NOTICE] switch_loadable_module.c:214 Adding Dialplan 'inline'
2013-03-06 09:50:04.431337 [NOTICE] switch_loadable_module.c:254 Adding Application 'blind_transfer_ack'
2013-03-06 09:50:04.431399 [NOTICE] switch_loadable_module.c:254 Adding Application 'bind_digit_action'
2013-03-06 09:50:04.431461 [NOTICE] switch_loadable_module.c:254 Adding Application 'capture'
2013-03-06 09:50:04.431519 [NOTICE] switch_loadable_module.c:254 Adding Application 'clear_digit_action'
2013-03-06 09:50:04.431577 [NOTICE] switch_loadable_module.c:254 Adding Application 'digit_action_set_realm'
2013-03-06 09:50:04.431635 [NOTICE] switch_loadable_module.c:254 Adding Application 'privacy'
2013-03-06 09:50:04.431693 [NOTICE] switch_loadable_module.c:254 Adding Application 'set_audio_level'
2013-03-06 09:50:04.431803 [NOTICE] switch_loadable_module.c:254 Adding Application 'set_mute'
2013-03-06 09:50:04.431930 [NOTICE] switch_loadable_module.c:254 Adding Application 'flush_dtmf'
2013-03-06 09:50:04.432040 [NOTICE] switch_loadable_module.c:254 Adding Application 'hold'
2013-03-06 09:50:04.432159 [NOTICE] switch_loadable_module.c:254 Adding Application 'unhold'
2013-03-06 09:50:04.432265 [NOTICE] switch_loadable_module.c:254 Adding Application 'mutex'
2013-03-06 09:50:04.432371 [NOTICE] switch_loadable_module.c:254 Adding Application 'page'
2013-03-06 09:50:04.432474 [NOTICE] switch_loadable_module.c:254 Adding Application 'transfer'
2013-03-06 09:50:04.432580 [NOTICE] switch_loadable_module.c:254 Adding Application 'check_acl'
2013-03-06 09:50:04.432685 [NOTICE] switch_loadable_module.c:254 Adding Application 'verbose_events'
2013-03-06 09:50:04.432789 [NOTICE] switch_loadable_module.c:254 Adding Application 'cng_plc'
2013-03-06 09:50:04.432891 [NOTICE] switch_loadable_module.c:254 Adding Application 'early_hangup'
2013-03-06 09:50:04.432993 [NOTICE] switch_loadable_module.c:254 Adding Application 'sleep'
2013-03-06 09:50:04.433097 [NOTICE] switch_loadable_module.c:254 Adding Application 'delay_echo'
2013-03-06 09:50:04.433902 [NOTICE] switch_loadable_module.c:254 Adding Application 'strftime'
2013-03-06 09:50:04.434216 [NOTICE] switch_loadable_module.c:254 Adding Application 'phrase'
2013-03-06 09:50:04.434394 [NOTICE] switch_loadable_module.c:254 Adding Application 'eval'
2013-03-06 09:50:04.434532 [NOTICE] switch_loadable_module.c:254 Adding Application 'stop'
2013-03-06 09:50:04.434634 [NOTICE] switch_loadable_module.c:254 Adding Application 'set_zombie_exec'
2013-03-06 09:50:04.434704 [NOTICE] switch_loadable_module.c:254 Adding Application 'pre_answer'
2013-03-06 09:50:04.434767 [NOTICE] switch_loadable_module.c:254 Adding Application 'answer'
2013-03-06 09:50:04.434825 [NOTICE] switch_loadable_module.c:254 Adding Application 'wait_for_answer'
2013-03-06 09:50:04.434883 [NOTICE] switch_loadable_module.c:254 Adding Application 'hangup'
2013-03-06 09:50:04.434941 [NOTICE] switch_loadable_module.c:254 Adding Application 'set_name'
2013-03-06 09:50:04.434998 [NOTICE] switch_loadable_module.c:254 Adding Application 'presence'
2013-03-06 09:50:04.435192 [NOTICE] switch_loadable_module.c:254 Adding Application 'log'
2013-03-06 09:50:04.435271 [NOTICE] switch_loadable_module.c:254 Adding Application 'info'
2013-03-06 09:50:04.435330 [NOTICE] switch_loadable_module.c:254 Adding Application 'event'
2013-03-06 09:50:04.435388 [NOTICE] switch_loadable_module.c:254 Adding Application 'sound_test'
2013-03-06 09:50:04.435445 [NOTICE] switch_loadable_module.c:254 Adding Application 'export'
2013-03-06 09:50:04.435504 [NOTICE] switch_loadable_module.c:254 Adding Application 'bridge_export'
2013-03-06 09:50:04.435563 [NOTICE] switch_loadable_module.c:254 Adding Application 'set'
2013-03-06 09:50:04.435621 [NOTICE] switch_loadable_module.c:254 Adding Application 'multiset'
2013-03-06 09:50:04.435679 [NOTICE] switch_loadable_module.c:254 Adding Application 'push'
2013-03-06 09:50:04.435738 [NOTICE] switch_loadable_module.c:254 Adding Application 'unshift'
2013-03-06 09:50:04.435796 [NOTICE] switch_loadable_module.c:254 Adding Application 'set_global'
2013-03-06 09:50:04.435854 [NOTICE] switch_loadable_module.c:254 Adding Application 'set_profile_var'
2013-03-06 09:50:04.435913 [NOTICE] switch_loadable_module.c:254 Adding Application 'unset'
2013-03-06 09:50:04.435970 [NOTICE] switch_loadable_module.c:254 Adding Application 'ring_ready'
2013-03-06 09:50:04.436028 [NOTICE] switch_loadable_module.c:254 Adding Application 'remove_bugs'
2013-03-06 09:50:04.436084 [NOTICE] switch_loadable_module.c:254 Adding Application 'break'
2013-03-06 09:50:04.436141 [NOTICE] switch_loadable_module.c:254 Adding Application 'detect_speech'
2013-03-06 09:50:04.436204 [NOTICE] switch_loadable_module.c:254 Adding Application 'play_and_detect_speech'
2013-03-06 09:50:04.436265 [NOTICE] switch_loadable_module.c:254 Adding Application 'ivr'
2013-03-06 09:50:04.436323 [NOTICE] switch_loadable_module.c:254 Adding Application 'redirect'
2013-03-06 09:50:04.436381 [NOTICE] switch_loadable_module.c:254 Adding Application 'video_refresh'
2013-03-06 09:50:04.436438 [NOTICE] switch_loadable_module.c:254 Adding Application 'send_info'
2013-03-06 09:50:04.436495 [NOTICE] switch_loadable_module.c:254 Adding Application 'jitterbuffer'
2013-03-06 09:50:04.436554 [NOTICE] switch_loadable_module.c:254 Adding Application 'send_display'
2013-03-06 09:50:04.436612 [NOTICE] switch_loadable_module.c:254 Adding Application 'respond'
2013-03-06 09:50:04.436625 [NOTICE] switch_loadable_module.c:254 Adding Application 'deflect'
2013-03-06 09:50:04.436625 [NOTICE] switch_loadable_module.c:254 Adding Application 'recovery_refresh'
2013-03-06 09:50:04.436954 [NOTICE] switch_loadable_module.c:254 Adding Application 'queue_dtmf'
2013-03-06 09:50:04.437279 [NOTICE] switch_loadable_module.c:254 Adding Application 'send_dtmf'
2013-03-06 09:50:04.437502 [NOTICE] switch_loadable_module.c:254 Adding Application 'sched_cancel'
2013-03-06 09:50:04.437683 [NOTICE] switch_loadable_module.c:254 Adding Application 'sched_hangup'
2013-03-06 09:50:04.437868 [NOTICE] switch_loadable_module.c:254 Adding Application 'sched_broadcast'
2013-03-06 09:50:04.438019 [NOTICE] switch_loadable_module.c:254 Adding Application 'sched_transfer'
2013-03-06 09:50:04.438158 [NOTICE] switch_loadable_module.c:254 Adding Application 'execute_extension'
2013-03-06 09:50:04.438291 [NOTICE] switch_loadable_module.c:254 Adding Application 'sched_heartbeat'
2013-03-06 09:50:04.438423 [NOTICE] switch_loadable_module.c:254 Adding Application 'enable_heartbeat'
2013-03-06 09:50:04.438555 [NOTICE] switch_loadable_module.c:254 Adding Application 'media_reset'
2013-03-06 09:50:04.438686 [NOTICE] switch_loadable_module.c:254 Adding Application 'mkdir'
2013-03-06 09:50:04.438821 [NOTICE] switch_loadable_module.c:254 Adding Application 'rename'
2013-03-06 09:50:04.438884 [NOTICE] switch_loadable_module.c:254 Adding Application 'soft_hold'
2013-03-06 09:50:04.438943 [NOTICE] switch_loadable_module.c:254 Adding Application 'bind_meta_app'
2013-03-06 09:50:04.439001 [NOTICE] switch_loadable_module.c:254 Adding Application 'unbind_meta_app'
2013-03-06 09:50:04.439060 [NOTICE] switch_loadable_module.c:254 Adding Application 'block_dfmf'
2013-03-06 09:50:04.439117 [NOTICE] switch_loadable_module.c:254 Adding Application 'unblock_dtmf'
2013-03-06 09:50:04.439174 [NOTICE] switch_loadable_module.c:254 Adding Application 'intercept'
2013-03-06 09:50:04.439232 [NOTICE] switch_loadable_module.c:254 Adding Application 'eavesdrop'
2013-03-06 09:50:04.439290 [NOTICE] switch_loadable_module.c:254 Adding Application 'three_way'
2013-03-06 09:50:04.439347 [NOTICE] switch_loadable_module.c:254 Adding Application 'set_user'
2013-03-06 09:50:04.439404 [NOTICE] switch_loadable_module.c:254 Adding Application 'stop_dtmf'
2013-03-06 09:50:04.439461 [NOTICE] switch_loadable_module.c:254 Adding Application 'start_dtmf'
2013-03-06 09:50:04.439517 [NOTICE] switch_loadable_module.c:254 Adding Application 'stop_dtmf_generate'
2013-03-06 09:50:04.439575 [NOTICE] switch_loadable_module.c:254 Adding Application 'start_dtmf_generate'
2013-03-06 09:50:04.439632 [NOTICE] switch_loadable_module.c:254 Adding Application 'stop_tone_detect'
2013-03-06 09:50:04.439690 [NOTICE] switch_loadable_module.c:254 Adding Application 'fax_detect'
2013-03-06 09:50:04.439746 [NOTICE] switch_loadable_module.c:254 Adding Application 'tone_detect'
2013-03-06 09:50:04.439803 [NOTICE] switch_loadable_module.c:254 Adding Application 'echo'
2013-03-06 09:50:04.439859 [NOTICE] switch_loadable_module.c:254 Adding Application 'park'
2013-03-06 09:50:04.439915 [NOTICE] switch_loadable_module.c:254 Adding Application 'park_state'
2013-03-06 09:50:04.439972 [NOTICE] switch_loadable_module.c:254 Adding Application 'gentones'
2013-03-06 09:50:04.440041 [NOTICE] switch_loadable_module.c:254 Adding Application 'playback'
2013-03-06 09:50:04.440131 [NOTICE] switch_loadable_module.c:254 Adding Application 'endless_playback'
2013-03-06 09:50:04.440228 [NOTICE] switch_loadable_module.c:254 Adding Application 'att_xfer'
2013-03-06 09:50:04.440322 [NOTICE] switch_loadable_module.c:254 Adding Application 'read'
2013-03-06 09:50:04.440416 [NOTICE] switch_loadable_module.c:254 Adding Application 'play_and_get_digits'
2013-03-06 09:50:04.440517 [NOTICE] switch_loadable_module.c:254 Adding Application 'stop_record_session'
2013-03-06 09:50:04.440611 [NOTICE] switch_loadable_module.c:254 Adding Application 'record_session'
2013-03-06 09:50:04.440707 [NOTICE] switch_loadable_module.c:254 Adding Application 'record'
2013-03-06 09:50:04.440801 [NOTICE] switch_loadable_module.c:254 Adding Application 'preprocess'
2013-03-06 09:50:04.440812 [NOTICE] switch_loadable_module.c:254 Adding Application 'stop_displace_session'
2013-03-06 09:50:04.441117 [NOTICE] switch_loadable_module.c:254 Adding Application 'displace_session'
2013-03-06 09:50:04.441190 [NOTICE] switch_loadable_module.c:254 Adding Application 'speak'
2013-03-06 09:50:04.441249 [NOTICE] switch_loadable_module.c:254 Adding Application 'clear_speech_cache'
2013-03-06 09:50:04.441309 [NOTICE] switch_loadable_module.c:254 Adding Application 'bridge'
2013-03-06 09:50:04.441367 [NOTICE] switch_loadable_module.c:254 Adding Application 'system'
2013-03-06 09:50:04.441425 [NOTICE] switch_loadable_module.c:254 Adding Application 'say'
2013-03-06 09:50:04.443803 [NOTICE] switch_loadable_module.c:254 Adding Application 'wait_for_silence'
2013-03-06 09:50:04.444290 [NOTICE] switch_loadable_module.c:254 Adding Application 'session_loglevel'
2013-03-06 09:50:04.444403 [NOTICE] switch_loadable_module.c:254 Adding Application 'limit'
2013-03-06 09:50:04.444511 [NOTICE] switch_loadable_module.c:254 Adding Application 'limit_hash'
2013-03-06 09:50:04.444617 [NOTICE] switch_loadable_module.c:254 Adding Application 'limit_execute'
2013-03-06 09:50:04.444785 [NOTICE] switch_loadable_module.c:254 Adding Application 'limit_hash_execute'
2013-03-06 09:50:04.444888 [NOTICE] switch_loadable_module.c:254 Adding Application 'pickup'
2013-03-06 09:50:04.444984 [NOTICE] switch_loadable_module.c:298 Adding API Function 'strepoch'
2013-03-06 09:50:04.445079 [NOTICE] switch_loadable_module.c:298 Adding API Function 'page'
2013-03-06 09:50:04.445171 [NOTICE] switch_loadable_module.c:298 Adding API Function 'strmicroepoch'
2013-03-06 09:50:04.445238 [NOTICE] switch_loadable_module.c:298 Adding API Function 'chat'
2013-03-06 09:50:04.445298 [NOTICE] switch_loadable_module.c:298 Adding API Function 'strftime'
2013-03-06 09:50:04.445342 [NOTICE] switch_loadable_module.c:298 Adding API Function 'presence'
2013-03-06 09:50:04.445400 [NOTICE] switch_loadable_module.c:324 Adding File Format 'file_string'
2013-03-06 09:50:04.445444 [NOTICE] switch_loadable_module.c:405 Adding Chat interface 'event'
2013-03-06 09:50:04.445489 [NOTICE] switch_loadable_module.c:405 Adding Chat interface 'api'
2013-03-06 09:50:04.445714 [NOTICE] switch_loadable_module.c:298 Adding API Function 'expr'
2013-03-06 09:50:04.446722 [INFO] switch_core_sqldb.c:1423 fifo Starting SQL thread.
2013-03-06 09:50:04.447827 [INFO] mod_fifo.c:4262 cool_fifo@10.0.0.12 configured
2013-03-06 09:50:04.447914 [NOTICE] switch_loadable_module.c:254 Adding Application 'fifo'
2013-03-06 09:50:04.447959 [NOTICE] switch_loadable_module.c:254 Adding Application 'fifo_track_call'
2013-03-06 09:50:04.447988 [NOTICE] switch_loadable_module.c:298 Adding API Function 'fifo'
2013-03-06 09:50:04.448014 [NOTICE] switch_loadable_module.c:298 Adding API Function 'fifo_member'
2013-03-06 09:50:04.448040 [NOTICE] switch_loadable_module.c:298 Adding API Function 'fifo_add_outbound'
2013-03-06 09:50:04.448066 [NOTICE] switch_loadable_module.c:298 Adding API Function 'fifo_check_bridge'
2013-03-06 09:50:04.448319 [DEBUG] switch_scheduler.c:214 Added task 3 limit_hash_cleanup (mod_hash) to run at 1362575104
2013-03-06 09:50:04.448402 [NOTICE] switch_loadable_module.c:254 Adding Application 'hash'
2013-03-06 09:50:04.448431 [NOTICE] switch_loadable_module.c:298 Adding API Function 'hash'
2013-03-06 09:50:04.448454 [NOTICE] switch_loadable_module.c:298 Adding API Function 'hash_dump'
2013-03-06 09:50:04.448454 [NOTICE] switch_loadable_module.c:298 Adding API Function 'hash_remote'
2013-03-06 09:50:04.448455 [NOTICE] switch_loadable_module.c:475 Adding Limit interface 'hash'
2013-03-06 09:50:04.449469 [INFO] mod_voicemail.c:776 Added Profile default
2013-03-06 09:50:04.449505 [NOTICE] switch_loadable_module.c:254 Adding Application 'voicemail'
2013-03-06 09:50:04.449563 [NOTICE] switch_loadable_module.c:298 Adding API Function 'voicemail'
2013-03-06 09:50:04.449610 [NOTICE] switch_loadable_module.c:298 Adding API Function 'voicemail_inject'
2013-03-06 09:50:04.449656 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_inject'
2013-03-06 09:50:04.449700 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_boxcount'
2013-03-06 09:50:04.449755 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_prefs'
2013-03-06 09:50:04.449784 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_delete'
2013-03-06 09:50:04.449809 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_read'
2013-03-06 09:50:04.449834 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_list'
2013-03-06 09:50:04.449858 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_auth_login'
2013-03-06 09:50:04.449883 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_count'
2013-03-06 09:50:04.449908 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_list'
2013-03-06 09:50:04.449933 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_get'
2013-03-06 09:50:04.449958 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_delete'
2013-03-06 09:50:04.449982 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_undelete'
2013-03-06 09:50:04.450007 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_email'
2013-03-06 09:50:04.450032 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_purge'
2013-03-06 09:50:04.450057 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_save'
2013-03-06 09:50:04.450081 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_msg_forward'
2013-03-06 09:50:04.450107 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_pref_greeting_set'
2013-03-06 09:50:04.450132 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_pref_greeting_get'
2013-03-06 09:50:04.450156 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_pref_recname_set'
2013-03-06 09:50:04.450181 [NOTICE] switch_loadable_module.c:298 Adding API Function 'vm_fsdb_pref_password_set'
2013-03-06 09:50:04.450235 [NOTICE] switch_loadable_module.c:254 Adding Application 'esf_page_group'
2013-03-06 09:50:04.450379 [NOTICE] switch_loadable_module.c:254 Adding Application 'play_fsv'
2013-03-06 09:50:04.450431 [NOTICE] switch_loadable_module.c:254 Adding Application 'record_fsv'
2013-03-06 09:50:04.450476 [NOTICE] switch_loadable_module.c:324 Adding File Format 'fsv'
2013-03-06 09:50:04.450623 [NOTICE] mod_cluechoo.c:83 Hello World!
2013-03-06 09:50:04.450639 [NOTICE] switch_loadable_module.c:254 Adding Application 'cluechoo'
2013-03-06 09:50:04.450695 [NOTICE] switch_loadable_module.c:298 Adding API Function 'cluechoo'
2013-03-06 09:50:04.450837 [NOTICE] switch_loadable_module.c:254 Adding Application 'valet_park'
2013-03-06 09:50:04.450872 [NOTICE] switch_loadable_module.c:298 Adding API Function 'valet_info'
2013-03-06 09:50:04.451004 [NOTICE] mod_httapi.c:2108 Profile [default] JSON Function [http://www.freeswitch.org/api/index.cgi]
2013-03-06 09:50:04.451015 [NOTICE] switch_loadable_module.c:254 Adding Application 'httapi'
2013-03-06 09:50:04.451067 [NOTICE] switch_loadable_module.c:298 Adding API Function 'httapi'
2013-03-06 09:50:04.451113 [NOTICE] switch_loadable_module.c:324 Adding File Format 'http'
2013-03-06 09:50:04.453564 [NOTICE] switch_loadable_module.c:214 Adding Dialplan 'XML'
2013-03-06 09:50:04.453706 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'SIP'
2013-03-06 09:50:04.453742 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'IAX2'
2013-03-06 09:50:04.453769 [NOTICE] switch_loadable_module.c:214 Adding Dialplan 'asterisk'
2013-03-06 09:50:04.453914 [NOTICE] switch_loadable_module.c:254 Adding Application 'Dial'
2013-03-06 09:50:04.453942 [NOTICE] switch_loadable_module.c:254 Adding Application 'Goto'
2013-03-06 09:50:04.453966 [NOTICE] switch_loadable_module.c:254 Adding Application 'AvoidingDeadlock'
2013-03-06 09:50:04.455673 [DEBUG] mod_spandsp_fax.c:192 FAX timer thread started.
2013-03-06 09:50:04.475572 [DEBUG] mod_spandsp.c:780 mod_spandsp loaded, using spandsp library version [20120902 163333]
2013-03-06 09:50:04.475621 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'modem'
2013-03-06 09:50:04.475682 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 6 ADPCM (IMA) 16000hz 10ms 64000bps
2013-03-06 09:50:04.475689 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 6 ADPCM (IMA) 16000hz 20ms 64000bps
2013-03-06 09:50:04.475693 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 6 ADPCM (IMA) 16000hz 30ms 64000bps
2013-03-06 09:50:04.475696 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 6 ADPCM (IMA) 16000hz 40ms 64000bps
2013-03-06 09:50:04.475699 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 6 ADPCM (IMA) 16000hz 50ms 64000bps
2013-03-06 09:50:04.475702 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 6 ADPCM (IMA) 16000hz 60ms 64000bps
2013-03-06 09:50:04.475705 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 10ms 32000bps
2013-03-06 09:50:04.475708 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 20ms 32000bps
2013-03-06 09:50:04.475712 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 30ms 32000bps
2013-03-06 09:50:04.475715 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 40ms 32000bps
2013-03-06 09:50:04.475717 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 50ms 32000bps
2013-03-06 09:50:04.475721 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 60ms 32000bps
2013-03-06 09:50:04.475724 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 70ms 32000bps
2013-03-06 09:50:04.475726 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 80ms 32000bps
2013-03-06 09:50:04.475729 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 90ms 32000bps
2013-03-06 09:50:04.475732 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 100ms 32000bps
2013-03-06 09:50:04.475736 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 110ms 32000bps
2013-03-06 09:50:04.475739 [NOTICE] switch_loadable_module.c:187 Adding Codec DVI4 5 ADPCM (IMA) 8000hz 120ms 32000bps
2013-03-06 09:50:04.475766 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 10ms 16000bps
2013-03-06 09:50:04.475772 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 20ms 16000bps
2013-03-06 09:50:04.475775 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 30ms 16000bps
2013-03-06 09:50:04.475778 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 40ms 16000bps
2013-03-06 09:50:04.475781 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 50ms 16000bps
2013-03-06 09:50:04.475784 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 60ms 16000bps
2013-03-06 09:50:04.475787 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 70ms 16000bps
2013-03-06 09:50:04.475790 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 80ms 16000bps
2013-03-06 09:50:04.475793 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 90ms 16000bps
2013-03-06 09:50:04.475796 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 100ms 16000bps
2013-03-06 09:50:04.475799 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 110ms 16000bps
2013-03-06 09:50:04.475802 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-16 124 G.726 16k (AAL2) 8000hz 120ms 16000bps
2013-03-06 09:50:04.475826 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 10ms 16000bps
2013-03-06 09:50:04.475832 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 20ms 16000bps
2013-03-06 09:50:04.475835 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 30ms 16000bps
2013-03-06 09:50:04.475838 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 40ms 16000bps
2013-03-06 09:50:04.475841 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 50ms 16000bps
2013-03-06 09:50:04.475844 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 60ms 16000bps
2013-03-06 09:50:04.475847 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 70ms 16000bps
2013-03-06 09:50:04.475849 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 80ms 16000bps
2013-03-06 09:50:04.475852 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 90ms 16000bps
2013-03-06 09:50:04.475855 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 100ms 16000bps
2013-03-06 09:50:04.475858 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 110ms 16000bps
2013-03-06 09:50:04.475861 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-16 124 G.726 16k 8000hz 120ms 16000bps
2013-03-06 09:50:04.475884 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 10ms 24000bps
2013-03-06 09:50:04.475891 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 20ms 24000bps
2013-03-06 09:50:04.475894 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 30ms 24000bps
2013-03-06 09:50:04.475897 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 40ms 24000bps
2013-03-06 09:50:04.475900 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 50ms 24000bps
2013-03-06 09:50:04.475903 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 60ms 24000bps
2013-03-06 09:50:04.475906 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 70ms 24000bps
2013-03-06 09:50:04.475909 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 80ms 24000bps
2013-03-06 09:50:04.475912 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 90ms 24000bps
2013-03-06 09:50:04.475915 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 100ms 24000bps
2013-03-06 09:50:04.475917 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 110ms 24000bps
2013-03-06 09:50:04.475920 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-24 123 G.726 24k (AAL2) 8000hz 120ms 24000bps
2013-03-06 09:50:04.475944 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 10ms 24000bps
2013-03-06 09:50:04.475949 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 20ms 24000bps
2013-03-06 09:50:04.475979 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 30ms 24000bps
2013-03-06 09:50:04.475982 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 40ms 24000bps
2013-03-06 09:50:04.475985 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 50ms 24000bps
2013-03-06 09:50:04.475988 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 60ms 24000bps
2013-03-06 09:50:04.475991 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 70ms 24000bps
2013-03-06 09:50:04.475993 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 80ms 24000bps
2013-03-06 09:50:04.475996 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 90ms 24000bps
2013-03-06 09:50:04.475999 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 100ms 24000bps
2013-03-06 09:50:04.476002 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 110ms 24000bps
2013-03-06 09:50:04.476004 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-24 123 G.726 24k 8000hz 120ms 24000bps
2013-03-06 09:50:04.476031 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 10ms 32000bps
2013-03-06 09:50:04.476036 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 20ms 32000bps
2013-03-06 09:50:04.476039 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 30ms 32000bps
2013-03-06 09:50:04.476042 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 40ms 32000bps
2013-03-06 09:50:04.476045 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 50ms 32000bps
2013-03-06 09:50:04.476048 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 60ms 32000bps
2013-03-06 09:50:04.476050 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 70ms 32000bps
2013-03-06 09:50:04.476053 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 80ms 32000bps
2013-03-06 09:50:04.476056 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 90ms 32000bps
2013-03-06 09:50:04.476059 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 100ms 32000bps
2013-03-06 09:50:04.476062 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 110ms 32000bps
2013-03-06 09:50:04.476065 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-32 122 G.726 32k (AAL2) 8000hz 120ms 32000bps
2013-03-06 09:50:04.476088 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 10ms 32000bps
2013-03-06 09:50:04.476093 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 20ms 32000bps
2013-03-06 09:50:04.476096 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 30ms 32000bps
2013-03-06 09:50:04.476099 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 40ms 32000bps
2013-03-06 09:50:04.476102 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 50ms 32000bps
2013-03-06 09:50:04.476104 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 60ms 32000bps
2013-03-06 09:50:04.476107 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 70ms 32000bps
2013-03-06 09:50:04.476110 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 80ms 32000bps
2013-03-06 09:50:04.476113 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 90ms 32000bps
2013-03-06 09:50:04.476115 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 100ms 32000bps
2013-03-06 09:50:04.476118 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 110ms 32000bps
2013-03-06 09:50:04.476121 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-32 122 G.726 32k 8000hz 120ms 32000bps
2013-03-06 09:50:04.476144 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 10ms 40000bps
2013-03-06 09:50:04.476149 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 20ms 40000bps
2013-03-06 09:50:04.476152 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 30ms 40000bps
2013-03-06 09:50:04.476155 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 40ms 40000bps
2013-03-06 09:50:04.476157 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 50ms 40000bps
2013-03-06 09:50:04.476160 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 60ms 40000bps
2013-03-06 09:50:04.476163 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 70ms 40000bps
2013-03-06 09:50:04.476166 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 80ms 40000bps
2013-03-06 09:50:04.476169 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 90ms 40000bps
2013-03-06 09:50:04.476172 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 100ms 40000bps
2013-03-06 09:50:04.476175 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 110ms 40000bps
2013-03-06 09:50:04.476178 [NOTICE] switch_loadable_module.c:187 Adding Codec AAL2-G726-40 121 G.726 40k (AAL2) 8000hz 120ms 40000bps
2013-03-06 09:50:04.476201 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 10ms 40000bps
2013-03-06 09:50:04.476206 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 20ms 40000bps
2013-03-06 09:50:04.476209 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 30ms 40000bps
2013-03-06 09:50:04.476212 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 40ms 40000bps
2013-03-06 09:50:04.476215 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 50ms 40000bps
2013-03-06 09:50:04.476217 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 60ms 40000bps
2013-03-06 09:50:04.476220 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 70ms 40000bps
2013-03-06 09:50:04.476223 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 80ms 40000bps
2013-03-06 09:50:04.476226 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 90ms 40000bps
2013-03-06 09:50:04.476229 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 100ms 40000bps
2013-03-06 09:50:04.476231 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 110ms 40000bps
2013-03-06 09:50:04.476234 [NOTICE] switch_loadable_module.c:187 Adding Codec G726-40 121 G.726 40k 8000hz 120ms 40000bps
2013-03-06 09:50:04.476257 [NOTICE] switch_loadable_module.c:187 Adding Codec G722 9 G.722 16000hz 10ms 64000bps
2013-03-06 09:50:04.476262 [NOTICE] switch_loadable_module.c:187 Adding Codec G722 9 G.722 16000hz 20ms 64000bps
2013-03-06 09:50:04.476265 [NOTICE] switch_loadable_module.c:187 Adding Codec G722 9 G.722 16000hz 30ms 64000bps
2013-03-06 09:50:04.476268 [NOTICE] switch_loadable_module.c:187 Adding Codec G722 9 G.722 16000hz 40ms 64000bps
2013-03-06 09:50:04.476270 [NOTICE] switch_loadable_module.c:187 Adding Codec G722 9 G.722 16000hz 50ms 64000bps
2013-03-06 09:50:04.476273 [NOTICE] switch_loadable_module.c:187 Adding Codec G722 9 G.722 16000hz 60ms 64000bps
2013-03-06 09:50:04.476295 [NOTICE] switch_loadable_module.c:187 Adding Codec GSM 3 GSM 8000hz 20ms 13200bps
2013-03-06 09:50:04.476300 [NOTICE] switch_loadable_module.c:187 Adding Codec GSM 3 GSM 8000hz 40ms 13200bps
2013-03-06 09:50:04.476303 [NOTICE] switch_loadable_module.c:187 Adding Codec GSM 3 GSM 8000hz 60ms 13200bps
2013-03-06 09:50:04.476305 [NOTICE] switch_loadable_module.c:187 Adding Codec GSM 3 GSM 8000hz 80ms 13200bps
2013-03-06 09:50:04.476308 [NOTICE] switch_loadable_module.c:187 Adding Codec GSM 3 GSM 8000hz 100ms 13200bps
2013-03-06 09:50:04.476311 [NOTICE] switch_loadable_module.c:187 Adding Codec GSM 3 GSM 8000hz 120ms 13200bps
2013-03-06 09:50:04.476339 [NOTICE] switch_loadable_module.c:187 Adding Codec LPC 7 LPC-10 8000hz 90ms 2400bps
2013-03-06 09:50:04.476365 [NOTICE] switch_loadable_module.c:254 Adding Application 't38_gateway'
2013-03-06 09:50:04.476392 [NOTICE] switch_loadable_module.c:254 Adding Application 'rxfax'
2013-03-06 09:50:04.476418 [NOTICE] switch_loadable_module.c:254 Adding Application 'txfax'
2013-03-06 09:50:04.476443 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_stop_dtmf'
2013-03-06 09:50:04.476446 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_start_dtmf'
2013-03-06 09:50:04.476446 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_stop_inject_tdd'
2013-03-06 09:50:04.476446 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_inject_tdd'
2013-03-06 09:50:04.476447 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_stop_detect_tdd'
2013-03-06 09:50:04.476447 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_detect_tdd'
2013-03-06 09:50:04.476447 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_send_tdd'
2013-03-06 09:50:04.476499 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_start_fax_detect'
2013-03-06 09:50:04.476561 [NOTICE] switch_loadable_module.c:254 Adding Application 'spandsp_stop_fax_detect'
2013-03-06 09:50:04.476614 [NOTICE] switch_loadable_module.c:254 Adding Application 'start_tone_detect'
2013-03-06 09:50:04.476641 [NOTICE] switch_loadable_module.c:254 Adding Application 'stop_tone_detect'
2013-03-06 09:50:04.476669 [NOTICE] switch_loadable_module.c:298 Adding API Function 'start_tone_detect'
2013-03-06 09:50:04.476696 [NOTICE] switch_loadable_module.c:298 Adding API Function 'stop_tone_detect'
2013-03-06 09:50:04.476722 [NOTICE] switch_loadable_module.c:298 Adding API Function 'start_tdd_detect'
2013-03-06 09:50:04.476748 [NOTICE] switch_loadable_module.c:298 Adding API Function 'stop_tdd_detect'
2013-03-06 09:50:04.476776 [NOTICE] switch_loadable_module.c:298 Adding API Function 'uuid_send_tdd'
2013-03-06 09:50:04.476989 [NOTICE] switch_loadable_module.c:187 Adding Codec G723 4 G.723.1 6.3k 8000hz 120ms 6300bps
2013-03-06 09:50:04.476994 [NOTICE] switch_loadable_module.c:187 Adding Codec G723 4 G.723.1 6.3k 8000hz 90ms 6300bps
2013-03-06 09:50:04.476997 [NOTICE] switch_loadable_module.c:187 Adding Codec G723 4 G.723.1 6.3k 8000hz 60ms 6300bps
2013-03-06 09:50:04.477000 [NOTICE] switch_loadable_module.c:187 Adding Codec G723 4 G.723.1 6.3k 8000hz 30ms 6300bps
2013-03-06 09:50:04.477293 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 10ms 8000bps
2013-03-06 09:50:04.477297 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 20ms 8000bps
2013-03-06 09:50:04.477300 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 30ms 8000bps
2013-03-06 09:50:04.477303 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 40ms 8000bps
2013-03-06 09:50:04.477306 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 50ms 8000bps
2013-03-06 09:50:04.477311 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 60ms 8000bps
2013-03-06 09:50:04.477314 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 70ms 8000bps
2013-03-06 09:50:04.477316 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 80ms 8000bps
2013-03-06 09:50:04.477319 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 90ms 8000bps
2013-03-06 09:50:04.477322 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 100ms 8000bps
2013-03-06 09:50:04.477325 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 110ms 8000bps
2013-03-06 09:50:04.477327 [NOTICE] switch_loadable_module.c:187 Adding Codec G729 18 G.729 8000hz 120ms 8000bps
2013-03-06 09:50:04.477455 [NOTICE] switch_loadable_module.c:187 Adding Codec AMR 96 AMR 8000hz 20ms 12200bps
2013-03-06 09:50:04.477785 [NOTICE] switch_loadable_module.c:187 Adding Codec SPEEX 99 Speex 32000hz 20ms 44000bps
2013-03-06 09:50:04.477796 [NOTICE] switch_loadable_module.c:187 Adding Codec SPEEX 99 Speex 16000hz 20ms 42200bps
2013-03-06 09:50:04.477801 [NOTICE] switch_loadable_module.c:187 Adding Codec SPEEX 99 Speex 8000hz 20ms 24600bps
2013-03-06 09:50:04.477983 [NOTICE] switch_loadable_module.c:187 Adding Codec H264 97 H.264 Video (passthru) 90000hz 0ms 0bps
2013-03-06 09:50:04.478033 [NOTICE] switch_loadable_module.c:187 Adding Codec H263 34 H.263 Video (passthru) 90000hz 0ms 0bps
2013-03-06 09:50:04.478078 [NOTICE] switch_loadable_module.c:187 Adding Codec H263-1998 115 H.263+ Video (passthru) 90000hz 0ms 0bps
2013-03-06 09:50:04.478116 [NOTICE] switch_loadable_module.c:187 Adding Codec H263-2000 121 H.263++ Video (passthru) 90000hz 0ms 0bps
2013-03-06 09:50:04.478187 [NOTICE] switch_loadable_module.c:187 Adding Codec H261 31 H.261 Video (passthru) 90000hz 0ms 0bps
2013-03-06 09:50:04.478357 [NOTICE] switch_loadable_module.c:187 Adding Codec VP8 99 VP8 Video (passthru) 90000hz 0ms 0bps
2013-03-06 09:50:04.478736 [INFO] mod_sndfile.c:363
LibSndFile Version : libsndfile-1.0.19 Supported Formats
================================================================================
AIFF (Apple/SGI) (extension "aiff")
AU (Sun/NeXT) (extension "au")
AVR (Audio Visual Research) (extension "avr")
CAF (Apple Core Audio File) (extension "caf")
HTK (HMM Tool Kit) (extension "htk")
IFF (Amiga IFF/SVX8/SV16) (extension "iff")
MAT4 (GNU Octave 2.0 / Matlab 4.2) (extension "mat")
MAT5 (GNU Octave 2.1 / Matlab 5.0) (extension "mat")
PAF (Ensoniq PARIS) (extension "paf")
PVF (Portable Voice Format) (extension "pvf")
RAW (header-less) (extension "raw")
SD2 (Sound Designer II) (extension "sd2")
SDS (Midi Sample Dump Standard) (extension "sds")
SF (Berkeley/IRCAM/CARL) (extension "sf")
VOC (Creative Labs) (extension "voc")
W64 (SoundFoundry WAVE 64) (extension "w64")
WAV (Microsoft) (extension "wav")
WAV (NIST Sphere) (extension "wav")
WAVEX (Microsoft) (extension "wav")
WVE (Psion Series 3) (extension "wve")
XI (FastTracker 2) (extension "xi")
================================================================================
2013-03-06 09:50:04.478829 [NOTICE] switch_loadable_module.c:324 Adding File Format 'aiff'
2013-03-06 09:50:04.478878 [NOTICE] switch_loadable_module.c:324 Adding File Format 'au'
2013-03-06 09:50:04.478917 [NOTICE] switch_loadable_module.c:324 Adding File Format 'avr'
2013-03-06 09:50:04.478952 [NOTICE] switch_loadable_module.c:324 Adding File Format 'caf'
2013-03-06 09:50:04.478987 [NOTICE] switch_loadable_module.c:324 Adding File Format 'htk'
2013-03-06 09:50:04.479022 [NOTICE] switch_loadable_module.c:324 Adding File Format 'iff'
2013-03-06 09:50:04.479062 [NOTICE] switch_loadable_module.c:324 Adding File Format 'mat'
2013-03-06 09:50:04.479099 [NOTICE] switch_loadable_module.c:324 Adding File Format 'paf'
2013-03-06 09:50:04.479735 [NOTICE] switch_loadable_module.c:324 Adding File Format 'pvf'
2013-03-06 09:50:04.479830 [NOTICE] switch_loadable_module.c:324 Adding File Format 'raw'
2013-03-06 09:50:04.479912 [NOTICE] switch_loadable_module.c:324 Adding File Format 'sd2'
2013-03-06 09:50:04.479994 [NOTICE] switch_loadable_module.c:324 Adding File Format 'sds'
2013-03-06 09:50:04.480113 [NOTICE] switch_loadable_module.c:324 Adding File Format 'sf'
2013-03-06 09:50:04.480157 [NOTICE] switch_loadable_module.c:324 Adding File Format 'voc'
2013-03-06 09:50:04.480195 [NOTICE] switch_loadable_module.c:324 Adding File Format 'w64'
2013-03-06 09:50:04.480231 [NOTICE] switch_loadable_module.c:324 Adding File Format 'wav'
2013-03-06 09:50:04.480272 [NOTICE] switch_loadable_module.c:324 Adding File Format 'wve'
2013-03-06 09:50:04.480308 [NOTICE] switch_loadable_module.c:324 Adding File Format 'xi'
2013-03-06 09:50:04.480342 [NOTICE] switch_loadable_module.c:324 Adding File Format 'r8'
2013-03-06 09:50:04.480377 [NOTICE] switch_loadable_module.c:324 Adding File Format 'r16'
2013-03-06 09:50:04.480412 [NOTICE] switch_loadable_module.c:324 Adding File Format 'r24'
2013-03-06 09:50:04.480447 [NOTICE] switch_loadable_module.c:324 Adding File Format 'r32'
2013-03-06 09:50:04.480482 [NOTICE] switch_loadable_module.c:324 Adding File Format 'gsm'
2013-03-06 09:50:04.480517 [NOTICE] switch_loadable_module.c:324 Adding File Format 'ul'
2013-03-06 09:50:04.480552 [NOTICE] switch_loadable_module.c:324 Adding File Format 'ulaw'
2013-03-06 09:50:04.480621 [NOTICE] switch_loadable_module.c:324 Adding File Format 'al'
2013-03-06 09:50:04.480664 [NOTICE] switch_loadable_module.c:324 Adding File Format 'alaw'
2013-03-06 09:50:04.480700 [NOTICE] switch_loadable_module.c:324 Adding File Format 'adpcm'
2013-03-06 09:50:04.480904 [NOTICE] switch_loadable_module.c:324 Adding File Format 'H263'
2013-03-06 09:50:04.480953 [NOTICE] switch_loadable_module.c:324 Adding File Format 'AMR'
2013-03-06 09:50:04.480994 [NOTICE] switch_loadable_module.c:324 Adding File Format 'SPEEX'
2013-03-06 09:50:04.481032 [NOTICE] switch_loadable_module.c:324 Adding File Format 'G729'
2013-03-06 09:50:04.481067 [NOTICE] switch_loadable_module.c:324 Adding File Format 'G726-16'
2013-03-06 09:50:04.481140 [NOTICE] switch_loadable_module.c:324 Adding File Format 'AAL2-G726-16'
2013-03-06 09:50:04.481177 [NOTICE] switch_loadable_module.c:324 Adding File Format 'PCMA'
2013-03-06 09:50:04.481212 [NOTICE] switch_loadable_module.c:324 Adding File Format 'DVI4'
2013-03-06 09:50:04.481247 [NOTICE] switch_loadable_module.c:324 Adding File Format 'G726-24'
2013-03-06 09:50:04.481282 [NOTICE] switch_loadable_module.c:324 Adding File Format 'PCMU'
2013-03-06 09:50:04.481322 [NOTICE] switch_loadable_module.c:324 Adding File Format 'L16'
2013-03-06 09:50:04.481357 [NOTICE] switch_loadable_module.c:324 Adding File Format 'PROXY'
2013-03-06 09:50:04.481393 [NOTICE] switch_loadable_module.c:324 Adding File Format 'AAL2-G726-24'
2013-03-06 09:50:04.481428 [NOTICE] switch_loadable_module.c:324 Adding File Format 'AAL2-G726-32'
2013-03-06 09:50:04.481475 [NOTICE] switch_loadable_module.c:324 Adding File Format 'G726-32'
2013-03-06 09:50:04.481518 [NOTICE] switch_loadable_module.c:324 Adding File Format 'G722'
2013-03-06 09:50:04.481554 [NOTICE] switch_loadable_module.c:324 Adding File Format 'AAL2-G726-40'
2013-03-06 09:50:04.481624 [NOTICE] switch_loadable_module.c:324 Adding File Format 'G726-40'
2013-03-06 09:50:04.481661 [NOTICE] switch_loadable_module.c:324 Adding File Format 'GSM'
2013-03-06 09:50:04.481697 [NOTICE] switch_loadable_module.c:324 Adding File Format 'H263-1998'
2013-03-06 09:50:04.481738 [NOTICE] switch_loadable_module.c:324 Adding File Format 'H263-2000'
2013-03-06 09:50:04.481798 [NOTICE] switch_loadable_module.c:324 Adding File Format 'H264'
2013-03-06 09:50:04.481835 [NOTICE] switch_loadable_module.c:324 Adding File Format 'H261'
2013-03-06 09:50:04.481871 [NOTICE] switch_loadable_module.c:324 Adding File Format 'G723'
2013-03-06 09:50:04.481906 [NOTICE] switch_loadable_module.c:324 Adding File Format 'LPC'
2013-03-06 09:50:04.481996 [NOTICE] switch_loadable_module.c:324 Adding File Format 'PROXY-VID'
2013-03-06 09:50:04.482040 [NOTICE] switch_loadable_module.c:324 Adding File Format 'VP8'
2013-03-06 09:50:04.482442 [NOTICE] switch_loadable_module.c:298 Adding API Function 'stop_local_stream'
2013-03-06 09:50:04.482522 [NOTICE] switch_loadable_module.c:298 Adding API Function 'start_local_stream'
2013-03-06 09:50:04.482567 [NOTICE] switch_loadable_module.c:298 Adding API Function 'show_local_stream'
2013-03-06 09:50:04.482610 [NOTICE] switch_loadable_module.c:324 Adding File Format 'local_stream'
2013-03-06 09:50:04.482845 [NOTICE] switch_loadable_module.c:324 Adding File Format 'tone_stream'
2013-03-06 09:50:04.482893 [NOTICE] switch_loadable_module.c:324 Adding File Format 'silence_stream'
2013-03-06 09:50:04.485184 [DEBUG] switch_loadable_module.c:1283 Loading module with global namespace at request of module
2013-03-06 09:50:04.485342 [DEBUG] mod_spidermonkey.c:980 Successfully Loaded [/usr/local/freeswitch/mod/mod_spidermonkey_teletone.so]
2013-03-06 09:50:04.485465 [DEBUG] mod_spidermonkey.c:980 Successfully Loaded [/usr/local/freeswitch/mod/mod_spidermonkey_core_db.so]
2013-03-06 09:50:04.485565 [DEBUG] mod_spidermonkey.c:980 Successfully Loaded [/usr/local/freeswitch/mod/mod_spidermonkey_socket.so]
2013-03-06 09:50:04.485605 [NOTICE] switch_loadable_module.c:254 Adding Application 'javascript'
2013-03-06 09:50:04.485653 [NOTICE] switch_loadable_module.c:276 Adding Chat Application 'javascript'
2013-03-06 09:50:04.485705 [NOTICE] switch_loadable_module.c:298 Adding API Function 'jsrun'
2013-03-06 09:50:04.485734 [NOTICE] switch_loadable_module.c:298 Adding API Function 'jsapi'
2013-03-06 09:50:04.486344 [DEBUG] switch_loadable_module.c:1283 Loading module with global namespace at request of module
2013-03-06 09:50:04.486387 [NOTICE] switch_loadable_module.c:214 Adding Dialplan 'LUA'
2013-03-06 09:50:04.486433 [NOTICE] switch_loadable_module.c:254 Adding Application 'lua'
2013-03-06 09:50:04.486465 [NOTICE] switch_loadable_module.c:276 Adding Chat Application 'lua'
2013-03-06 09:50:04.486506 [NOTICE] switch_loadable_module.c:298 Adding API Function 'luarun'
2013-03-06 09:50:04.486548 [NOTICE] switch_loadable_module.c:298 Adding API Function 'lua'
2013-03-06 09:50:04.486762 [NOTICE] switch_loadable_module.c:425 Adding Say interface 'en'
2013-03-06 09:50:04.486963 [INFO] switch_time.c:475 Clock synchronized to system time.
2013-03-06 09:50:04.487150 [DEBUG] mod_event_socket.c:2805 Socket up listening on 0.0.0.0:8021
2013-03-06 09:50:04.487378 [NOTICE] switch_core.c:1209 Created ip list rfc1918.auto default (deny)
2013-03-06 09:50:04.487387 [NOTICE] switch_utils.c:295 Adding 10.0.0.0/8 (allow) [] to list rfc1918.auto
2013-03-06 09:50:04.487392 [NOTICE] switch_utils.c:295 Adding 172.16.0.0/12 (allow) [] to list rfc1918.auto
2013-03-06 09:50:04.487395 [NOTICE] switch_utils.c:295 Adding 192.168.0.0/16 (allow) [] to list rfc1918.auto
2013-03-06 09:50:04.487398 [NOTICE] switch_core.c:1217 Created ip list wan.auto default (allow)
2013-03-06 09:50:04.487401 [NOTICE] switch_utils.c:295 Adding 0.0.0.0/8 (deny) [] to list wan.auto
2013-03-06 09:50:04.487404 [NOTICE] switch_utils.c:295 Adding 10.0.0.0/8 (deny) [] to list wan.auto
2013-03-06 09:50:04.487408 [NOTICE] switch_utils.c:295 Adding 172.16.0.0/12 (deny) [] to list wan.auto
2013-03-06 09:50:04.487411 [NOTICE] switch_utils.c:295 Adding 192.168.0.0/16 (deny) [] to list wan.auto
2013-03-06 09:50:04.487414 [NOTICE] switch_core.c:1226 Created ip list nat.auto default (deny)
2013-03-06 09:50:04.487417 [NOTICE] switch_core.c:1228 Adding 10.0.2.15/255.255.255.0 (deny) to list nat.auto
2013-03-06 09:50:04.487420 [NOTICE] switch_utils.c:295 Adding 10.0.0.0/8 (allow) [] to list nat.auto
2013-03-06 09:50:04.487423 [NOTICE] switch_utils.c:295 Adding 172.16.0.0/12 (allow) [] to list nat.auto
2013-03-06 09:50:04.487426 [NOTICE] switch_utils.c:295 Adding 192.168.0.0/16 (allow) [] to list nat.auto
2013-03-06 09:50:04.487429 [NOTICE] switch_core.c:1237 Created ip list loopback.auto default (deny)
2013-03-06 09:50:04.487432 [NOTICE] switch_utils.c:295 Adding 127.0.0.0/8 (allow) [] to list loopback.auto
2013-03-06 09:50:04.487435 [NOTICE] switch_core.c:1243 Created ip list localnet.auto default (deny)
2013-03-06 09:50:04.487438 [NOTICE] switch_core.c:1246 Adding 10.0.2.15/255.255.255.0 (allow) to list localnet.auto
2013-03-06 09:50:04.487449 [NOTICE] switch_utils.c:295 Adding 192.168.42.0/24 (deny) [] to list lan
2013-03-06 09:50:04.487452 [NOTICE] switch_core.c:1342 Adding 192.168.42.0/24 (deny) to list lan
2013-03-06 09:50:04.487455 [NOTICE] switch_utils.c:295 Adding 192.168.42.42/32 (allow) [] to list lan
2013-03-06 09:50:04.487458 [NOTICE] switch_core.c:1342 Adding 192.168.42.42/32 (allow) to list lan
2013-03-06 09:50:04.487489 [NOTICE] switch_utils.c:295 Adding 192.0.2.0/24 (allow) [brian@10.0.0.12] to list domains
2013-03-06 09:50:04.487499 [NOTICE] switch_utils.c:295 Adding 10.0.0.10/24 (allow) [] to list domains
2013-03-06 09:50:04.487503 [NOTICE] switch_core.c:1342 Adding 10.0.0.10/24 (allow) to list domains
2013-03-06 09:50:04.488134 [DEBUG] switch_loadable_module.c:642 Chat Thread Started
2013-03-06 09:50:04.488146 [DEBUG] switch_loadable_module.c:642 Chat Thread Started
2013-03-06 09:50:27.168131 [NOTICE] switch_channel.c:976 New Channel sofia/internal/1000@10.0.0.10 [6b6a3a56-865c-11e2-b717-db70ade1bfb6]
2013-03-06 09:50:27.168131 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:27.168131 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:27.168131 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_NEW
2013-03-06 09:50:27.168131 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/1000@10.0.0.10) State NEW
2013-03-06 09:50:27.188124 [DEBUG] sofia.c:7697 IP 10.0.0.10 Approved by acl "domains[]". Access Granted.
2013-03-06 09:50:27.188124 [DEBUG] sofia.c:5597 Channel sofia/internal/1000@10.0.0.10 entering state [received][100]
2013-03-06 09:50:27.188124 [DEBUG] sofia.c:5608 Remote SDP:
v=0
o=- 3571563091 3571563091 IN IP4 10.0.0.3
s=pjmedia
c=IN IP4 10.0.0.3
t=0 0
m=audio 4022 RTP/AVP 18 102 105 8 101
c=IN IP4 10.0.0.3
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:102 ILBC/8000
a=fmtp:102 mode=30
a=rtpmap:105 speex/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:4023 IN IP4 10.0.0.3
2013-03-06 09:50:27.188124 [DEBUG] sofia.c:5821 (sofia/internal/1000@10.0.0.10) State Change CS_NEW -> CS_INIT
2013-03-06 09:50:27.188124 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_INIT
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/1000@10.0.0.10) State INIT
2013-03-06 09:50:27.188124 [DEBUG] mod_sofia.c:86 sofia/internal/1000@10.0.0.10 SOFIA INIT
2013-03-06 09:50:27.188124 [DEBUG] mod_sofia.c:126 (sofia/internal/1000@10.0.0.10) State Change CS_INIT -> CS_ROUTING
2013-03-06 09:50:27.188124 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/1000@10.0.0.10) State INIT going to sleep
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_ROUTING
2013-03-06 09:50:27.188124 [DEBUG] switch_channel.c:2012 (sofia/internal/1000@10.0.0.10) Callstate Change DOWN -> RINGING
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/1000@10.0.0.10) State ROUTING
2013-03-06 09:50:27.188124 [DEBUG] mod_sofia.c:149 sofia/internal/1000@10.0.0.10 SOFIA ROUTING
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:117 sofia/internal/1000@10.0.0.10 Standard ROUTING
2013-03-06 09:50:27.188124 [INFO] mod_dialplan_xml.c:557 Processing Extension 1000 <1000>->3 in context default
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->unloop] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->tod_example] continue=true
Dialplan: sofia/internal/1000@10.0.0.10 Date/Time Match (PASS) [tod_example] break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 Action set(open=true)
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->holiday_example] continue=true
Dialplan: sofia/internal/1000@10.0.0.10 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->global-intercept] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [global-intercept] destination_number(3) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [group-intercept] destination_number(3) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [intercept-ext] destination_number(3) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->redial] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [redial] destination_number(3) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->global] continue=true
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never
Dialplan: sofia/internal/1000@10.0.0.10 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [global] ${switch_r_sdp}(v=0
o=- 3571563091 3571563091 IN IP4 10.0.0.3
s=pjmedia
c=IN IP4 10.0.0.3
t=0 0
m=audio 4022 RTP/AVP 18 102 105 8 101
c=IN IP4 10.0.0.3
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:102 ILBC/8000
a=fmtp:102 mode=30
a=rtpmap:105 speex/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtcp:4023 IN IP4 10.0.0.3
) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
Dialplan: sofia/internal/1000@10.0.0.10 Absolute Condition [global]
Dialplan: sofia/internal/1000@10.0.0.10 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1000@10.0.0.10 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1000@10.0.0.10 Action hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1000@10.0.0.10 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->snom-demo-2] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [snom-demo-2] destination_number(3) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->snom-demo-1] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [snom-demo-1] destination_number(3) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [eavesdrop] destination_number(3) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [eavesdrop] destination_number(3) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->call_return] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [call_return] destination_number(3) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->del-group] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [del-group] destination_number(3) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->add-group] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [add-group] destination_number(3) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->call-group-simo] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [call-group-simo] destination_number(3) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->call-group-order] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [call-group-order] destination_number(3) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->extension-intercom] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [extension-intercom] destination_number(3) =~ /^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->Local_Extension] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [Local_Extension] destination_number(3) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [Local_Extension_Skinny] destination_number(3) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->group_dial_sales] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [group_dial_sales] destination_number(3) =~ /^2000$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->group_dial_support] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [group_dial_support] destination_number(3) =~ /^2001$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->group_dial_billing] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [group_dial_billing] destination_number(3) =~ /^2002$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->operator] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [operator] destination_number(3) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->vmain] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [vmain] destination_number(3) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->sip_uri] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [sip_uri] destination_number(3) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->nb_conferences] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [nb_conferences] destination_number(3) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->wb_conferences] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [wb_conferences] destination_number(3) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->uwb_conferences] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [uwb_conferences] destination_number(3) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [cdquality_conferences] destination_number(3) =~ /^(33\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(3) =~ /^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [mad_boss_intercom] destination_number(3) =~ /^0911$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [mad_boss_intercom] destination_number(3) =~ /^0912$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->mad_boss] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [mad_boss] destination_number(3) =~ /^0913$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->ivr_demo] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [ivr_demo] destination_number(3) =~ /^5000$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->dynamic_conference] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [dynamic_conference] destination_number(3) =~ /^5001$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->rtp_multicast_page] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [rtp_multicast_page] destination_number(3) =~ /^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [park] destination_number(3) =~ /^5900$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [unpark] destination_number(3) =~ /^5901$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->valet_park] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [valet_park] destination_number(3) =~ /^(6000)$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->valet_park] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [valet_park] destination_number(3) =~ /^(60\d[1-9])$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [park] destination_number(3) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [unpark] destination_number(3) =~ /^parking$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [park] destination_number(3) =~ /callpark/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [unpark] destination_number(3) =~ /pickup/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->wait] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [wait] destination_number(3) =~ /^wait$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->fax_receive] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [fax_receive] destination_number(3) =~ /^9178$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->fax_transmit] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [fax_transmit] destination_number(3) =~ /^9179$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->ringback_180] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [ringback_180] destination_number(3) =~ /^9180$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->ringback_183_uk_ring] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [ringback_183_uk_ring] destination_number(3) =~ /^9181$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->ringback_183_music_ring] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [ringback_183_music_ring] destination_number(3) =~ /^9182$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->ringback_post_answer_uk_ring] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(3) =~ /^9183$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->ringback_post_answer_music] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [ringback_post_answer_music] destination_number(3) =~ /^9184$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->ClueCon] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [ClueCon] destination_number(3) =~ /^9191$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->show_info] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [show_info] destination_number(3) =~ /^9192$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->video_record] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [video_record] destination_number(3) =~ /^9193$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->video_playback] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [video_playback] destination_number(3) =~ /^9194$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->delay_echo] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [delay_echo] destination_number(3) =~ /^9195$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->echo] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [echo] destination_number(3) =~ /^9196$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->milliwatt] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [milliwatt] destination_number(3) =~ /^9197$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->tone_stream] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [tone_stream] destination_number(3) =~ /^9198$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->zrtp_enrollement] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [zrtp_enrollement] destination_number(3) =~ /^9787$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->hold_music] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [hold_music] destination_number(3) =~ /^9664$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->laugh break] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Regex (FAIL) [laugh break] destination_number(3) =~ /^9386$/ break=on-false
Dialplan: sofia/internal/1000@10.0.0.10 parsing [default->adhearsion] continue=false
Dialplan: sofia/internal/1000@10.0.0.10 Absolute Condition [adhearsion]
Dialplan: sofia/internal/1000@10.0.0.10 Action log(INFO por aca pase yo)
Dialplan: sofia/internal/1000@10.0.0.10 Action park()
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/1000@10.0.0.10) State Change CS_ROUTING -> CS_EXECUTE
2013-03-06 09:50:27.188124 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:27.188124 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/1000@10.0.0.10) State ROUTING going to sleep
2013-03-06 09:50:27.208880 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_EXECUTE
2013-03-06 09:50:27.208880 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/1000@10.0.0.10) State EXECUTE
2013-03-06 09:50:27.208880 [DEBUG] mod_sofia.c:242 sofia/internal/1000@10.0.0.10 SOFIA EXECUTE
2013-03-06 09:50:27.208880 [DEBUG] switch_core_state_machine.c:209 sofia/internal/1000@10.0.0.10 Standard EXECUTE
EXECUTE sofia/internal/1000@10.0.0.10 set(open=true)
2013-03-06 09:50:27.208880 [DEBUG] mod_dptools.c:1367 sofia/internal/1000@10.0.0.10 SET [open]=[true]
EXECUTE sofia/internal/1000@10.0.0.10 hash(insert/10.0.0.12-spymap/1000/6b6a3a56-865c-11e2-b717-db70ade1bfb6)
EXECUTE sofia/internal/1000@10.0.0.10 hash(insert/10.0.0.12-last_dial/1000/3)
EXECUTE sofia/internal/1000@10.0.0.10 hash(insert/10.0.0.12-last_dial/global/6b6a3a56-865c-11e2-b717-db70ade1bfb6)
EXECUTE sofia/internal/1000@10.0.0.10 export(RFC2822_DATE=Wed, 06 Mar 2013 09:50:27 -0300)
2013-03-06 09:50:27.208880 [DEBUG] switch_channel.c:1143 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 06 Mar 2013 09:50:27 -0300]
EXECUTE sofia/internal/1000@10.0.0.10 log(INFO por aca pase yo)
2013-03-06 09:50:27.208880 [INFO] mod_dptools.c:1533 por aca pase yo
EXECUTE sofia/internal/1000@10.0.0.10 park()
2013-03-06 09:50:27.368104 [DEBUG] switch_core_session.c:1110 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:27.388199 [DEBUG] switch_ivr.c:612 sofia/internal/1000@10.0.0.10 Command Execute respond(180 Ringing)
EXECUTE sofia/internal/1000@10.0.0.10 respond(180 Ringing)
2013-03-06 09:50:27.388199 [DEBUG] mod_sofia.c:2631 Responding with 180 [Ringing]
2013-03-06 09:50:27.388199 [WARNING] mod_sofia.c:2692 Cannot respond.
2013-03-06 09:50:27.388199 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.088099 [DEBUG] switch_core_session.c:1110 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.088099 [DEBUG] switch_ivr.c:612 sofia/internal/1000@10.0.0.10 Command Execute answer(%[punchblock_command_id=5e3573e3-1e59-4921-a5f5-83481d5bbed1])
2013-03-06 09:50:28.088099 [DEBUG] switch_event.c:1608 Parsing variable [punchblock_command_id]=[5e3573e3-1e59-4921-a5f5-83481d5bbed1]
EXECUTE sofia/internal/1000@10.0.0.10 answer()
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [G729:18:8000:20:8000]/[G722:9:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [G729:18:8000:20:8000]/[PCMU:0:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [G729:18:8000:20:8000]/[PCMA:8:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [G729:18:8000:20:8000]/[GSM:3:8000:20:13200]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [ILBC:102:8000:30:0]/[G722:9:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [ILBC:102:8000:30:0]/[PCMU:0:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [ILBC:102:8000:30:0]/[PCMA:8:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [ILBC:102:8000:30:0]/[GSM:3:8000:20:13200]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [speex:105:8000:20:0]/[G722:9:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [speex:105:8000:20:0]/[PCMU:0:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [speex:105:8000:20:0]/[PCMA:8:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [speex:105:8000:20:0]/[GSM:3:8000:20:13200]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:3110 Set Codec sofia/internal/1000@10.0.0.10 PCMA/8000 20 ms 160 samples 64000 bits
2013-03-06 09:50:28.108896 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@10.0.0.10 Original read codec set to PCMA:8
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:5284 Set 2833 dtmf send/recv payload to 101
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:3369 AUDIO RTP [sofia/internal/1000@10.0.0.10] 10.0.0.12 port 30536 -> 10.0.0.3 port 4022 codec: 8 ms: 20
2013-03-06 09:50:28.108896 [DEBUG] switch_rtp.c:1982 Starting timer [soft] 160 bytes per 20ms
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:3633 Set 2833 dtmf send payload to 101
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:3639 Set 2833 dtmf receive payload to 101
2013-03-06 09:50:28.108896 [DEBUG] sofia_glue.c:3666 sofia/internal/1000@10.0.0.10 Set rtp dtmf delay to 40
2013-03-06 09:50:28.108896 [NOTICE] sofia_glue.c:4277 Pre-Answer sofia/internal/1000@10.0.0.10!
2013-03-06 09:50:28.108896 [DEBUG] switch_channel.c:3155 (sofia/internal/1000@10.0.0.10) Callstate Change RINGING -> EARLY
2013-03-06 09:50:28.108896 [DEBUG] mod_sofia.c:856 Local SDP sofia/internal/1000@10.0.0.10:
v=0
o=FreeSWITCH 1362543692 1362543693 IN IP4 10.0.0.12
s=FreeSWITCH
c=IN IP4 10.0.0.12
t=0 0
m=audio 30536 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
2013-03-06 09:50:28.108896 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.108896 [DEBUG] sofia.c:5597 Channel sofia/internal/1000@10.0.0.10 entering state [completed][200]
2013-03-06 09:50:28.108896 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.108896 [DEBUG] switch_channel.c:3414 (sofia/internal/1000@10.0.0.10) Callstate Change EARLY -> ACTIVE
2013-03-06 09:50:28.108896 [NOTICE] mod_dptools.c:1199 Channel [sofia/internal/1000@10.0.0.10] has been answered
2013-03-06 09:50:28.108896 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.108896 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.108896 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.108896 [DEBUG] sofia.c:5597 Channel sofia/internal/1000@10.0.0.10 entering state [ready][200]
2013-03-06 09:50:28.148085 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:28.169293 [INFO] sofia.c:931 sofia/internal/1000@10.0.0.10 Update Callee ID to "1000" <1000>
2013-03-06 09:50:28.169293 [DEBUG] sofia.c:7471 dispatched freeswitch event for INFO
2013-03-06 09:50:28.248115 [DEBUG] switch_rtp.c:3701 Correct ip/port confirmed.
2013-03-06 09:50:30.208179 [DEBUG] switch_rtp.c:3912 RTP RECV DTMF 1:1600
2013-03-06 09:50:30.208179 [DEBUG] switch_channel.c:417 RECV DTMF 1:1600
2013-03-06 09:50:30.328276 [DEBUG] switch_ivr_originate.c:2039 Parsing global variables
2013-03-06 09:50:30.328276 [DEBUG] switch_event.c:1608 Parsing variable [return_ring_ready]=[true]
2013-03-06 09:50:30.328276 [DEBUG] switch_event.c:1608 Parsing variable [origination_uuid]=[dc12f524-0ff3-4d14-8e58-9e0487c6727b]
2013-03-06 09:50:30.328276 [DEBUG] switch_event.c:1608 Parsing variable [origination_caller_id_number]=[1000]
2013-03-06 09:50:30.328276 [DEBUG] switch_event.c:1608 Parsing variable [originate_timeout]=[30]
2013-03-06 09:50:30.328276 [NOTICE] switch_channel.c:976 New Channel sofia/external/1001 [dc12f524-0ff3-4d14-8e58-9e0487c6727b]
2013-03-06 09:50:30.328276 [DEBUG] mod_sofia.c:4960 (sofia/external/1001) State Change CS_NEW -> CS_INIT
2013-03-06 09:50:30.328276 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.328276 [DEBUG] switch_core_session.c:560 sofia/external/1001 set UUID=dc12f524-0ff3-4d14-8e58-9e0487c6727b
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_INIT
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:454 (sofia/external/1001) State INIT
2013-03-06 09:50:30.328276 [DEBUG] mod_sofia.c:86 sofia/external/1001 SOFIA INIT
2013-03-06 09:50:30.328276 [DEBUG] sofia_glue.c:2664 Local SDP:
v=0
o=FreeSWITCH 1362541744 1362541745 IN IP4 10.0.0.12
s=FreeSWITCH
c=IN IP4 10.0.0.12
t=0 0
m=audio 32486 RTP/AVP 0 8 3 101 13
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
2013-03-06 09:50:30.328276 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.328276 [DEBUG] mod_sofia.c:126 (sofia/external/1001) State Change CS_INIT -> CS_ROUTING
2013-03-06 09:50:30.328276 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:454 (sofia/external/1001) State INIT going to sleep
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_ROUTING
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:470 (sofia/external/1001) State ROUTING
2013-03-06 09:50:30.328276 [DEBUG] mod_sofia.c:149 sofia/external/1001 SOFIA ROUTING
2013-03-06 09:50:30.328276 [DEBUG] switch_ivr_originate.c:67 (sofia/external/1001) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2013-03-06 09:50:30.328276 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:470 (sofia/external/1001) State ROUTING going to sleep
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_CONSUME_MEDIA
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:489 (sofia/external/1001) State CONSUME_MEDIA
2013-03-06 09:50:30.328276 [DEBUG] switch_core_state_machine.c:489 (sofia/external/1001) State CONSUME_MEDIA going to sleep
2013-03-06 09:50:30.328276 [DEBUG] sofia.c:5597 Channel sofia/external/1001 entering state [calling][0]
2013-03-06 09:50:30.348124 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.348124 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.348124 [DEBUG] sofia.c:5597 Channel sofia/external/1001 entering state [calling][0]
2013-03-06 09:50:30.448203 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.448203 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.448203 [INFO] sofia.c:931 sofia/external/1001 Update Callee ID to "1001" <sip:1001@10.0.0.14>
2013-03-06 09:50:30.448203 [DEBUG] sofia.c:5597 Channel sofia/external/1001 entering state [proceeding][183]
2013-03-06 09:50:30.448203 [DEBUG] sofia.c:5608 Remote SDP:
v=0
o=FreeSWITCH 1362544011 1362544012 IN IP4 10.0.0.14
s=FreeSWITCH
c=IN IP4 10.0.0.14
t=0 0
m=audio 30310 RTP/AVP 0 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:5155 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:3110 Set Codec sofia/external/1001 PCMU/8000 20 ms 160 samples 64000 bits
2013-03-06 09:50:30.448203 [DEBUG] switch_core_codec.c:111 sofia/external/1001 Original read codec set to PCMU:0
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:5277 Set 2833 dtmf send payload to 101
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:3369 AUDIO RTP [sofia/external/1001] 10.0.0.12 port 32486 -> 10.0.0.14 port 30310 codec: 0 ms: 20
2013-03-06 09:50:30.448203 [DEBUG] switch_rtp.c:1982 Starting timer [soft] 160 bytes per 20ms
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:3633 Set 2833 dtmf send payload to 101
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:3639 Set 2833 dtmf receive payload to 101
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:3666 sofia/external/1001 Set rtp dtmf delay to 40
2013-03-06 09:50:30.448203 [DEBUG] sofia_glue.c:3672 Set comfort noise payload to 13
2013-03-06 09:50:30.448203 [NOTICE] sofia_glue.c:4277 Pre-Answer sofia/external/1001!
2013-03-06 09:50:30.448203 [DEBUG] switch_channel.c:3155 (sofia/external/1001) Callstate Change DOWN -> EARLY
2013-03-06 09:50:30.448203 [DEBUG] switch_ivr_originate.c:3463 Originate Resulted in Success: [sofia/external/1001]
2013-03-06 09:50:30.448203 [INFO] switch_channel.c:2872 sofia/external/1001 Flipping CID from "" <1000> to "1001" <1001>
2013-03-06 09:50:30.448203 [DEBUG] mod_commands.c:4049 (sofia/external/1001) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
2013-03-06 09:50:30.448203 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:30.448203 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_EXECUTE
2013-03-06 09:50:30.448203 [DEBUG] switch_core_state_machine.c:477 (sofia/external/1001) State EXECUTE
2013-03-06 09:50:30.448203 [DEBUG] mod_sofia.c:242 sofia/external/1001 SOFIA EXECUTE
2013-03-06 09:50:30.448203 [DEBUG] switch_core_state_machine.c:209 sofia/external/1001 Standard EXECUTE
EXECUTE sofia/external/1001 park()
2013-03-06 09:50:30.488094 [DEBUG] switch_rtp.c:3701 Correct ip/port confirmed.
2013-03-06 09:50:33.448208 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.448208 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.468131 [INFO] sofia.c:931 sofia/external/1001 Update Callee ID to "Outbound Call" <sip:1001@10.0.0.14>
2013-03-06 09:50:33.468131 [DEBUG] sofia.c:5597 Channel sofia/external/1001 entering state [completing][200]
2013-03-06 09:50:33.468131 [DEBUG] sofia.c:5605 Duplicate SDP
v=0
o=FreeSWITCH 1362544011 1362544012 IN IP4 10.0.0.14
s=FreeSWITCH
c=IN IP4 10.0.0.14
t=0 0
m=audio 30310 RTP/AVP 0 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
2013-03-06 09:50:33.468131 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.468131 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.468131 [DEBUG] sofia.c:5597 Channel sofia/external/1001 entering state [ready][200]
2013-03-06 09:50:33.468131 [DEBUG] switch_channel.c:3414 (sofia/external/1001) Callstate Change EARLY -> ACTIVE
2013-03-06 09:50:33.468131 [NOTICE] sofia.c:6288 Channel [sofia/external/1001] has been answered
2013-03-06 09:50:33.468131 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.488230 [DEBUG] sofia.c:7471 dispatched freeswitch event for INFO
2013-03-06 09:50:33.488230 [DEBUG] switch_ivr_bridge.c:1700 (sofia/external/1001) State Change CS_EXECUTE -> CS_HIBERNATE
2013-03-06 09:50:33.488230 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.488230 [DEBUG] switch_ivr_bridge.c:1702 (sofia/internal/1000@10.0.0.10) State Change CS_EXECUTE -> CS_HIBERNATE
2013-03-06 09:50:33.488230 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/1000@10.0.0.10) State EXECUTE going to sleep
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_HIBERNATE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1000@10.0.0.10) State HIBERNATE
2013-03-06 09:50:33.508112 [DEBUG] mod_sofia.c:223 sofia/internal/1000@10.0.0.10 SOFIA HIBERNATE
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:766 (sofia/internal/1000@10.0.0.10) State Change CS_HIBERNATE -> CS_RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1000@10.0.0.10) State HIBERNATE going to sleep
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@10.0.0.10) State RESET
2013-03-06 09:50:33.508112 [DEBUG] mod_sofia.c:167 sofia/internal/1000@10.0.0.10 SOFIA RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:751 sofia/internal/1000@10.0.0.10 CUSTOM RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:106 sofia/internal/1000@10.0.0.10 Standard RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@10.0.0.10) State RESET going to sleep
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:477 (sofia/external/1001) State EXECUTE going to sleep
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_HIBERNATE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:492 (sofia/external/1001) State HIBERNATE
2013-03-06 09:50:33.508112 [DEBUG] mod_sofia.c:223 sofia/external/1001 SOFIA HIBERNATE
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:766 (sofia/external/1001) State Change CS_HIBERNATE -> CS_RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:492 (sofia/external/1001) State HIBERNATE going to sleep
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1001) State RESET
2013-03-06 09:50:33.508112 [DEBUG] mod_sofia.c:167 sofia/external/1001 SOFIA RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:751 sofia/external/1001 CUSTOM RESET
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:758 (sofia/external/1001) State Change CS_RESET -> CS_SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:473 (sofia/external/1001) State RESET going to sleep
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:483 (sofia/external/1001) State SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] mod_sofia.c:687 SOFIA SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:776 sofia/external/1001 CUSTOM SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:808 (sofia/internal/1000@10.0.0.10) State Change CS_RESET -> CS_SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:483 (sofia/internal/1000@10.0.0.10) State SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] mod_sofia.c:687 SOFIA SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_ivr_bridge.c:776 sofia/internal/1000@10.0.0.10 CUSTOM SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:277 sofia/internal/1000@10.0.0.10 Standard SOFT_EXECUTE
2013-03-06 09:50:33.508112 [DEBUG] switch_core_state_machine.c:483 (sofia/internal/1000@10.0.0.10) State SOFT_EXECUTE going to sleep
2013-03-06 09:50:33.528168 [DEBUG] switch_ivr_bridge.c:1268 (sofia/internal/1000@10.0.0.10) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
2013-03-06 09:50:33.528168 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.528168 [DEBUG] switch_core_session.c:830 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.528168 [DEBUG] switch_core_session.c:830 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.528168 [DEBUG] switch_ivr_bridge.c:1370 (sofia/internal/1000@10.0.0.10) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2013-03-06 09:50:33.528168 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.528168 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_EXCHANGE_MEDIA
2013-03-06 09:50:33.528168 [DEBUG] switch_core_state_machine.c:480 (sofia/internal/1000@10.0.0.10) State EXCHANGE_MEDIA
2013-03-06 09:50:33.528168 [DEBUG] mod_sofia.c:681 SOFIA EXCHANGE_MEDIA
2013-03-06 09:50:33.528168 [DEBUG] switch_core_session.c:892 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.528168 [DEBUG] switch_core_session.c:892 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:33.548120 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:33.548120 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:34.688158 [DEBUG] switch_core_session.c:975 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_channel.c:3011 (sofia/external/1001) Callstate Change ACTIVE -> HANGUP
2013-03-06 09:50:34.708155 [NOTICE] sofia.c:711 Hangup sofia/external/1001 [CS_SOFT_EXECUTE] [NORMAL_CLEARING]
2013-03-06 09:50:34.708155 [DEBUG] switch_channel.c:3034 Send signal sofia/external/1001 [KILL]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_ivr_bridge.c:597 BRIDGE THREAD DONE [sofia/external/1001]
2013-03-06 09:50:34.708155 [DEBUG] switch_ivr_bridge.c:622 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_ivr_bridge.c:510 sofia/external/1001 ending bridge by request from write function
2013-03-06 09:50:34.708155 [DEBUG] switch_ivr_bridge.c:597 BRIDGE THREAD DONE [sofia/internal/1000@10.0.0.10]
2013-03-06 09:50:34.708155 [DEBUG] switch_ivr_bridge.c:622 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_channel.c:3011 (sofia/internal/1000@10.0.0.10) Callstate Change ACTIVE -> HANGUP
2013-03-06 09:50:34.708155 [NOTICE] switch_ivr_bridge.c:685 Hangup sofia/internal/1000@10.0.0.10 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2013-03-06 09:50:34.708155 [DEBUG] switch_channel.c:3034 Send signal sofia/internal/1000@10.0.0.10 [KILL]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:480 (sofia/internal/1000@10.0.0.10) State EXCHANGE_MEDIA going to sleep
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_HANGUP
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/1000@10.0.0.10) State HANGUP
2013-03-06 09:50:34.708155 [DEBUG] mod_sofia.c:497 sofia/internal/1000@10.0.0.10 Overriding SIP cause 480 with 200 from the other leg
2013-03-06 09:50:34.708155 [DEBUG] mod_sofia.c:503 Channel sofia/internal/1000@10.0.0.10 hanging up, cause: NORMAL_CLEARING
2013-03-06 09:50:34.708155 [DEBUG] mod_sofia.c:552 Sending BYE to sofia/internal/1000@10.0.0.10
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:48 sofia/internal/1000@10.0.0.10 Standard HANGUP, cause: NORMAL_CLEARING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:676 (sofia/internal/1000@10.0.0.10) State HANGUP going to sleep
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/1000@10.0.0.10) State Change CS_HANGUP -> CS_REPORTING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/1000@10.0.0.10) Running State Change CS_REPORTING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/1000@10.0.0.10) State REPORTING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:92 sofia/internal/1000@10.0.0.10 Standard REPORTING, cause: NORMAL_CLEARING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:758 (sofia/internal/1000@10.0.0.10) State REPORTING going to sleep
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/1000@10.0.0.10) State Change CS_REPORTING -> CS_DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1310 Send signal sofia/internal/1000@10.0.0.10 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1518 Session 1 (sofia/internal/1000@10.0.0.10) Locked, Waiting on external entities
2013-03-06 09:50:34.708155 [DEBUG] switch_ivr_bridge.c:1471 sofia/external/1001 skip receive message [UNBRIDGE] (channel is hungup already)
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:483 (sofia/external/1001) State SOFT_EXECUTE going to sleep
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_HANGUP
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:676 (sofia/external/1001) State HANGUP
2013-03-06 09:50:34.708155 [DEBUG] mod_sofia.c:503 Channel sofia/external/1001 hanging up, cause: NORMAL_CLEARING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:48 sofia/external/1001 Standard HANGUP, cause: NORMAL_CLEARING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:676 (sofia/external/1001) State HANGUP going to sleep
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:446 (sofia/external/1001) State Change CS_HANGUP -> CS_REPORTING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:415 (sofia/external/1001) Running State Change CS_REPORTING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:758 (sofia/external/1001) State REPORTING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:92 sofia/external/1001 Standard REPORTING, cause: NORMAL_CLEARING
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:758 (sofia/external/1001) State REPORTING going to sleep
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:440 (sofia/external/1001) State Change CS_REPORTING -> CS_DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1310 Send signal sofia/external/1001 [BREAK]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_session.c:1518 Session 2 (sofia/external/1001) Locked, Waiting on external entities
2013-03-06 09:50:34.708155 [NOTICE] switch_core_session.c:1536 Session 2 (sofia/external/1001) Ended
2013-03-06 09:50:34.708155 [NOTICE] switch_core_session.c:1540 Close Channel sofia/external/1001 [CS_DESTROY]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:565 (sofia/external/1001) Callstate Change HANGUP -> DOWN
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:568 (sofia/external/1001) Running State Change CS_DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:578 (sofia/external/1001) State DESTROY
2013-03-06 09:50:34.708155 [DEBUG] mod_sofia.c:396 sofia/external/1001 SOFIA DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:99 sofia/external/1001 Standard DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:578 (sofia/external/1001) State DESTROY going to sleep
2013-03-06 09:50:34.708155 [NOTICE] switch_core_session.c:1536 Session 1 (sofia/internal/1000@10.0.0.10) Ended
2013-03-06 09:50:34.708155 [NOTICE] switch_core_session.c:1540 Close Channel sofia/internal/1000@10.0.0.10 [CS_DESTROY]
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/1000@10.0.0.10) Callstate Change HANGUP -> DOWN
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:568 (sofia/internal/1000@10.0.0.10) Running State Change CS_DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/1000@10.0.0.10) State DESTROY
2013-03-06 09:50:34.708155 [DEBUG] mod_sofia.c:396 sofia/internal/1000@10.0.0.10 SOFIA DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:99 sofia/internal/1000@10.0.0.10 Standard DESTROY
2013-03-06 09:50:34.708155 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/1000@10.0.0.10) State DESTROY going to sleep
<include>
<gateway name="re_engine_out">
<param name="username" value="1000"/>
<param name="realm" value="$${outbound_ip}"/>
<param name="password" value="1234"/>
<param name="extension" value=""/>
<param name="proxy" value="$${outbound_ip}"/>
<param name="register" value="false"/>
<param name="context" value="default"/>
<param name="expire-seconds" value="600"/>
<param name="caller-id-in-from" value="true"/>
<!--<param name="inbound-bypass-media" value="true"/>-->
<param name="from-domain" value="10.0.0.7"/>
</gateway>
</include>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment