[This app Best viewed at 160x60 or more..]
+OK log level  [7]
2017-03-03 12:24:43.743990 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1002@192.168.0.104:5060 [aacc1dfb-735b-42ad-b912-00a62af5dac6]
2017-03-03 12:24:43.743990 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_NEW (Cur 1 Tot 73)
2017-03-03 12:24:43.743990 [DEBUG] sofia.c:10016 sofia/internal/1002@192.168.0.104:5060 receiving invite from 192.168.0.105:37527 version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
2017-03-03 12:24:43.743990 [DEBUG] sofia.c:10187 IP 192.168.0.105 Rejected by acl "domains". Falling back to Digest auth.
2017-03-03 12:24:43.753985 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1002@192.168.0.104:5060) State NEW
2017-03-03 12:24:43.753985 [DEBUG] sofia.c:2405 detaching session aacc1dfb-735b-42ad-b912-00a62af5dac6
2017-03-03 12:24:43.753985 [DEBUG] sofia.c:2514 Re-attaching to session aacc1dfb-735b-42ad-b912-00a62af5dac6
2017-03-03 12:24:43.773997 [DEBUG] sofia.c:10016 sofia/internal/1002@192.168.0.104:5060 receiving invite from 192.168.0.105:37527 version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
2017-03-03 12:24:43.773997 [DEBUG] sofia.c:10187 IP 192.168.0.105 Rejected by acl "domains". Falling back to Digest auth.
2017-03-03 12:24:43.773997 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [received][100]
2017-03-03 12:24:43.773997 [DEBUG] sofia.c:7251 Remote SDP:
v=0
o=Zoiper 0 0 IN IP4 192.168.0.105
s=Zoiper
c=IN IP4 192.168.0.105
t=0 0
m=audio 38388 RTP/AVP 3 0 8 101
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

