[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: [] 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" ;tag=qcs4gzhfbm] 67cdaf36-4d0a-11e6-83d9-eb77bd4505f4 variable_sip_full_to: [] 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]