[root@ringme_morskaya freeswitchconf]# egrep -A 30 "67cdaf36-4d0a-11e6-83d9-eb77bd4505f4|67d32966-4d0a-11e6-8410-eb77bd4505f4" /var/log/freeswitch/freeswitch.log.2016-07-18-20-28-37.1
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [NOTICE] switch_channel.c:1077 New Channel sofia/internal/483*101@sipproxy.telphin.ru [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/483*101@sipproxy.telphin.ru) Running State Change CS_NEW
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] sofia.c:8964 sofia/internal/483*101@sipproxy.telphin.ru receiving invite from 213.170.84.105:5060 version: 1.4.26  64bit
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] sofia.c:6658 Channel sofia/internal/483*101@sipproxy.telphin.ru entering state [received][100]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] sofia.c:6668 Remote SDP:
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 v=0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 o=root 452279176 452279176 IN IP4 192.77.1.39
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 s=call
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 c=IN IP4 192.77.1.39
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 t=0 0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 m=audio 52530 RTP/AVP 0 8
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=rtpmap:0 PCMU/8000
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=rtpmap:8 PCMA/8000
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=ptime:20
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] sofia.c:6934 (sofia/internal/483*101@sipproxy.telphin.ru) State Change CS_NEW -> CS_INIT
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/483*101@sipproxy.telphin.ru) State NEW
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/483*101@sipproxy.telphin.ru) Running State Change CS_INIT
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/483*101@sipproxy.telphin.ru) State INIT
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] mod_sofia.c:87 sofia/internal/483*101@sipproxy.telphin.ru SOFIA INIT
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:40 sofia/internal/483*101@sipproxy.telphin.ru Standard INIT
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/483*101@sipproxy.telphin.ru) State Change CS_INIT -> CS_ROUTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/483*101@sipproxy.telphin.ru) State INIT going to sleep
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/483*101@sipproxy.telphin.ru) Running State Change CS_ROUTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_channel.c:2206 (sofia/internal/483*101@sipproxy.telphin.ru) Callstate Change DOWN -> RINGING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/483*101@sipproxy.telphin.ru) State ROUTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] mod_sofia.c:123 sofia/internal/483*101@sipproxy.telphin.ru SOFIA ROUTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [DEBUG] switch_core_state_machine.c:166 sofia/internal/483*101@sipproxy.telphin.ru Standard ROUTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.776394 [INFO] mod_dialplan_xml.c:635 Processing 101 <483*101>->89031883186 in context public
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru parsing [public->Pre-sets] continue=true
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Absolute Condition [Pre-sets]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_h_X-FromID=10494)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(INFO sip_h_X-FromID=${sip_h_X-FromID})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(X-Next=@)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(INFO X-Next=${X-Next})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_received_port=5060)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(INFO sip_received_port=${sip_received_port})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru parsing [public->check_inbound] continue=true
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Absolute Condition [check_inbound]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(X-need-bind=True) INLINE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(X-need-bind=True)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [X-need-bind]=[True]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(info set: X-need-bind=True)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru parsing [public->check_outbound] continue=true
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Absolute Condition [check_outbound]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action export(nolocal:X-need-bind=False) INLINE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru export(nolocal:X-need-bind=False)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_channel.c:1269 EXPORT (export_vars) (REMOTE ONLY) [X-need-bind]=[False]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(info export: nolocal:X-need-bind=False)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru parsing [public->PRELOAD extension] continue=true
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Absolute Condition [PRELOAD extension]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(X-preloaded=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(transfer_ringback=${us-ring})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(ringback=${us-ring})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_copy_custom_headers=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_rh_X-UUID=${uuid})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_ph_X-UUID=${uuid})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action unset(invalid_option_key)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action unset(X-AttXferred)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Regex (PASS) [PRELOAD extension] ${sip_received_port}(5060) =~ /^(\d+)$/ break=never
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_received_port=5060)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Regex (PASS) [PRELOAD extension] ${sip_ph_X-Call-UUID}() =~ /^$/ break=never
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(INFO NO X-Call-UUID, setting sip_*h_X-Call-UUID=${call_uuid})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_h_X-Call-UUID=${call_uuid})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_rh_X-Call-UUID=${call_uuid})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_ph_X-Call-UUID=${call_uuid})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru parsing [public->BIND keys extension] continue=true
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Absolute Condition [BIND keys extension]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(debug bind run HERE)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(bridge_pre_execute_bleg_app=execute_extension)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(bridge_pre_execute_bleg_data=bind XML features)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(none,NONE,api:sleep,1)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(none_a,NONE,api:sleep,1)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Regex (PASS) [BIND keys extension] ${X-need-bind}(True) =~ /[Tt]rue/ break=on-false
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(debug bind run HERE)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(X-binded=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(X-LegA=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action export(nolocal:X-LegB=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(transfer_ringback=${us-ring})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(ringback=${us-ring})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(bind,999,exec:playback,ivr/ivr-welcome_to_freeswitch.wav)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(bind,*1,exec:execute_extension,manual_record XML features)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(bind,#1,exec:execute_extension, dx XML features)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(bind,*2,exec:execute_extension,att_xfer XML features)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action digit_action_set_realm(bind)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(none,NONE,api:sleep,1)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bind_digit_action(none_a,NONE,api:sleep,1)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru parsing [public->resolve-ext] continue=true
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Absolute Condition [resolve-ext]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action push(X-ResolveArray=dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=${strmicroepoch()})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action export(X-ResolveArray=${X-ResolveArray})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(INFO X-ResolveArray=${X-ResolveArray})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru parsing [public->to_kamailio, default DP @ (based on sip.ringme)] continue=false
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Regex (PASS) [to_kamailio, default DP @ (based on sip.ringme)] destination_number(89031883186) =~ /^(.*)$/ break=on-false
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_copy_custom_headers=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_to_port=${sip_from_port})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action set(sip_invite_domain=${sip_from_host})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action info()
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action log(DEBUG sofia_profile_name=${sofia_profile_name})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Dialplan: sofia/internal/483*101@sipproxy.telphin.ru Action bridge({absolute_codec_string=PCMA,PCMU}sofia/internal/89031883186@${sip_invite_domain}:${sip_network_port})
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/483*101@sipproxy.telphin.ru) State Change CS_ROUTING -> CS_EXECUTE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/483*101@sipproxy.telphin.ru) State ROUTING going to sleep
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/483*101@sipproxy.telphin.ru) Running State Change CS_EXECUTE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/483*101@sipproxy.telphin.ru) State EXECUTE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_sofia.c:178 sofia/internal/483*101@sipproxy.telphin.ru SOFIA EXECUTE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:258 sofia/internal/483*101@sipproxy.telphin.ru Standard EXECUTE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_h_X-FromID=10494)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_h_X-FromID]=[10494]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(INFO sip_h_X-FromID=10494)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1670 sip_h_X-FromID=10494
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(X-Next=@)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [X-Next]=[@]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(INFO X-Next=@)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1670 X-Next=@
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_received_port=5060)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_received_port]=[5060]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(INFO sip_received_port=5060)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1670 sip_received_port=5060
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(info set: X-need-bind=True)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1670 set: X-need-bind=True
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(info export: nolocal:X-need-bind=False)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1670 export: nolocal:X-need-bind=False
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(X-preloaded=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [X-preloaded]=[true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(transfer_ringback=%(2000,4000,440,480))
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [transfer_ringback]=[%(2000,4000,440,480)]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(ringback=%(2000,4000,440,480))
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [ringback]=[%(2000,4000,440,480)]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_copy_custom_headers=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_copy_custom_headers]=[true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_rh_X-UUID=67cdaf36-4d0a-11e6-83d9-eb77bd4505f4)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_rh_X-UUID]=[67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_ph_X-UUID=67cdaf36-4d0a-11e6-83d9-eb77bd4505f4)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_ph_X-UUID]=[67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru unset(invalid_option_key)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1621 UNSET [invalid_option_key]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru unset(X-AttXferred)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1621 UNSET [X-AttXferred]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_received_port=5060)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_received_port]=[5060]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(INFO NO X-Call-UUID, setting sip_*h_X-Call-UUID=67cdaf36-4d0a-11e6-83d9-eb77bd4505f4)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1670 NO X-Call-UUID, setting sip_*h_X-Call-UUID=67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_h_X-Call-UUID=67cdaf36-4d0a-11e6-83d9-eb77bd4505f4)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_h_X-Call-UUID]=[67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_rh_X-Call-UUID=67cdaf36-4d0a-11e6-83d9-eb77bd4505f4)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_rh_X-Call-UUID]=[67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_ph_X-Call-UUID=67cdaf36-4d0a-11e6-83d9-eb77bd4505f4)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_ph_X-Call-UUID]=[67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(debug bind run HERE)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1670 bind run HERE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(bridge_pre_execute_bleg_app=execute_extension)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [bridge_pre_execute_bleg_app]=[execute_extension]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(bridge_pre_execute_bleg_data=bind XML features)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [bridge_pre_execute_bleg_data]=[bind XML features]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(none,NONE,api:sleep,1)
2016-07-18 20:09:36.816381 [INFO] switch_ivr_async.c:212 Digit parser DPTOOLS: Setting realm to 'none'
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Digit parser DPTOOLS: binding NONE/none/0 callback: 0x7f62853f9d70 data: 0x7f61b4362a10
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(none_a,NONE,api:sleep,1)
2016-07-18 20:09:36.816381 [INFO] switch_ivr_async.c:212 Digit parser DPTOOLS: Setting realm to 'none_a'
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Digit parser DPTOOLS: binding NONE/none_a/0 callback: 0x7f62853f9d70 data: 0x7f61b4362ad0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(debug bind run HERE)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1670 bind run HERE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(X-binded=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [X-binded]=[true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(X-LegA=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [X-LegA]=[true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru export(nolocal:X-LegB=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_channel.c:1269 EXPORT (export_vars) (REMOTE ONLY) [X-LegB]=[true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(transfer_ringback=%(2000,4000,440,480))
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [transfer_ringback]=[%(2000,4000,440,480)]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(ringback=%(2000,4000,440,480))
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [ringback]=[%(2000,4000,440,480)]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(bind,999,exec:playback,ivr/ivr-welcome_to_freeswitch.wav)
2016-07-18 20:09:36.816381 [INFO] switch_ivr_async.c:212 Digit parser DPTOOLS: Setting realm to 'bind'
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Digit parser DPTOOLS: binding 999/bind/0 callback: 0x7f62853f9d70 data: 0x7f61b481bce8
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(bind,*1,exec:execute_extension,manual_record XML features)
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Digit parser DPTOOLS: binding *1/bind/0 callback: 0x7f62853f9d70 data: 0x7f61b481be18
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(bind,#1,exec:execute_extension, dx XML features)
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Digit parser DPTOOLS: binding #1/bind/0 callback: 0x7f62853f9d70 data: 0x7f61b481bf28
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(bind,*2,exec:execute_extension,att_xfer XML features)
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Digit parser DPTOOLS: binding *2/bind/0 callback: 0x7f62853f9d70 data: 0x7f61b481c038
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru digit_action_set_realm(bind)
2016-07-18 20:09:36.816381 [INFO] switch_ivr_async.c:212 Digit parser DPTOOLS: Setting realm to 'bind'
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(none,NONE,api:sleep,1)
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Reuse Existing Digit parser DPTOOLS: binding NONE/none/0 callback: 0x7f62853f9d70 data: 0x7f61b481c158
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bind_digit_action(none_a,NONE,api:sleep,1)
2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_async.c:321 Reuse Existing Digit parser DPTOOLS: binding NONE/none_a/0 callback: 0x7f62853f9d70 data: 0x7f61b481c218
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru push(X-ResolveArray=dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [X-ResolveArray]=[dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru export(X-ResolveArray=dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_channel.c:1269 EXPORT (export_vars) [X-ResolveArray]=[dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(INFO X-ResolveArray=dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1670 X-ResolveArray=dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_copy_custom_headers=true)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_copy_custom_headers]=[true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_to_port=)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_to_port]=[UNDEF]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru set(sip_invite_domain=sipproxy.telphin.ru)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1477 sofia/internal/483*101@sipproxy.telphin.ru SET [sip_invite_domain]=[sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru info()
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [INFO] mod_dptools.c:1689 CHANNEL_DATA:
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Channel-State: [CS_EXECUTE]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Channel-Call-State: [RINGING]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Channel-State-Number: [4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Channel-Name: [sofia/internal/483*101@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Unique-ID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Call-Direction: [inbound]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Presence-Call-Direction: [inbound]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Channel-HIT-Dialplan: [true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Channel-Presence-ID: [483*101@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Channel-Call-UUID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Answer-State: [ringing]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Direction: [inbound]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Logical-Direction: [inbound]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Username: [483*101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Dialplan: [XML]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Caller-ID-Name: [101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Caller-ID-Number: [483*101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Orig-Caller-ID-Name: [101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Orig-Caller-ID-Number: [483*101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Network-Addr: [213.170.84.105]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-ANI: [483*101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Destination-Number: [89031883186]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Unique-ID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Source: [mod_sofia]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Context: [public]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Name: [sofia/internal/483*101@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Profile-Index: [1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Profile-Created-Time: [1468861776776394]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Created-Time: [1468861776776394]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Answered-Time: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Progress-Time: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Progress-Media-Time: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Hangup-Time: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Transfer-Time: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Resurrect-Time: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Bridged-Time: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Last-Hold: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Channel-Hold-Accum: [0]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Screen-Bit: [true]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Privacy-Hide-Name: [false]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Caller-Privacy-Hide-Number: [false]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_direction: [inbound]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_uuid: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_session_id: [527445]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_from_user: [483*101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_from_uri: [483*101@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_from_host: [sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_channel_name: [sofia/internal/483*101@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_call_id: [313436383836313736383137333538-szm2qy7cw9b5]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_ep_codec_string: [PCMU@8000h@20i@64000b,PCMA@8000h@20i@64000b]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_local_network_addr: [213.170.84.101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_network_ip: [213.170.84.105]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_network_port: [5060]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_received_ip: [213.170.84.105]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_via_protocol: [udp]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_from_user_stripped: [483*101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_from_tag: [qcs4gzhfbm]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sofia_profile_name: [internal]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_recovery_profile_name: [internal]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_invite_record_route: [<sip:89031883186@213.170.84.105;lr=on;ftag=qcs4gzhfbm;did=ce7.2093;nat=yes>]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_full_via: [SIP/2.0/UDP 213.170.84.105;branch=z9hG4bK128c.ddb49169c57340c936ae2d3371f4b342.0,SIP/2.0/UDP 192.77.1.39:57113;received=212.5.164.15;branch=z9hG4bK-16yyljhw5ql2;rport=57113]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_from_display: [101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_full_from: ["101" <sip:483*101@sipproxy.telphin.ru>;tag=qcs4gzhfbm]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_full_to: [<sip:89031883186@sipproxy.telphin.ru;user=phone>]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_req_params: [user=phone]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_req_user: [89031883186]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_req_uri: [89031883186@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_req_host: [sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_to_params: [user=phone]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_to_user: [89031883186]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_to_uri: [89031883186@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_to_host: [sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_contact_params: [line=b2dj73bg;alias=212.5.164.15~57113~1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_contact_user: [483*101]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_contact_port: [57113]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_contact_uri: [483*101@192.77.1.39:57113]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_contact_host: [192.77.1.39]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_rtp_use_codec_string: [OPUS,PCMA,PCMU,G722]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_user_agent: [snom715/8.7.5.35]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_via_host: [213.170.84.105]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_max_forwards: [69]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_presence_id: [483*101@sipproxy.telphin.ru]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_h_X-Inring: [pId=10494;srcId=10494;srcDid=1;srcCid=1236;srcExt=483*101@sipproxy.telphin.ru;srcType=phone]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_h_X-Serialnumber: [0004137543FB]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_h_P-Key-Flags: [resolution="31x13", keys="4"]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_switch_r_sdp: [v=0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 o=root 452279176 452279176 IN IP4 192.77.1.39
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 s=call
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 c=IN IP4 192.77.1.39
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 t=0 0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 m=audio 52530 RTP/AVP 0 8
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=rtpmap:0 PCMU/8000
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=rtpmap:8 PCMA/8000
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=ptime:20
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 ]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_endpoint_disposition: [DELAYED NEGOTIATION]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_X-need-bind: [True]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_nolocal:X-need-bind: [False]
variable_DP_MATCH: [ARRAY::89031883186|:89031883186]
variable_call_uuid: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
variable_sip_h_X-FromID: [10494]
variable_X-Next: [@]
variable_X-preloaded: [true]
variable_sip_rh_X-UUID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
variable_sip_ph_X-UUID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
variable_sip_received_port: [5060]
variable_sip_h_X-Call-UUID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
variable_sip_rh_X-Call-UUID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
variable_sip_ph_X-Call-UUID: [67cdaf36-4d0a-11e6-83d9-eb77bd4505f4]
variable_bridge_pre_execute_bleg_app: [execute_extension]
variable_bridge_pre_execute_bleg_data: [bind XML features]
variable_X-binded: [true]
variable_X-LegA: [true]
variable_nolocal:X-LegB: [true]
variable_transfer_ringback: [%(2000,4000,440,480)]
variable_ringback: [%(2000,4000,440,480)]
variable_X-ResolveArray: [dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381]
variable_export_vars: [nolocal:X-need-bind,nolocal:X-LegB,X-ResolveArray]
variable_sip_copy_custom_headers: [true]
variable_sip_invite_domain: [sipproxy.telphin.ru]
variable_current_application: [info]



67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru log(DEBUG sofia_profile_name=internal)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_dptools.c:1670 sofia_profile_name=internal
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 EXECUTE sofia/internal/483*101@sipproxy.telphin.ru bridge({absolute_codec_string=PCMA,PCMU}sofia/internal/89031883186@sipproxy.telphin.ru:5060)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_channel.c:1223 sofia/internal/483*101@sipproxy.telphin.ru EXPORTING[export_vars] [X-need-bind]=[False] to event
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_channel.c:1223 sofia/internal/483*101@sipproxy.telphin.ru EXPORTING[export_vars] [X-LegB]=[true] to event
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_channel.c:1223 sofia/internal/483*101@sipproxy.telphin.ru EXPORTING[export_vars] [X-ResolveArray]=[dial=89031883186@sipproxy.telphin.ru;fromid=10494;fromName=483*101@sipproxy.telphin.ru;fromCid=1236;fromDid=1;fromType=phone;resolving=89031883186;ts=1468861776816381] to event
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_originate.c:2124 Parsing global variables
2016-07-18 20:09:36.816381 [DEBUG] switch_event.c:1698 Parsing variable [absolute_codec_string]=[PCMA,PCMU]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [NOTICE] switch_channel.c:1077 New Channel sofia/internal/89031883186@sipproxy.telphin.ru:5060 [67d32966-4d0a-11e6-8410-eb77bd4505f4]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_sofia.c:4700 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State Change CS_NEW -> CS_INIT
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Running State Change CS_INIT
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State INIT
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_sofia.c:87 sofia/internal/89031883186@sipproxy.telphin.ru:5060 SOFIA INIT
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] sofia_glue.c:1241 sofia/internal/89031883186@sipproxy.telphin.ru:5060 sending invite version: 1.4.26  64bit
67d32966-4d0a-11e6-8410-eb77bd4505f4 Local SDP:
67d32966-4d0a-11e6-8410-eb77bd4505f4 v=0
67d32966-4d0a-11e6-8410-eb77bd4505f4 o=RINGME 1468835608 1468835609 IN IP4 213.170.84.101
67d32966-4d0a-11e6-8410-eb77bd4505f4 s=RINGME
67d32966-4d0a-11e6-8410-eb77bd4505f4 c=IN IP4 213.170.84.101
67d32966-4d0a-11e6-8410-eb77bd4505f4 t=0 0
67d32966-4d0a-11e6-8410-eb77bd4505f4 m=audio 26168 RTP/AVP 8 0 101 13
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=rtpmap:8 PCMA/8000
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=rtpmap:0 PCMU/8000
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=rtpmap:101 telephone-event/8000
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=fmtp:101 0-16
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=ptime:20
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=sendrecv
67d32966-4d0a-11e6-8410-eb77bd4505f4 
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:40 sofia/internal/89031883186@sipproxy.telphin.ru:5060 Standard INIT
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State Change CS_INIT -> CS_ROUTING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State INIT going to sleep
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Running State Change CS_ROUTING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] sofia.c:6658 Channel sofia/internal/89031883186@sipproxy.telphin.ru:5060 entering state [calling][0]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State ROUTING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] mod_sofia.c:123 sofia/internal/89031883186@sipproxy.telphin.ru:5060 SOFIA ROUTING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State ROUTING going to sleep
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Running State Change CS_CONSUME_MEDIA
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State CONSUME_MEDIA
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:36.816381 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State CONSUME_MEDIA going to sleep
66cdb3d8-4d0a-11e6-839e-eb77bd4505f4 2016-07-18 20:09:36.936380 [INFO] switch_rtp.c:5901 Auto Changing port from 10.215.148.237:10000 to 107.107.56.49:22204
420a2e3c-4d0a-11e6-80bc-eb77bd4505f4 2016-07-18 20:09:38.096411 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:38.100164] SSRC[601823969]RTT[0.030228] A[2345802148] - DLSR[185453] - LSR[2345614714]
07446efc-4d0a-11e6-b938-eb77bd4505f4 2016-07-18 20:09:40.336381 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:40.355469] SSRC[462318916]RTT[0.041336] A[2345949952] - DLSR[289580] - LSR[2345657663]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:40.776419 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:40.776419 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:40.776419 [DEBUG] sofia.c:6658 Channel sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 entering state [proceeding][180]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:43.096391 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:43.096391 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:43.096391 [DEBUG] sofia.c:6658 Channel sofia/internal/89031883186@sipproxy.telphin.ru:5060 entering state [proceeding][180]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:43.096391 [NOTICE] sofia.c:6760 Ring-Ready sofia/internal/89031883186@sipproxy.telphin.ru:5060!
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:43.096391 [DEBUG] switch_channel.c:3299 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Callstate Change DOWN -> RINGING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [INFO] switch_ivr_originate.c:1215 Sending early media
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1228 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1228 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1228 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1228 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1228 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1233 Found suite AES_CM_128_HMAC_SHA1_80
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1299 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:ZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:965 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:oxTSAIX0VXc3+C3qvMv1UhZp9PBcfLjvVHFRHAH7]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:2506 Set Codec sofia/internal/483*101@sipproxy.telphin.ru PCMU/8000 20 ms 160 samples 64000 bits 1 channels
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_codec.c:111 sofia/internal/483*101@sipproxy.telphin.ru Original read codec set to PCMU:0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:3955 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:5178 AUDIO RTP [sofia/internal/483*101@sipproxy.telphin.ru] 213.170.84.101 port 20380 -> 192.77.1.39 port 52530 codec: 0 ms: 20
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_rtp.c:3608 Starting timer [soft] 160 bytes per 20ms
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:1747 Setting Jitterbuffer to 305ms (15 frames) (75 max frames) (0 max drift)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_media.c:5504 sofia/internal/483*101@sipproxy.telphin.ru Set rtp dtmf delay to 40
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [INFO] switch_rtp.c:3423 Activating Audio Secure RTP SEND
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [INFO] switch_rtp.c:3401 Activating Audio Secure RTP RECV
2016-07-18 20:09:43.116443 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/483*101@sipproxy.telphin.ru!
2016-07-18 20:09:43.116443 [DEBUG] switch_core_sqldb.c:2599 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_channel.c:3421 (sofia/internal/483*101@sipproxy.telphin.ru) Callstate Change RINGING -> EARLY
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] mod_sofia.c:2264 Ring SDP:
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 v=0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 o=RINGME 1468841403 1468841404 IN IP4 213.170.84.101
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 s=RINGME
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 c=IN IP4 213.170.84.101
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 t=0 0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 m=audio 20380 RTP/SAVP 0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=rtpmap:0 PCMU/8000
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=ptime:20
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=sendrecv
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:oxTSAIX0VXc3+C3qvMv1UhZp9PBcfLjvVHFRHAH7
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_session.c:913 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_ivr_originate.c:1272 Raw Codec Activation Success L16@8000hz 1 channel 20ms
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_core_codec.c:221 sofia/internal/483*101@sipproxy.telphin.ru Push codec L16:100
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.116443 [DEBUG] switch_ivr_originate.c:1341 Play Ringback Tone [%(2000,4000,440,480)]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:43.136397 [DEBUG] sofia.c:6658 Channel sofia/internal/483*101@sipproxy.telphin.ru entering state [early][183]
420a2e3c-4d0a-11e6-80bc-eb77bd4505f4 2016-07-18 20:09:43.336406 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:43.340191] SSRC[601823969]RTT[0.030716] A[2346145558] - DLSR[201151] - LSR[2345942394]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] sofia.c:6658 Channel sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 entering state [completing][200]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] sofia.c:6668 Remote SDP:
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 v=0
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 o=- 1468861781 1468861781 IN IP4 109.107.176.42
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 s=-
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 c=IN IP4 109.107.176.42
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 t=0 0
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 m=audio 15198 RTP/AVP 8 0 101
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 a=rtpmap:8 PCMA/8000
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 a=rtpmap:0 PCMU/8000
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 a=rtpmap:101 telephone-event/8000
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 a=fmtp:101 0-15
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 a=ptime:20
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 a=silenceSupp:off - - - -
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] sofia.c:6658 Channel sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 entering state [ready][200]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_media.c:2506 Set Codec sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:44.596426 [DEBUG] switch_core_codec.c:111 sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 Original read codec set to PCMA:8
--
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] sofia.c:6658 Channel sofia/internal/89031883186@sipproxy.telphin.ru:5060 entering state [completing][200]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] sofia.c:6668 Remote SDP:
67d32966-4d0a-11e6-8410-eb77bd4505f4 v=0
67d32966-4d0a-11e6-8410-eb77bd4505f4 o=AudiocodesGW 1064695897 1064695870 IN IP4 31.200.203.137
67d32966-4d0a-11e6-8410-eb77bd4505f4 s=Phone-Call
67d32966-4d0a-11e6-8410-eb77bd4505f4 c=IN IP4 31.200.203.137
67d32966-4d0a-11e6-8410-eb77bd4505f4 t=0 0
67d32966-4d0a-11e6-8410-eb77bd4505f4 m=audio 9070 RTP/AVP 8 13 101
67d32966-4d0a-11e6-8410-eb77bd4505f4 c=IN IP4 31.200.203.137
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=rtpmap:8 PCMA/8000
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=rtpmap:101 telephone-event/8000
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=fmtp:101 0-15
67d32966-4d0a-11e6-8410-eb77bd4505f4 a=ptime:20
67d32966-4d0a-11e6-8410-eb77bd4505f4 
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] sofia.c:6658 Channel sofia/internal/89031883186@sipproxy.telphin.ru:5060 entering state [ready][200]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_media.c:2506 Set Codec sofia/internal/89031883186@sipproxy.telphin.ru:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.476399 [DEBUG] switch_core_codec.c:111 sofia/internal/89031883186@sipproxy.telphin.ru:5060 Original read codec set to PCMA:8
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:3933 Set 2833 dtmf send payload to 101
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:5178 AUDIO RTP [sofia/internal/89031883186@sipproxy.telphin.ru:5060] 213.170.84.101 port 26168 -> 31.200.203.137 port 9070 codec: 8 ms: 20
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_rtp.c:3608 Starting timer [soft] 160 bytes per 20ms
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:1747 Setting Jitterbuffer to 305ms (15 frames) (75 max frames) (0 max drift)
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:5476 Set 2833 dtmf send payload to 101
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:5482 Set 2833 dtmf receive payload to 101
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:5504 sofia/internal/89031883186@sipproxy.telphin.ru:5060 Set rtp dtmf delay to 40
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:5510 Set comfort noise payload to 13
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_channel.c:3659 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [NOTICE] sofia.c:7525 Channel [sofia/internal/89031883186@sipproxy.telphin.ru:5060] has been answered
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_codec.c:246 sofia/internal/483*101@sipproxy.telphin.ru Restore previous codec PCMU:0.
2016-07-18 20:09:46.496391 [INFO] switch_cpp.cpp:1358 direction -> outbound
2016-07-18 20:09:46.496391 [INFO] switch_cpp.cpp:1358 Other-Leg-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4

2016-07-18 20:09:46.496391 [INFO] switch_cpp.cpp:1358 leg2i srcId=10494;srcDid=1;srcCid=1236;srcExt=483*101@sipproxy.telphin.ru;srcType=phone

2016-07-18 20:09:46.496391 [DEBUG] switch_cpp.cpp:1358 sets 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 Other-Leg-X-Leg2i srcId=10494;srcDid=1;srcCid=1236;srcExt=483*101@sipproxy.telphin.ru;srcType=phone

2016-07-18 20:09:46.496391 [DEBUG] switch_cpp.cpp:1358 result +OK


67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] mod_sofia.c:780 Local SDP sofia/internal/483*101@sipproxy.telphin.ru:
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 v=0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 o=RINGME 1468841403 1468841405 IN IP4 213.170.84.101
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 s=RINGME
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 c=IN IP4 213.170.84.101
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 t=0 0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 m=audio 20380 RTP/SAVP 0
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=rtpmap:0 PCMU/8000
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=ptime:20
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=sendrecv
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:oxTSAIX0VXc3+C3qvMv1UhZp9PBcfLjvVHFRHAH7
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_session.c:913 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [NOTICE] switch_ivr_originate.c:3546 Channel [sofia/internal/483*101@sipproxy.telphin.ru] has been answered
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
2016-07-18 20:09:46.496391 [DEBUG] switch_cpp.cpp:1358 init record-check script, 'Event-Name: CHANNEL_ANSWER
Core-UUID: 253cff26-478a-11e6-9300-eb77bd4505f4
FreeSWITCH-Hostname: ringme_morskaya
FreeSWITCH-Switchname: ringme_morskaya
FreeSWITCH-IPv4: 213.170.84.101
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2016-07-18%2020%3A09%3A46
Event-Date-GMT: Mon,%2018%20Jul%202016%2017%3A09%3A46%20GMT
Event-Date-Timestamp: 1468861786496391
Event-Calling-File: switch_channel.c
Event-Calling-Function: switch_channel_perform_mark_answered
Event-Calling-Line-Number: 3649
Event-Sequence: 26421755
Channel-State: CS_CONSUME_MEDIA
Channel-Call-State: RINGING
Channel-State-Number: 7
Channel-Name: sofia/internal/89031883186%40sipproxy.telphin.ru%3A5060
Unique-ID: 67d32966-4d0a-11e6-8410-eb77bd4505f4
Call-Direction: outbound
Presence-Call-Direction: outbound
Channel-HIT-Dialplan: false
Channel-Call-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
Answer-State: answered
Channel-Read-Codec-Name: PCMA
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMA
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: outbound
Caller-Logical-Direction: outbound
Caller-Username: 483*101
Caller-Dialplan: XML
Caller-Caller-ID-Name: 101
Caller-Caller-ID-Number: 483*101
Caller-Orig-Caller-ID-Name: 101
Caller-Orig-Caller-ID-Number: 483*101
Caller-Callee-ID-Name: Outbound%20Call
Caller-Callee-ID-Number: 89031883186
Caller-Network-Addr: 213.170.84.100
Caller-ANI: 483*101
Caller-Destination-Number: 89031883186
Caller-Unique-ID: 67d32966-4d0a-11e6-8410-eb77bd4505f4
Caller-Source: mod_sofia
Caller-Context: public
Caller-Channel-Name: sofia/internal/89031883186%40sipproxy.telphin.ru%3A5060
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1468861776816381
Caller-Channel-Created-Time: 1468861776816381
Caller-Channel-Answered-Time: 1468861786496391
Caller-Channel-Progress-Time: 1468861783096391
Caller-Channel-Progress-Media-Time: 0
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
Other-Type: originator
Other-Leg-Direction: inbound
Other-Leg-Logical-Direction: inbound
Other-Leg-Username: 483*101
Other-Leg-Dialplan: XML
Other-Leg-Caller-ID-Name: 101
Other-Leg-Caller-ID-Number: 483*101
Other-Leg-Orig-Caller-ID-Name: 101
Other-Leg-Orig-Caller-ID-Number: 483*101
Other-Leg-Network-Addr: 213.170.84.105
Other-Leg-ANI: 483*101
Other-Leg-Destination-Number: 89031883186
Other-Leg-Unique-ID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
Other-Leg-Source: mod_sofia
Other-Leg-Context: public
Other-Leg-Channel-Name: sofia/internal/483*101%40sipproxy.telphin.ru
Other-Leg-Profile-Created-Time: 0
Other-Leg-Channel-Created-Time: 0
Other-Leg-Channel-Answered-Time: 0
Other-Leg-Channel-Progress-Time: 1468861783096391
Other-Leg-Channel-Progress-Media-Time: 0
Other-Leg-Channel-Hangup-Time: 0
Other-Leg-Channel-Transfer-Time: 0
Other-Leg-Channel-Resurrect-Time: 0
Other-Leg-Channel-Bridged-Time: 0
Other-Leg-Channel-Last-Hold: 0
Other-Leg-Channel-Hold-Accum: 0
Other-Leg-Screen-Bit: true
Other-Leg-Privacy-Hide-Name: false
Other-Leg-Privacy-Hide-Number: false
variable_direction: outbound
variable_is_outbound: true
variable_uuid: 67d32966-4d0a-11e6-8410-eb77bd4505f4
variable_session_id: 527446
variable_sip_profile_name: internal
variable_channel_name: sofia/internal/89031883186%40sipproxy.telphin.ru%3A5060
variable_sip_destination_url: sip%3A89031883186%40sipproxy.telphin.ru%3A5060
variable_sip_h_X-Inring: pId%3D10494%3BsrcId%3D10494%3BsrcDid%3D1%3BsrcCid%3D1236%3BsrcExt%3D483*101%40sipproxy.telphin.ru%3BsrcType%3Dphone
variable_sip_h_X-Serialnumber: 0004137543FB
variable_sip_h_P-Key-Flags: resolution%3D%2231x13%22,%20keys%3D%224%22
variable_sip_h_X-FromID: 10494
variable_sip_h_X-Call-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_max_forwards: 68
variable_originator_codec: PCMU%408000h%4020i%4064000b,PCMA%408000h%4020i%4064000b
variable_originator: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_signal_bond: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_switch_m_sdp: v%3D0%0D%0Ao%3Droot%20452279176%20452279176%20IN%20IP4%20192.77.1.39%0D%0As%3Dcall%0D%0Ac%3DIN%20IP4%20192.77.1.39%0D%0At%3D0%200%0D%0Am%3Daudio%2052530%20RTP/AVP%200%208%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Dcrypto%3A1%20AES_CM_128_HMAC_SHA1_80%20inline%3AZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL%0D%0Aa%3Dptime%3A20%0D%0A
variable_call_uuid: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_export_vars: nolocal%3AX-need-bind,nolocal%3AX-LegB,X-ResolveArray
variable_X-need-bind: False
variable_X-LegB: true
variable_X-ResolveArray: dial%3D89031883186%40sipproxy.telphin.ru%3Bfromid%3D10494%3BfromName%3D483*101%40sipproxy.telphin.ru%3BfromCid%3D1236%3BfromDid%3D1%3BfromType%3Dphone%3Bresolving%3D89031883186%3Bts%3D1468861776816381
variable_absolute_codec_string: PCMA,PCMU
variable_originate_early_media: true
variable_originating_leg_uuid: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_rtp_local_sdp_str: v%3D0%0Ao%3DRINGME%201468835608%201468835609%20IN%20IP4%20213.170.84.101%0As%3DRINGME%0Ac%3DIN%20IP4%20213.170.84.101%0At%3D0%200%0Am%3Daudio%2026168%20RTP/AVP%208%200%20101%2013%0Aa%3Drtpmap%3A8%20PCMA/8000%0Aa%3Drtpmap%3A0%20PCMU/8000%0Aa%3Drtpmap%3A101%20telephone-event/8000%0Aa%3Dfmtp%3A101%200-16%0Aa%3Dptime%3A20%0Aa%3Dsendrecv%0A
variable_sip_outgoing_contact_uri: %3Csip%3Amod_sofia%40213.170.84.101%3A5080%3E
variable_sip_req_uri: 89031883186%40sipproxy.telphin.ru%3A5060
variable_sofia_profile_name: internal
variable_recovery_profile_name: internal
variable_sip_ph_X-Leg2i: srcId%3D10494%3BsrcDid%3D1%3BsrcCid%3D1236%3BsrcExt%3D483*101%40sipproxy.telphin.ru%3BsrcType%3Dphone
variable_sip_local_network_addr: 213.170.84.101
variable_sip_reply_host: 213.170.84.100
variable_sip_reply_port: 5060
variable_sip_network_ip: 213.170.84.100
variable_sip_network_port: 5060
variable_ep_codec_string: PCMA%408000h%4020i%4064000b
variable_sip_rh_X-Leg2i: srcId%3D10494%3BsrcDid%3D1%3BsrcCid%3D1236%3BsrcExt%3D483*101%40sipproxy.telphin.ru%3BsrcType%3Dphone
variable_sip_user_agent: Mediant%201000/v.6.60A.260.002
variable_sip_recover_contact: %3Csip%3A1089%4031.200.203.137%3A5060%3E
variable_sip_invite_record_route: %3Csip%3A89031883186%40213.170.84.100%3Blr%3Don%3Bftag%3D57ZS3ymar7SyD%3Bdid%3D9a4.af62%3E,%3Csip%3A89031883186%40213.170.92.166%3Blr%3Don%3Bftag%3D57ZS3ymar7SyD%3Bldi%3D9a42.ae36%3E,%3Csip%3A79031883186%40213.170.92.166%3A5062%3Blr%3Don%3Bftag%3D57ZS3ymar7SyD%3E,%3Csip%3A213.170.100.150%3Blr%3Bftag%3D57ZS3ymar7SyD%3Bdid%3D9a4.59073695%3E
variable_sip_full_via: SIP/2.0/UDP%20213.170.84.101%3A5080%3Brport%3Bbranch%3Dz9hG4bKjyjmpaHarrepc
variable_sip_recover_via: SIP/2.0/UDP%20213.170.84.101%3A5080%3Brport%3Bbranch%3Dz9hG4bKjyjmpaHarrepc
variable_sip_from_display: 101
variable_sip_full_from: %22101%22%20%3Csip%3A483*101%40213.170.84.101%3E%3Btag%3D57ZS3ymar7SyD
variable_sip_full_to: %3Csip%3A89031883186%40sipproxy.telphin.ru%3E%3Btag%3D1c1059679600
variable_sip_from_user: 483*101
variable_sip_from_uri: 483*101%40213.170.84.101
variable_sip_from_host: 213.170.84.101
variable_sip_to_user: 89031883186
variable_sip_to_uri: 89031883186%40sipproxy.telphin.ru
variable_sip_to_host: sipproxy.telphin.ru
variable_sip_contact_user: 1089
variable_sip_contact_port: 5060
variable_sip_contact_uri: 1089%4031.200.203.137%3A5060
--
variable_sip_call_id: 67d32966-4d0a-11e6-8410-eb77bd4505f4
variable_switch_r_sdp: v%3D0%0D%0Ao%3DAudiocodesGW%201064695897%201064695870%20IN%20IP4%2031.200.203.137%0D%0As%3DPhone-Call%0D%0Ac%3DIN%20IP4%2031.200.203.137%0D%0At%3D0%200%0D%0Am%3Daudio%209070%20RTP/AVP%208%2013%20101%0D%0Ac%3DIN%20IP4%2031.200.203.137%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0Aa%3Dptime%3A20%0D%0A
variable_rtp_use_codec_string: PCMA,PCMU
variable_rtp_audio_recv_pt: 8
variable_rtp_use_codec_name: PCMA
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
variable_read_codec: PCMA
variable_original_read_codec: PCMA
variable_read_rate: 8000
variable_original_read_rate: 8000
variable_write_codec: PCMA
variable_write_rate: 8000
variable_dtmf_type: rfc2833
variable_local_media_ip: 213.170.84.101
variable_local_media_port: 26168
variable_advertised_media_ip: 213.170.84.101
variable_rtp_use_timer_name: soft
variable_rtp_use_pt: 8
variable_rtp_use_ssrc: 195096992
variable_rtp_2833_send_payload: 101
variable_rtp_2833_recv_payload: 101
variable_remote_media_ip: 31.200.203.137
variable_remote_media_port: 9070

'67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_channel.c:3713 (sofia/internal/483*101@sipproxy.telphin.ru) Callstate Change EARLY -> ACTIVE
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] sofia.c:6658 Channel sofia/internal/483*101@sipproxy.telphin.ru entering state [completed][200]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_ivr_originate.c:3604 Originate Resulted in Success: [sofia/internal/89031883186@sipproxy.telphin.ru:5060]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:7929 sofia/internal/89031883186@sipproxy.telphin.ru:5060 PAUSE Jitterbuffer
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_session.c:913 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_media.c:7929 sofia/internal/483*101@sipproxy.telphin.ru PAUSE Jitterbuffer
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_session.c:913 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_ivr_bridge.c:1473 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Running State Change CS_EXCHANGE_MEDIA
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] switch_core_state_machine.c:538 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State EXCHANGE_MEDIA
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.496391 [DEBUG] mod_sofia.c:594 SOFIA EXCHANGE_MEDIA
2016-07-18 20:09:46.516385 [INFO] switch_cpp.cpp:1358 direction -> inbound
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.536401 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.536401 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.536401 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.536401 [DEBUG] switch_rtp.c:5928 Correct ip/port confirmed.
2016-07-18 20:09:46.536401 [INFO] switch_cpp.cpp:1358 hook/event-CHANNEL_ANSWER-for-record_start.lua starting
2016-07-18 20:09:46.536401 [DEBUG] switch_cpp.cpp:1358 init record-check script, 'Event-Name: CHANNEL_ANSWER
Core-UUID: 253cff26-478a-11e6-9300-eb77bd4505f4
FreeSWITCH-Hostname: ringme_morskaya
FreeSWITCH-Switchname: ringme_morskaya
FreeSWITCH-IPv4: 213.170.84.101
FreeSWITCH-IPv6: %3A%3A1
Event-Date-Local: 2016-07-18%2020%3A09%3A46
Event-Date-GMT: Mon,%2018%20Jul%202016%2017%3A09%3A46%20GMT
Event-Date-Timestamp: 1468861786496391
Event-Calling-File: switch_channel.c
Event-Calling-Function: switch_channel_perform_mark_answered
Event-Calling-Line-Number: 3649
Event-Sequence: 26421759
Channel-State: CS_EXECUTE
Channel-Call-State: EARLY
Channel-State-Number: 4
Channel-Name: sofia/internal/483*101%40sipproxy.telphin.ru
Unique-ID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
Call-Direction: inbound
Presence-Call-Direction: inbound
Channel-HIT-Dialplan: true
Channel-Presence-ID: 483*101%40sipproxy.telphin.ru
Channel-Call-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
Answer-State: answered
Channel-Read-Codec-Name: PCMU
Channel-Read-Codec-Rate: 8000
Channel-Read-Codec-Bit-Rate: 64000
Channel-Write-Codec-Name: PCMU
Channel-Write-Codec-Rate: 8000
Channel-Write-Codec-Bit-Rate: 64000
Caller-Direction: inbound
Caller-Logical-Direction: inbound
Caller-Username: 483*101
Caller-Dialplan: XML
Caller-Caller-ID-Name: 101
Caller-Caller-ID-Number: 483*101
Caller-Orig-Caller-ID-Name: 101
Caller-Orig-Caller-ID-Number: 483*101
Caller-Callee-ID-Name: Outbound%20Call
Caller-Callee-ID-Number: 89031883186
Caller-Network-Addr: 213.170.84.105
Caller-ANI: 483*101
Caller-Destination-Number: 89031883186
Caller-Unique-ID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
Caller-Source: mod_sofia
Caller-Context: public
Caller-Channel-Name: sofia/internal/483*101%40sipproxy.telphin.ru
Caller-Profile-Index: 1
Caller-Profile-Created-Time: 1468861776776394
Caller-Channel-Created-Time: 1468861776776394
Caller-Channel-Answered-Time: 1468861786496391
Caller-Channel-Progress-Time: 1468861783096391
Caller-Channel-Progress-Media-Time: 1468861783116443
Caller-Channel-Hangup-Time: 0
Caller-Channel-Transfer-Time: 0
Caller-Channel-Resurrect-Time: 0
Caller-Channel-Bridged-Time: 0
Caller-Channel-Last-Hold: 0
Caller-Channel-Hold-Accum: 0
Caller-Screen-Bit: true
Caller-Privacy-Hide-Name: false
Caller-Privacy-Hide-Number: false
variable_direction: inbound
variable_uuid: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_session_id: 527445
variable_sip_from_user: 483*101
variable_sip_from_uri: 483*101%40sipproxy.telphin.ru
variable_sip_from_host: sipproxy.telphin.ru
variable_channel_name: sofia/internal/483*101%40sipproxy.telphin.ru
variable_sip_call_id: 313436383836313736383137333538-szm2qy7cw9b5
variable_ep_codec_string: PCMU%408000h%4020i%4064000b,PCMA%408000h%4020i%4064000b
variable_sip_local_network_addr: 213.170.84.101
variable_sip_network_ip: 213.170.84.105
variable_sip_network_port: 5060
variable_sip_received_ip: 213.170.84.105
variable_sip_via_protocol: udp
variable_sip_from_user_stripped: 483*101
variable_sip_from_tag: qcs4gzhfbm
variable_sofia_profile_name: internal
variable_recovery_profile_name: internal
variable_sip_invite_record_route: %3Csip%3A89031883186%40213.170.84.105%3Blr%3Don%3Bftag%3Dqcs4gzhfbm%3Bdid%3Dce7.2093%3Bnat%3Dyes%3E
variable_sip_full_via: SIP/2.0/UDP%20213.170.84.105%3Bbranch%3Dz9hG4bK128c.ddb49169c57340c936ae2d3371f4b342.0,SIP/2.0/UDP%20192.77.1.39%3A57113%3Breceived%3D212.5.164.15%3Bbranch%3Dz9hG4bK-16yyljhw5ql2%3Brport%3D57113
variable_sip_from_display: 101
variable_sip_full_from: %22101%22%20%3Csip%3A483*101%40sipproxy.telphin.ru%3E%3Btag%3Dqcs4gzhfbm
variable_sip_full_to: %3Csip%3A89031883186%40sipproxy.telphin.ru%3Buser%3Dphone%3E
variable_sip_req_params: user%3Dphone
variable_sip_req_user: 89031883186
variable_sip_req_uri: 89031883186%40sipproxy.telphin.ru
variable_sip_req_host: sipproxy.telphin.ru
variable_sip_to_params: user%3Dphone
variable_sip_to_user: 89031883186
variable_sip_to_uri: 89031883186%40sipproxy.telphin.ru
variable_sip_to_host: sipproxy.telphin.ru
variable_sip_contact_params: line%3Db2dj73bg%3Balias%3D212.5.164.15~57113~1
--
variable_call_uuid: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_sip_h_X-FromID: 10494
variable_X-Next: %40
variable_X-preloaded: true
variable_sip_rh_X-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_sip_ph_X-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_sip_received_port: 5060
variable_sip_h_X-Call-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_sip_rh_X-Call-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_sip_ph_X-Call-UUID: 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4
variable_bridge_pre_execute_bleg_app: execute_extension
variable_bridge_pre_execute_bleg_data: bind%20XML%20features
variable_X-binded: true
variable_X-LegA: true
variable_nolocal:X-LegB: true
variable_transfer_ringback: %25(2000,4000,440,480)
variable_ringback: %25(2000,4000,440,480)
variable_X-ResolveArray: dial%3D89031883186%40sipproxy.telphin.ru%3Bfromid%3D10494%3BfromName%3D483*101%40sipproxy.telphin.ru%3BfromCid%3D1236%3BfromDid%3D1%3BfromType%3Dphone%3Bresolving%3D89031883186%3Bts%3D1468861776816381
variable_export_vars: nolocal%3AX-need-bind,nolocal%3AX-LegB,X-ResolveArray
variable_sip_copy_custom_headers: true
variable_sip_invite_domain: sipproxy.telphin.ru
variable_current_application_data: %7Babsolute_codec_string%3D'PCMA,PCMU'%7Dsofia/internal/89031883186%40sipproxy.telphin.ru%3A5060
variable_current_application: bridge
variable_originate_disposition: failure
variable_DIALSTATUS: INVALIDARGS
variable_originate_signal_bond: 67d32966-4d0a-11e6-8410-eb77bd4505f4
variable_originated_legs: 67d32966-4d0a-11e6-8410-eb77bd4505f4%3BOutbound%20Call%3B89031883186
variable_sip_ph_X-Leg2i: srcId%3D10494%3BsrcDid%3D1%3BsrcCid%3D1236%3BsrcExt%3D483*101%40sipproxy.telphin.ru%3BsrcType%3Dphone
variable_rtp_use_codec_string: OPUS,PCMA,PCMU,G722
variable_srtp_remote_audio_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3AZOiSsBfBtnDJYvnzQY5uL6T2kExF0jO8dPZcIHiL
variable_srtp_remote_audio_crypto_type: AES_CM_128_HMAC_SHA1_80
variable_rtp_has_crypto: AES_CM_128_HMAC_SHA1_80
variable_rtp_last_audio_local_crypto_key: 1%20AES_CM_128_HMAC_SHA1_80%20inline%3AoxTSAIX0VXc3%2BC3qvMv1UhZp9PBcfLjvVHFRHAH7
variable_rtp_audio_recv_pt: 0
variable_rtp_use_codec_name: PCMU
variable_rtp_use_codec_rate: 8000
variable_rtp_use_codec_ptime: 20
variable_rtp_use_codec_channels: 1
variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
variable_original_read_codec: PCMU
variable_original_read_rate: 8000
variable_write_codec: PCMU
variable_write_rate: 8000
variable_dtmf_type: info
variable_local_media_ip: 213.170.84.101
variable_local_media_port: 20380
variable_advertised_media_ip: 213.170.84.101
variable_rtp_use_timer_name: soft
variable_rtp_use_pt: 0
variable_rtp_use_ssrc: 3490658896
variable_send_silence_when_idle: -1
variable_rtp_secure_audio_confirmed: true
variable_rtp_secure_media_negotiated: AES_CM_128_HMAC_SHA1_80
variable_remote_media_ip: 192.77.1.39
variable_remote_media_port: 52530
variable_endpoint_disposition: EARLY%20MEDIA
variable_sip_rh_X-Leg2i: srcId%3D10494%3BsrcDid%3D1%3BsrcCid%3D1236%3BsrcExt%3D483*101%40sipproxy.telphin.ru%3BsrcType%3Dphone
--
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.536401 [DEBUG] sofia.c:6658 Channel sofia/internal/483*101@sipproxy.telphin.ru entering state [ready][200]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.536401 [DEBUG] switch_core_session.c:979 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.536401 [DEBUG] switch_core_session.c:979 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.556382 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.576381 [NOTICE] sofia.c:952 Hangup sofia/internal/483*101@sipproxy.telphin.ru [CS_EXECUTE] [NORMAL_CLEARING]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.576381 [DEBUG] switch_channel.c:3244 Send signal sofia/internal/483*101@sipproxy.telphin.ru [KILL]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.576381 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.576381 [DEBUG] switch_ivr_bridge.c:665 BRIDGE THREAD DONE [sofia/internal/483*101@sipproxy.telphin.ru]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.576381 [DEBUG] switch_ivr_bridge.c:695 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_ivr_bridge.c:584 sofia/internal/483*101@sipproxy.telphin.ru ending bridge by request from write function
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_ivr_bridge.c:665 BRIDGE THREAD DONE [sofia/internal/89031883186@sipproxy.telphin.ru:5060]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_ivr_bridge.c:695 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [NOTICE] switch_ivr_bridge.c:763 Hangup sofia/internal/89031883186@sipproxy.telphin.ru:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_channel.c:3244 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [KILL]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:538 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State EXCHANGE_MEDIA going to sleep
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Running State Change CS_HANGUP
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Callstate Change ACTIVE -> HANGUP
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State HANGUP
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] mod_sofia.c:407 sofia/internal/89031883186@sipproxy.telphin.ru:5060 Overriding SIP cause 480 with 200 from the other leg
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] mod_sofia.c:413 Channel sofia/internal/89031883186@sipproxy.telphin.ru:5060 hanging up, cause: NORMAL_CLEARING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_ivr_bridge.c:1571 sofia/internal/89031883186@sipproxy.telphin.ru:5060 skip receive message [UNBRIDGE] (channel is hungup already)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_ivr_bridge.c:1574 sofia/internal/483*101@sipproxy.telphin.ru skip receive message [UNBRIDGE] (channel is hungup already)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:2767 sofia/internal/483*101@sipproxy.telphin.ru skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/483*101@sipproxy.telphin.ru) State EXECUTE going to sleep
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/483*101@sipproxy.telphin.ru) Running State Change CS_HANGUP
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] mod_sofia.c:465 Sending BYE to sofia/internal/89031883186@sipproxy.telphin.ru:5060
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/483*101@sipproxy.telphin.ru) Callstate Change ACTIVE -> HANGUP
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:60 sofia/internal/89031883186@sipproxy.telphin.ru:5060 Standard HANGUP, cause: NORMAL_CLEARING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State HANGUP going to sleep
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State Change CS_HANGUP -> CS_REPORTING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Running State Change CS_REPORTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/483*101@sipproxy.telphin.ru) State HANGUP
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] mod_sofia.c:413 Channel sofia/internal/483*101@sipproxy.telphin.ru hanging up, cause: NORMAL_CLEARING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State REPORTING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:104 sofia/internal/89031883186@sipproxy.telphin.ru:5060 Standard REPORTING, cause: NORMAL_CLEARING
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State REPORTING going to sleep
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:498 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State Change CS_REPORTING -> CS_DESTROY
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/89031883186@sipproxy.telphin.ru:5060 [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:1624 Session 527446 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Locked, Waiting on external entities
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [NOTICE] switch_core_session.c:1642 Session 527446 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Ended
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [NOTICE] switch_core_session.c:1646 Close Channel sofia/internal/89031883186@sipproxy.telphin.ru:5060 [CS_DESTROY]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:60 sofia/internal/483*101@sipproxy.telphin.ru Standard HANGUP, cause: NORMAL_CLEARING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/483*101@sipproxy.telphin.ru) State HANGUP going to sleep
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:626 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) Running State Change CS_DESTROY
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/483*101@sipproxy.telphin.ru) State Change CS_HANGUP -> CS_REPORTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State DESTROY
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] mod_sofia.c:323 sofia/internal/89031883186@sipproxy.telphin.ru:5060 SOFIA DESTROY
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:111 sofia/internal/89031883186@sipproxy.telphin.ru:5060 Standard DESTROY
67d32966-4d0a-11e6-8410-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/89031883186@sipproxy.telphin.ru:5060) State DESTROY going to sleep
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/483*101@sipproxy.telphin.ru) Running State Change CS_REPORTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/483*101@sipproxy.telphin.ru) State REPORTING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:104 sofia/internal/483*101@sipproxy.telphin.ru Standard REPORTING, cause: NORMAL_CLEARING
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:823 (sofia/internal/483*101@sipproxy.telphin.ru) State REPORTING going to sleep
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:498 (sofia/internal/483*101@sipproxy.telphin.ru) State Change CS_REPORTING -> CS_DESTROY
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/483*101@sipproxy.telphin.ru [BREAK]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_session.c:1624 Session 527445 (sofia/internal/483*101@sipproxy.telphin.ru) Locked, Waiting on external entities
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [NOTICE] switch_core_session.c:1642 Session 527445 (sofia/internal/483*101@sipproxy.telphin.ru) Ended
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [NOTICE] switch_core_session.c:1646 Close Channel sofia/internal/483*101@sipproxy.telphin.ru [CS_DESTROY]
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:626 (sofia/internal/483*101@sipproxy.telphin.ru) Running State Change CS_DESTROY
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/483*101@sipproxy.telphin.ru) State DESTROY
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] mod_sofia.c:323 sofia/internal/483*101@sipproxy.telphin.ru SOFIA DESTROY
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:111 sofia/internal/483*101@sipproxy.telphin.ru Standard DESTROY
67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 2016-07-18 20:09:46.596389 [DEBUG] switch_core_state_machine.c:636 (sofia/internal/483*101@sipproxy.telphin.ru) State DESTROY going to sleep

420a2e3c-4d0a-11e6-80bc-eb77bd4505f4 2016-07-18 20:09:48.576405 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:48.580153] SSRC[601823969]RTT[0.031128] A[2346488964] - DLSR[216850] - LSR[2346270074]
07446efc-4d0a-11e6-b938-eb77bd4505f4 2016-07-18 20:09:49.736412 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:49.755506] SSRC[462318916]RTT[0.034409] A[2346565992] - DLSR[250712] - LSR[2346313025]
66cdb3d8-4d0a-11e6-839e-eb77bd4505f4 2016-07-18 20:09:50.816386 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/144*102@sipproxy.telphin.ru:5060 [BREAK]
66cdb3d8-4d0a-11e6-839e-eb77bd4505f4 2016-07-18 20:09:50.816386 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/144*102@sipproxy.telphin.ru:5060 [BREAK]
66cdb3d8-4d0a-11e6-839e-eb77bd4505f4 2016-07-18 20:09:50.816386 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/144*102@sipproxy.telphin.ru:5060 [BREAK]
66cdb3d8-4d0a-11e6-839e-eb77bd4505f4 2016-07-18 20:09:50.836413 [DEBUG] sofia.c:6658 Channel sofia/internal/144*102@sipproxy.telphin.ru:5060 entering state [ready][200]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:09:50.836413 [DEBUG] switch_core_session.c:979 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66cdb3d8-4d0a-11e6-839e-eb77bd4505f4 2016-07-18 20:09:50.836413 [DEBUG] switch_core_session.c:979 Send signal sofia/internal/144*102@sipproxy.telphin.ru:5060 [BREAK]
420a2e3c-4d0a-11e6-80bc-eb77bd4505f4 2016-07-18 20:09:53.816434 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:53.820191] SSRC[601823969]RTT[0.030106] A[2346832376] - DLSR[232649] - LSR[2346597754]
07446efc-4d0a-11e6-b938-eb77bd4505f4 2016-07-18 20:09:54.436388 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:54.455474] SSRC[462318916]RTT[0.041046] A[2346874009] - DLSR[230615] - LSR[2346640704]
420a2e3c-4d0a-11e6-80bc-eb77bd4505f4 2016-07-18 20:09:59.056382 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:59.60163] SSRC[601823969]RTT[0.030365] A[2347175782] - DLSR[248355] - LSR[2346925437]
07446efc-4d0a-11e6-b938-eb77bd4505f4 2016-07-18 20:09:59.136380 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:09:59.155515] SSRC[462318916]RTT[0.037567] A[2347182031] - DLSR[211187] - LSR[2346968382]
07446efc-4d0a-11e6-b938-eb77bd4505f4 2016-07-18 20:10:03.836425 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:10:03.855469] SSRC[462318916]RTT[0.034363] A[2347490048] - DLSR[191733] - LSR[2347296063]
420a2e3c-4d0a-11e6-80bc-eb77bd4505f4 2016-07-18 20:10:04.296399 [NOTICE] switch_rtp.c:5239 Receiving an RTCP packet[2016-18-06 17:10:04.300195] SSRC[601823969]RTT[0.030975] A[2347519193] - DLSR[264044] - LSR[2347253119]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_session.c:1062 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [NOTICE] sofia.c:952 Hangup sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_channel.c:3244 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [KILL]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_ivr_bridge.c:665 BRIDGE THREAD DONE [sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060]
66cdb3d8-4d0a-11e6-839e-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_ivr_bridge.c:695 Send signal sofia/internal/144*102@sipproxy.telphin.ru:5060 [BREAK]
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_state_machine.c:538 (sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060) State EXCHANGE_MEDIA going to sleep
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060) Running State Change CS_HANGUP
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_state_machine.c:735 (sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060) Callstate Change ACTIVE -> HANGUP
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060) State HANGUP
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] mod_sofia.c:413 Channel sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 hanging up, cause: NORMAL_CLEARING
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_state_machine.c:60 sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 Standard HANGUP, cause: NORMAL_CLEARING
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_state_machine.c:737 (sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060) State HANGUP going to sleep
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_state_machine.c:504 (sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060) State Change CS_HANGUP -> CS_REPORTING
66d25a64-4d0a-11e6-83d5-eb77bd4505f4 2016-07-18 20:10:07.436464 [DEBUG] switch_core_session.c:1397 Send signal sofia/internal/%2B79858832517@sipproxy.telphin.ru:5060 [BREAK]