2017-03-03 12:24:43.773997 [DEBUG] sofia.c:7643 (sofia/internal/1002@192.168.0.104:5060) State Change CS_NEW -> CS_INIT
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_INIT (Cur 1 Tot 73)
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@192.168.0.104:5060) State INIT
2017-03-03 12:24:43.773997 [DEBUG] mod_sofia.c:93 sofia/internal/1002@192.168.0.104:5060 SOFIA INIT
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1002@192.168.0.104:5060 Standard INIT
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1002@192.168.0.104:5060) State Change CS_INIT -> CS_ROUTING
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@192.168.0.104:5060) State INIT going to sleep
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_ROUTING (Cur 1 Tot 73)
2017-03-03 12:24:43.773997 [DEBUG] switch_channel.c:2249 (sofia/internal/1002@192.168.0.104:5060) Callstate Change DOWN -> RINGING
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@192.168.0.104:5060) State ROUTING
2017-03-03 12:24:43.773997 [DEBUG] mod_sofia.c:154 sofia/internal/1002@192.168.0.104:5060 SOFIA ROUTING
2017-03-03 12:24:43.773997 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1002@192.168.0.104:5060 Standard ROUTING
2017-03-03 12:24:43.773997 [INFO] mod_dialplan_xml.c:637 Processing 1002 <1002>->1010 in context default
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->unloop] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->tod_example] continue=true
Dialplan: sofia/internal/1002@192.168.0.104:5060 Date/Time Match (PASS) [tod_example] break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(open=true) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->holiday_example] continue=true
Dialplan: sofia/internal/1002@192.168.0.104:5060 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->global-intercept] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global-intercept] destination_number(1010) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [group-intercept] destination_number(1010) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [intercept-ext] destination_number(1010) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->redial] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [redial] destination_number(1010) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->global] continue=true
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [global] ${switch_r_sdp}(v=0
o=Zoiper 0 0 IN IP4 192.168.0.105
s=Zoiper
c=IN IP4 192.168.0.105
t=0 0
m=audio 38388 RTP/AVP 3 0 8 101
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
Dialplan: sofia/internal/1002@192.168.0.104:5060 Absolute Condition [global]
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial/global/${uuid}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->snom-demo-2] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [snom-demo-2] destination_number(1010) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->snom-demo-1] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [snom-demo-1] destination_number(1010) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [eavesdrop] destination_number(1010) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [eavesdrop] destination_number(1010) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->call_return] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [call_return] destination_number(1010) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->del-group] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [del-group] destination_number(1010) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->add-group] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [add-group] destination_number(1010) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->call-group-simo] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [call-group-simo] destination_number(1010) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->call-group-order] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [call-group-order] destination_number(1010) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->extension-intercom] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (FAIL) [extension-intercom] destination_number(1010) =~ /^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 parsing [default->Local_Extension] continue=false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Regex (PASS) [Local_Extension] destination_number(1010) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action export(dialed_extension=1010) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(1 b s execute_extension::dx XML features) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(3 b s execute_extension::cf XML features) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bind_meta_app(4 b s execute_extension::att_xfer XML features) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(ringback=${us-ring}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(transfer_ringback=local_stream://moh) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(call_timeout=30) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(hangup_after_bridge=true) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(continue_on_fail=true) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bridge(user/${dialed_extension}@${domain_name}) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action answer() 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action sleep(1000) 
Dialplan: sofia/internal/1002@192.168.0.104:5060 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) 
2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1002@192.168.0.104:5060) State Change CS_ROUTING -> CS_EXECUTE
2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@192.168.0.104:5060) State ROUTING going to sleep
2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@192.168.0.104:5060) Running State Change CS_EXECUTE (Cur 1 Tot 73)
2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@192.168.0.104:5060) State EXECUTE
2017-03-03 12:24:43.783979 [DEBUG] mod_sofia.c:209 sofia/internal/1002@192.168.0.104:5060 SOFIA EXECUTE
2017-03-03 12:24:43.783979 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1002@192.168.0.104:5060 Standard EXECUTE
EXECUTE sofia/internal/1002@192.168.0.104:5060 set(open=true)
2017-03-03 12:24:43.783979 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [open]=[true]
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-spymap/1002/aacc1dfb-735b-42ad-b912-00a62af5dac6)
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial/1002/1010)
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial/global/aacc1dfb-735b-42ad-b912-00a62af5dac6)
EXECUTE sofia/internal/1002@192.168.0.104:5060 export(RFC2822_DATE=Fri, 03 Mar 2017 12:24:43 +0000)
2017-03-03 12:24:43.783979 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 03 Mar 2017 12:24:43 +0000]
EXECUTE sofia/internal/1002@192.168.0.104:5060 export(dialed_extension=1010)
2017-03-03 12:24:43.783979 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1010]
EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(1 b s execute_extension::dx XML features)
2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *1 execute_extension::dx XML features
EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(2 b s record_session::/usr/local/freeswitch/recordings/1002.2017-03-03-12-24-43.wav)
2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *2 record_session::/usr/local/freeswitch/recordings/1002.2017-03-03-12-24-43.wav
EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(3 b s execute_extension::cf XML features)
2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *3 execute_extension::cf XML features
EXECUTE sofia/internal/1002@192.168.0.104:5060 bind_meta_app(4 b s execute_extension::att_xfer XML features)
2017-03-03 12:24:43.794091 [INFO] switch_ivr_async.c:4247 Bound B-Leg: *4 execute_extension::att_xfer XML features
EXECUTE sofia/internal/1002@192.168.0.104:5060 set(ringback=%(2000,4000,440,480))
2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/internal/1002@192.168.0.104:5060 set(transfer_ringback=local_stream://moh)
2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [transfer_ringback]=[local_stream://moh]
EXECUTE sofia/internal/1002@192.168.0.104:5060 set(call_timeout=30)
2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [call_timeout]=[30]
EXECUTE sofia/internal/1002@192.168.0.104:5060 set(hangup_after_bridge=true)
2017-03-03 12:24:43.794091 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [hangup_after_bridge]=[true]
EXECUTE sofia/internal/1002@192.168.0.104:5060 set(continue_on_fail=true)
2017-03-03 12:24:43.803984 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [continue_on_fail]=[true]
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-call_return/1010/1002)
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial_ext/1010/aacc1dfb-735b-42ad-b912-00a62af5dac6)
EXECUTE sofia/internal/1002@192.168.0.104:5060 set(called_party_callgroup=techsupport)
2017-03-03 12:24:43.803984 [DEBUG] mod_dptools.c:1567 SET sofia/internal/1002@192.168.0.104:5060 [called_party_callgroup]=[techsupport]
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial_ext/techsupport/aacc1dfb-735b-42ad-b912-00a62af5dac6)
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial_ext/global/aacc1dfb-735b-42ad-b912-00a62af5dac6)
EXECUTE sofia/internal/1002@192.168.0.104:5060 hash(insert/192.168.0.104-last_dial/techsupport/aacc1dfb-735b-42ad-b912-00a62af5dac6)
EXECUTE sofia/internal/1002@192.168.0.104:5060 bridge(user/1010@192.168.0.104)
2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 03 Mar 2017 12:24:43 +0000] to event
2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [dialed_extension]=[1010] to event
2017-03-03 12:24:43.813983 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 03 Mar 2017 12:24:43 +0000] to event
2017-03-03 12:24:43.813983 [DEBUG] switch_channel.c:1250 sofia/internal/1002@192.168.0.104:5060 EXPORTING[export_vars] [dialed_extension]=[1010] to event
2017-03-03 12:24:43.813983 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
2017-03-03 12:24:43.813983 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1010@192.168.0.128:36056 [d2238c44-67b5-4e18-b4a9-dd100906de39]
2017-03-03 12:24:43.813983 [DEBUG] mod_sofia.c:5002 (sofia/internal/1010@192.168.0.128:36056) State Change CS_NEW -> CS_INIT
2017-03-03 12:24:43.813983 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_INIT (Cur 2 Tot 74)
2017-03-03 12:24:43.813983 [NOTICE] switch_ivr_originate.c:2846 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1010@192.168.0.128:36056) State INIT
2017-03-03 12:24:43.823989 [DEBUG] mod_sofia.c:93 sofia/internal/1010@192.168.0.128:36056 SOFIA INIT
2017-03-03 12:24:43.823989 [DEBUG] sofia_glue.c:1296 sofia/internal/1010@192.168.0.128:36056 sending invite version: 1.9.0 git 91d62b7 2017-02-24 22:49:43Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1488525837 1488525838 IN IP4 192.168.0.104
s=FreeSWITCH
c=IN IP4 192.168.0.104
t=0 0
m=audio 18046 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1010@192.168.0.128:36056 Standard INIT
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1010@192.168.0.128:36056) State Change CS_INIT -> CS_ROUTING
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1010@192.168.0.128:36056) State INIT going to sleep
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_ROUTING (Cur 2 Tot 74)
2017-03-03 12:24:43.823989 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [calling][0]
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1010@192.168.0.128:36056) State ROUTING
2017-03-03 12:24:43.823989 [DEBUG] mod_sofia.c:154 sofia/internal/1010@192.168.0.128:36056 SOFIA ROUTING
2017-03-03 12:24:43.823989 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1010@192.168.0.128:36056) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1010@192.168.0.128:36056) State ROUTING going to sleep
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 74)
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1010@192.168.0.128:36056) State CONSUME_MEDIA
2017-03-03 12:24:43.823989 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1010@192.168.0.128:36056) State CONSUME_MEDIA going to sleep
2017-03-03 12:24:43.943979 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [proceeding][180]
2017-03-03 12:24:43.943979 [NOTICE] sofia.c:7349 Ring-Ready sofia/internal/1010@192.168.0.128:36056!
2017-03-03 12:24:43.943979 [DEBUG] switch_channel.c:3353 (sofia/internal/1010@192.168.0.128:36056) Callstate Change DOWN -> RINGING
2017-03-03 12:24:43.963985 [INFO] switch_ivr_originate.c:1220 Sending early media
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:3414 Set Codec sofia/internal/1002@192.168.0.104:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2017-03-03 12:24:43.963985 [DEBUG] switch_core_codec.c:111 sofia/internal/1002@192.168.0.104:5060 Original read codec set to PCMU:0
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:5376 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:7978 AUDIO RTP [sofia/internal/1002@192.168.0.104:5060] 192.168.0.104 port 20582 -> 192.168.0.105 port 38388 codec: 0 ms: 20
2017-03-03 12:24:43.963985 [DEBUG] switch_rtp.c:3888 Starting timer [soft] 160 bytes per 20ms
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:8286 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf send payload to 101
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:8293 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf receive payload to 101
2017-03-03 12:24:43.963985 [DEBUG] switch_core_media.c:8316 sofia/internal/1002@192.168.0.104:5060 Set rtp dtmf delay to 40
2017-03-03 12:24:43.963985 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1002@192.168.0.104:5060!
2017-03-03 12:24:43.974018 [DEBUG] switch_channel.c:3481 (sofia/internal/1002@192.168.0.104:5060) Callstate Change RINGING -> EARLY
2017-03-03 12:24:43.974018 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1002@192.168.0.104:5060.
2017-03-03 12:24:43.974018 [DEBUG] mod_sofia.c:2502 Ring SDP:
v=0
o=FreeSWITCH 1488523301 1488523302 IN IP4 192.168.0.104
s=FreeSWITCH
c=IN IP4 192.168.0.104
t=0 0
m=audio 20582 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2017-03-03 12:24:43.974018 [DEBUG] switch_ivr_originate.c:1278 Raw Codec Activation Success L16@8000hz 1 channel 20ms
2017-03-03 12:24:43.974018 [DEBUG] switch_core_codec.c:223 sofia/internal/1002@192.168.0.104:5060 Push codec L16:100
2017-03-03 12:24:43.974018 [DEBUG] switch_ivr_originate.c:1347 Play Ringback Tone [%(2000,4000,440,480)]
2017-03-03 12:24:43.994007 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [early][183]
2017-03-03 12:24:44.323992 [DEBUG] switch_rtp.c:7020 Correct audio ip/port confirmed.
2017-03-03 12:24:48.563996 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [completing][200]
2017-03-03 12:24:48.563996 [DEBUG] sofia.c:7251 Remote SDP:
v=0
o=Zoiper 0 1 IN IP4 192.168.0.128
s=Zoiper
c=IN IP4 192.168.0.128
t=0 0
m=audio 33540 RTP/AVP 0 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16

2017-03-03 12:24:48.563996 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [ready][200]
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:3414 Set Codec sofia/internal/1010@192.168.0.128:36056 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2017-03-03 12:24:48.563996 [DEBUG] switch_core_codec.c:111 sofia/internal/1010@192.168.0.128:36056 Original read codec set to PCMU:0
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:5376 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf send payload to 101 recv payload to 101
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:7978 AUDIO RTP [sofia/internal/1010@192.168.0.128:36056] 192.168.0.104 port 18046 -> 192.168.0.128 port 33540 codec: 0 ms: 20
2017-03-03 12:24:48.563996 [DEBUG] switch_rtp.c:3888 Starting timer [soft] 160 bytes per 20ms
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:8286 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf send payload to 101
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:8293 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf receive payload to 101
2017-03-03 12:24:48.563996 [DEBUG] switch_core_media.c:8316 sofia/internal/1010@192.168.0.128:36056 Set rtp dtmf delay to 40
2017-03-03 12:24:48.563996 [NOTICE] sofia.c:8365 Channel [sofia/internal/1010@192.168.0.128:36056] has been answered
2017-03-03 12:24:48.573982 [DEBUG] switch_channel.c:3780 (sofia/internal/1010@192.168.0.128:36056) Callstate Change RINGING -> ACTIVE
2017-03-03 12:24:48.583987 [DEBUG] switch_core_codec.c:248 sofia/internal/1002@192.168.0.104:5060 Restore previous codec PCMU:0.
2017-03-03 12:24:48.583987 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1002@192.168.0.104:5060.
2017-03-03 12:24:48.583987 [DEBUG] mod_sofia.c:881 Local SDP sofia/internal/1002@192.168.0.104:5060:
v=0
o=FreeSWITCH 1488523301 1488523303 IN IP4 192.168.0.104
s=FreeSWITCH
c=IN IP4 192.168.0.104
t=0 0
m=audio 20582 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2017-03-03 12:24:48.583987 [NOTICE] switch_ivr_originate.c:3633 Channel [sofia/internal/1002@192.168.0.104:5060] has been answered
2017-03-03 12:24:48.593983 [DEBUG] switch_channel.c:3780 (sofia/internal/1002@192.168.0.104:5060) Callstate Change EARLY -> ACTIVE
2017-03-03 12:24:48.593983 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [completed][200]
2017-03-03 12:24:48.593983 [DEBUG] switch_ivr_originate.c:3691 Originate Resulted in Success: [sofia/internal/1010@192.168.0.128:36056]
2017-03-03 12:24:48.593983 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [ready][200]
2017-03-03 12:24:48.593983 [DEBUG] switch_ivr_originate.c:3691 Originate Resulted in Success: [sofia/internal/1010@192.168.0.128:36056]
2017-03-03 12:24:48.603984 [DEBUG] switch_ivr_bridge.c:1696 (sofia/internal/1010@192.168.0.128:36056) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2017-03-03 12:24:48.603984 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1010@192.168.0.128:36056) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 74)
2017-03-03 12:24:48.603984 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1010@192.168.0.128:36056) State EXCHANGE_MEDIA
2017-03-03 12:24:48.603984 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
2017-03-03 12:24:48.823991 [DEBUG] switch_rtp.c:7020 Correct audio ip/port confirmed.
2017-03-03 12:24:48.823991 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [received][100]
2017-03-03 12:24:48.823991 [DEBUG] sofia.c:7251 Remote SDP:
v=0
o=Zoiper 0 1 IN IP4 192.168.0.105
s=Zoiper
c=IN IP4 192.168.0.105
t=0 0
m=audio 38388 RTP/AVP 0 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 38414 RTP/AVP 116 115
a=rtpmap:116 VP8/90000
a=rtpmap:115 H263-1998/90000

2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5376 sofia/internal/1002@192.168.0.104:5060 Set 2833 dtmf send payload to 101 recv payload to 101
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5659 Video Codec Compare [VP8:116]/[VP8:99]
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5691 Video Codec Compare [VP8:116] +++ is saved as a match
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:5659 Video Codec Compare [H263-1998:115]/[VP8:99]
2017-03-03 12:24:48.823991 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-03-03 12:24:48.823991 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-03-03 12:24:48.823991 [DEBUG] switch_core_media.c:3224 Set VIDEO Codec sofia/internal/1002@192.168.0.104:5060 VP8/90000 0 ms
2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1002@192.168.0.104:5060.
2017-03-03 12:24:48.833990 [DEBUG] switch_rtp.c:3899 Starting video timer.
2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:8816 VIDEO RTP [sofia/internal/1002@192.168.0.104:5060] 192.168.0.104:27574->192.168.0.105:38414 codec: 116 ms: 0 [SUCCESS]
2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:6977 sofia/internal/1002@192.168.0.104:5060 Starting Video thread
2017-03-03 12:24:48.833990 [DEBUG] sofia.c:8144 Processing updated SDP
2017-03-03 12:24:48.833990 [DEBUG] switch_core_media.c:6843 sofia/internal/1002@192.168.0.104:5060 Video thread started. Echo is off
2017-03-03 12:24:48.843988 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [completed][200]
2017-03-03 12:24:48.843988 [DEBUG] sofia.c:7241 Channel sofia/internal/1002@192.168.0.104:5060 entering state [ready][200]
2017-03-03 12:24:48.853987 [NOTICE] switch_vpx.c:402 VPX reset encoder picture from 320x240 to 352x288 1024 BW
2017-03-03 12:24:49.163995 [DEBUG] switch_rtp.c:7020 Correct video ip/port confirmed.
2017-03-03 12:24:49.244020 [DEBUG] switch_rtp.c:7020 Correct audio ip/port confirmed.
2017-03-03 12:24:57.683985 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [received][100]
2017-03-03 12:24:57.683985 [DEBUG] sofia.c:7251 Remote SDP:
v=0
o=Zoiper 0 2 IN IP4 192.168.0.128
s=Zoiper
c=IN IP4 192.168.0.128
t=0 0
m=audio 33540 RTP/AVP 0 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 33552 RTP/AVP 116 115
a=rtpmap:116 VP8/90000
a=rtpmap:115 H263-1998/90000

2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5058 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5113 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:4974 Set telephone-event payload to 101@8000
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5317 Set telephone-event payload to 101@8000
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5376 sofia/internal/1010@192.168.0.128:36056 Set 2833 dtmf send payload to 101 recv payload to 101
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5659 Video Codec Compare [VP8:116]/[VP8:99]
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5691 Video Codec Compare [VP8:116] +++ is saved as a match
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:5659 Video Codec Compare [H263-1998:115]/[VP8:99]
2017-03-03 12:24:57.683985 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-03-03 12:24:57.683985 [DEBUG] switch_vpx.c:627 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7
2017-03-03 12:24:57.683985 [DEBUG] switch_core_media.c:3224 Set VIDEO Codec sofia/internal/1010@192.168.0.128:36056 VP8/90000 0 ms
2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:7961 Audio params are unchanged for sofia/internal/1010@192.168.0.128:36056.
2017-03-03 12:24:57.694000 [DEBUG] switch_rtp.c:3899 Starting video timer.
2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:8816 VIDEO RTP [sofia/internal/1010@192.168.0.128:36056] 192.168.0.104:26338->192.168.0.128:33552 codec: 116 ms: 0 [SUCCESS]
2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:6977 sofia/internal/1010@192.168.0.128:36056 Starting Video thread
2017-03-03 12:24:57.694000 [DEBUG] sofia.c:8144 Processing updated SDP
2017-03-03 12:24:57.694000 [DEBUG] switch_core_media.c:6843 sofia/internal/1010@192.168.0.128:36056 Video thread started. Echo is off
2017-03-03 12:24:57.704004 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [completed][200]
2017-03-03 12:24:57.704004 [DEBUG] sofia.c:7241 Channel sofia/internal/1010@192.168.0.128:36056 entering state [ready][200]
2017-03-03 12:24:58.143997 [DEBUG] switch_rtp.c:7020 Correct video ip/port confirmed.