From Sharp Gibbon, 6 Years ago, written in Plain Text.
This paste is a reply to Re: type [error] cause: [USER_NOT_REGISTERED] from 11119
- go back
Embed
[BEGIN] 2016-5-9 11:29:53
2016-04-29 10:36:29.698191 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1000@192.168.7.66 [9b1e46d4-c42e-41ec-9e50-b6361fed93d4]
2016-04-29 10:36:29.698191 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_NEW
2016-04-29 10:36:29.698191 [DEBUG] sofia.c:9248 sofia/internal/1000@192.168.7.66 receiving invite from 192.168.7.40:5060 version: 1.6.6 -13-d2d0b32 64bit
2016-04-29 10:36:29.698191 [DEBUG] sofia.c:9415 IP 192.168.7.40 Rejected by acl "domains". Falling back to Digest auth.
2016-04-29 10:36:29.708466 [DEBUG] sofia.c:2147 detaching session 9b1e46d4-c42e-41ec-9e50-b6361fed93d4
2016-04-29 10:36:29.708466 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1000@192.168.7.66) State NEW
2016-04-29 10:36:40.038628 [WARNING] switch_core_state_machine.c:576 9b1e46d4-c42e-41ec-9e50-b6361fed93d4 sofia/internal/1000@192.168.7.66 Abandoned
2016-04-29 10:36:40.038628 [NOTICE] switch_core_state_machine.c:579 Hangup sofia/internal/1000@192.168.7.66 [CS_NEW] [WRONG_CALL_STATE]
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_HANGUP
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1000@192.168.7.66) Callstate Change DOWN -> HANGUP
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@192.168.7.66) State HANGUP
2016-04-29 10:36:40.038628 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1000@192.168.7.66 hanging up, cause: WRONG_CALL_STATE
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@192.168.7.66 Standard HANGUP, cause: WRONG_CALL_STATE
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@192.168.7.66) State HANGUP going to sleep
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1000@192.168.7.66) State Change CS_HANGUP -> CS_REPORTING
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_REPORTING
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@192.168.7.66) State REPORTING
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1000@192.168.7.66 Standard REPORTING, cause: WRONG_CALL_STATE
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@192.168.7.66) State REPORTING going to sleep
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1000@192.168.7.66) State Change CS_REPORTING -> CS_DESTROY
2016-04-29 10:36:40.038628 [DEBUG] switch_core_session.c:1646 Session 386 (sofia/internal/1000@192.168.7.66) Locked, Waiting on external entities
2016-04-29 10:36:40.038628 [NOTICE] switch_core_session.c:1664 Session 386 (sofia/internal/1000@192.168.7.66) Ended
2016-04-29 10:36:40.038628 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1000@192.168.7.66 [CS_DESTROY]
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1000@192.168.7.66) Running State Change CS_DESTROY
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.7.66) State DESTROY
2016-04-29 10:36:40.038628 [DEBUG] mod_sofia.c:341 sofia/internal/1000@192.168.7.66 SOFIA DESTROY
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1000@192.168.7.66 Standard DESTROY
2016-04-29 10:36:40.038628 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.7.66) State DESTROY going to sleep
2016-04-29 10:36:54.068228 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1000@192.168.7.66 [aa6051f5-5b67-45ed-8c90-d8db56c1adaf]
2016-04-29 10:36:54.068228 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_NEW
2016-04-29 10:36:54.068228 [DEBUG] sofia.c:9248 sofia/internal/1000@192.168.7.66 receiving invite from 192.168.7.40:5060 version: 1.6.6 -13-d2d0b32 64bit
2016-04-29 10:36:54.068228 [DEBUG] sofia.c:9415 IP 192.168.7.40 Rejected by acl "domains". Falling back to Digest auth.
2016-04-29 10:36:54.078172 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [received][100]
2016-04-29 10:36:54.078172 [DEBUG] sofia.c:6770 Remote SDP:
v=0
o=- 1462764602 1462764602 IN IP4 192.168.7.40
s=MRD=MRE MRC-V=1.0.1
c=IN IP4 192.168.7.40
b=AS:1920
t=0 0
a=sendrecv
m=audio 25000 RTP/AVP 118 115 114 113 102 101 103 99 98 97 9 18 15 0 8 119
a=rtpmap:118 SIRENLPR/48000/1
a=fmtp:118 bitrate=64000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:114 G7221/32000
a=fmtp:114 bitrate=32000
a=rtpmap:113 G7221/32000
a=fmtp:113 bitrate=24000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:101 G7221/16000
a=fmtp:101 bitrate=24000
a=rtpmap:103 G7221/16000
a=fmtp:103 bitrate=16000
a=rtpmap:99 SIREN14/16000
a=fmtp:99 bitrate=48000
a=rtpmap:98 SIREN14/16000
a=fmtp:98 bitrate=32000
a=rtpmap:97 SIREN14/16000
a=fmtp:97 bitrate=24000
a=rtpmap:9 G722/8000
a=fmtp:9 bitrate=64000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:15 G728/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-15
m=video 25002 RTP/AVP 111 109 110 96 34 31 106 105 116
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:109 H264/90000
a=fmtp:109 profile-level-id=42801f; max-br=20010; sar=13
a=rtpmap:110 H264/90000
a=fmtp:110 profile-level-id=42801f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CIF4=1;CIF=1;QCIF=1;SQCIF=1;CUSTOM=352,240,1;CUSTOM=704,480,1;CUSTOM=1024,768,1;CUSTOM=800,600,1;CUSTOM=640,480,1;T
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1;SQCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtpmap:106 H264-SVC/90000
a=fmtp:106 profile-level-id=56001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:105 H264-SVC/90000
a=fmtp:105 profile-level-id=53e01f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:116 vnd.polycom.lpr/9000
a=fmtp:116 V=2;minPP=0;PP=150;RS=52;RP=10;PS=1400
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=rtcp-fb:* ccm tmmbr
a=vnd.polycom.forceVideoMode:9
m=application 25008 UDP/BFCP *
a=setup:actpass
a=connection:new
a=floorctrl:c-s
m=application 25006 RTP/AVP 100
a=rtpmap:100 H224/4800

2016-04-29 10:36:54.078172 [DEBUG] sofia.c:7125 (sofia/internal/1000@192.168.7.66) State Change CS_NEW -> CS_INIT
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1000@192.168.7.66) State NEW
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_INIT
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1000@192.168.7.66) State INIT
2016-04-29 10:36:54.078172 [DEBUG] mod_sofia.c:88 sofia/internal/1000@192.168.7.66 SOFIA INIT
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@192.168.7.66 Standard INIT
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@192.168.7.66) State Change CS_INIT -> CS_ROUTING
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1000@192.168.7.66) State INIT going to sleep
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_ROUTING
2016-04-29 10:36:54.078172 [DEBUG] switch_channel.c:2247 (sofia/internal/1000@192.168.7.66) Callstate Change DOWN -> RINGING
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1000@192.168.7.66) State ROUTING
2016-04-29 10:36:54.078172 [DEBUG] mod_sofia.c:141 sofia/internal/1000@192.168.7.66 SOFIA ROUTING
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1000@192.168.7.66 Standard ROUTING
2016-04-29 10:36:54.078172 [INFO] mod_dialplan_xml.c:637 Processing xiaoming.yang <1000>->1001 in context default
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->rtmp] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [rtmp] destination_number(1001) =~ /^1005$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->unloop] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->tod_example] continue=true
Dialplan: sofia/internal/1000@192.168.7.66 Date/Time Match (PASS) [tod_example] break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 Action set(open=true) 
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->holiday_example] continue=true
Dialplan: sofia/internal/1000@192.168.7.66 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->global-intercept] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [global-intercept] destination_number(1001) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [group-intercept] destination_number(1001) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [intercept-ext] destination_number(1001) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->redial] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [redial] destination_number(1001) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->global] continue=true
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [global] ${default_password}(123456) =~ /^1234$/ break=never
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/1000@192.168.7.66 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [global] ${switch_r_sdp}(v=0
o=- 1462764602 1462764602 IN IP4 192.168.7.40
s=MRD=MRE MRC-V=1.0.1
c=IN IP4 192.168.7.40
b=AS:1920
t=0 0
a=sendrecv
m=audio 25000 RTP/AVP 118 115 114 113 102 101 103 99 98 97 9 18 15 0 8 119
a=rtpmap:118 SIRENLPR/48000/1
a=fmtp:118 bitrate=64000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:114 G7221/32000
a=fmtp:114 bitrate=32000
a=rtpmap:113 G7221/32000
a=fmtp:113 bitrate=24000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:101 G7221/16000
a=fmtp:101 bitrate=24000
a=rtpmap:103 G7221/16000
a=fmtp:103 bitrate=16000
a=rtpmap:99 SIREN14/16000
a=fmtp:99 bitrate=48000
a=rtpmap:98 SIREN14/16000
a=fmtp:98 bitrate=32000
a=rtpmap:97 SIREN14/16000
a=fmtp:97 bitrate=24000
a=rtpmap:9 G722/8000
a=fmtp:9 bitrate=64000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:15 G728/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-15
m=video 25002 RTP/AVP 111 109 110 96 34 31 106 105 116
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:109 H264/90000
a=fmtp:109 profile-level-id=42801f; max-br=20010; sar=13
a=rtpmap:110 H264/90000
a=fmtp:110 profile-level-id=42801f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CIF4=1;CIF=1;QCIF=1;SQCIF=1;CUSTOM=352,240,1;CUSTOM=704,480,1;CUSTOM=1024,768,1;CUSTOM=800,600,1;CUSTOM=640,480,1;T
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1;SQCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtpmap:106 H264-SVC/90000
a=fmtp:106 profile-level-id=56001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:105 H264-SVC/90000
a=fmtp:105 profile-level-id=53e01f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:116 vnd.polycom.lpr/9000
a=fmtp:116 V=2;minPP=0;PP=150;RS=52;RP=10;PS=1400
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=rtcp-fb:* ccm tmmbr
a=vnd.polycom.forceVideoMode:9
m=application 25008 UDP/BFCP *
a=setup:actpass
a=connection:new
a=floorctrl:c-s
m=application 25006 RTP/AVP 100
a=rtpmap:100 H224/4800
) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
Dialplan: sofia/internal/1000@192.168.7.66 Absolute Condition [global]
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-last_dial/global/${uuid}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->snom-demo-2] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [snom-demo-2] destination_number(1001) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->snom-demo-1] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [snom-demo-1] destination_number(1001) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->call_return] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [call_return] destination_number(1001) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->del-group] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [del-group] destination_number(1001) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->add-group] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [add-group] destination_number(1001) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->call-group-simo] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [call-group-simo] destination_number(1001) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->call-group-order] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [call-group-order] destination_number(1001) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->extension-intercom] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (FAIL) [extension-intercom] destination_number(1001) =~ /^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 parsing [default->Local_Extension] continue=false
Dialplan: sofia/internal/1000@192.168.7.66 Regex (PASS) [Local_Extension] destination_number(1001) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@192.168.7.66 Action export(dialed_extension=1001) 
Dialplan: sofia/internal/1000@192.168.7.66 Action bind_meta_app(1 b s execute_extension::dx XML features) 
Dialplan: sofia/internal/1000@192.168.7.66 Action bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav) 
Dialplan: sofia/internal/1000@192.168.7.66 Action bind_meta_app(3 b s execute_extension::cf XML features) 
Dialplan: sofia/internal/1000@192.168.7.66 Action bind_meta_app(4 b s execute_extension::att_xfer XML features) 
Dialplan: sofia/internal/1000@192.168.7.66 Action set(ringback=${us-ring}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action set(transfer_ringback=local_stream://moh) 
Dialplan: sofia/internal/1000@192.168.7.66 Action set(call_timeout=30) 
Dialplan: sofia/internal/1000@192.168.7.66 Action set(hangup_after_bridge=true) 
Dialplan: sofia/internal/1000@192.168.7.66 Action set(continue_on_fail=true) 
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action bridge(user/${dialed_extension}@${domain_name}) 
Dialplan: sofia/internal/1000@192.168.7.66 Action answer() 
Dialplan: sofia/internal/1000@192.168.7.66 Action sleep(1000) 
Dialplan: sofia/internal/1000@192.168.7.66 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) 
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1000@192.168.7.66) State Change CS_ROUTING -> CS_EXECUTE
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1000@192.168.7.66) State ROUTING going to sleep
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_EXECUTE
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@192.168.7.66) State EXECUTE
2016-04-29 10:36:54.078172 [DEBUG] mod_sofia.c:196 sofia/internal/1000@192.168.7.66 SOFIA EXECUTE
2016-04-29 10:36:54.078172 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1000@192.168.7.66 Standard EXECUTE
EXECUTE sofia/internal/1000@192.168.7.66 set(open=true)
2016-04-29 10:36:54.078172 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@192.168.7.66 [open]=[true]
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-spymap/1000/aa6051f5-5b67-45ed-8c90-d8db56c1adaf)
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-last_dial/1000/1001)
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-last_dial/global/aa6051f5-5b67-45ed-8c90-d8db56c1adaf)
EXECUTE sofia/internal/1000@192.168.7.66 export(RFC2822_DATE=Fri, 29 Apr 2016 10:36:54 +0800)
2016-04-29 10:36:54.078172 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [RFC2822_DATE]=[Fri, 29 Apr 2016 10:36:54 +0800]
EXECUTE sofia/internal/1000@192.168.7.66 export(dialed_extension=1001)
2016-04-29 10:36:54.078172 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [dialed_extension]=[1001]
EXECUTE sofia/internal/1000@192.168.7.66 bind_meta_app(1 b s execute_extension::dx XML features)
2016-04-29 10:36:54.078172 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *1 execute_extension::dx XML features
EXECUTE sofia/internal/1000@192.168.7.66 bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/1000.2016-04-29-10-36-54.wav)
2016-04-29 10:36:54.078172 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *2 record_session::/var/lib/freeswitch/recordings/1000.2016-04-29-10-36-54.wav
EXECUTE sofia/internal/1000@192.168.7.66 bind_meta_app(3 b s execute_extension::cf XML features)
2016-04-29 10:36:54.078172 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *3 execute_extension::cf XML features
EXECUTE sofia/internal/1000@192.168.7.66 bind_meta_app(4 b s execute_extension::att_xfer XML features)
2016-04-29 10:36:54.088286 [INFO] switch_ivr_async.c:4152 Bound B-Leg: *4 execute_extension::att_xfer XML features
EXECUTE sofia/internal/1000@192.168.7.66 set(ringback=%(2000,4000,440,480))
2016-04-29 10:36:54.088286 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@192.168.7.66 [ringback]=[%(2000,4000,440,480)]
EXECUTE sofia/internal/1000@192.168.7.66 set(transfer_ringback=local_stream://moh)
2016-04-29 10:36:54.088286 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@192.168.7.66 [transfer_ringback]=[local_stream://moh]
EXECUTE sofia/internal/1000@192.168.7.66 set(call_timeout=30)
2016-04-29 10:36:54.088286 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@192.168.7.66 [call_timeout]=[30]
EXECUTE sofia/internal/1000@192.168.7.66 set(hangup_after_bridge=true)
2016-04-29 10:36:54.088286 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@192.168.7.66 [hangup_after_bridge]=[true]
EXECUTE sofia/internal/1000@192.168.7.66 set(continue_on_fail=true)
2016-04-29 10:36:54.088286 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@192.168.7.66 [continue_on_fail]=[true]
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-call_return/1001/1000)
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-last_dial_ext/1001/aa6051f5-5b67-45ed-8c90-d8db56c1adaf)
EXECUTE sofia/internal/1000@192.168.7.66 set(called_party_callgroup=techsupport)
2016-04-29 10:36:54.088286 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@192.168.7.66 [called_party_callgroup]=[techsupport]
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-last_dial_ext/techsupport/aa6051f5-5b67-45ed-8c90-d8db56c1adaf)
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-last_dial_ext/global/aa6051f5-5b67-45ed-8c90-d8db56c1adaf)
EXECUTE sofia/internal/1000@192.168.7.66 hash(insert/192.168.7.66-last_dial/techsupport/aa6051f5-5b67-45ed-8c90-d8db56c1adaf)
EXECUTE sofia/internal/1000@192.168.7.66 bridge(user/1001@192.168.7.66)
2016-04-29 10:36:54.088286 [DEBUG] switch_channel.c:1247 sofia/internal/1000@192.168.7.66 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 29 Apr 2016 10:36:54 +0800] to event
2016-04-29 10:36:54.088286 [DEBUG] switch_channel.c:1247 sofia/internal/1000@192.168.7.66 EXPORTING[export_vars] [dialed_extension]=[1001] to event
2016-04-29 10:36:54.088286 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
2016-04-29 10:36:54.098159 [DEBUG] switch_channel.c:1247 sofia/internal/1000@192.168.7.66 EXPORTING[export_vars] [RFC2822_DATE]=[Fri, 29 Apr 2016 10:36:54 +0800] to event
2016-04-29 10:36:54.098159 [DEBUG] switch_channel.c:1247 sofia/internal/1000@192.168.7.66 EXPORTING[export_vars] [dialed_extension]=[1001] to event
2016-04-29 10:36:54.098159 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
2016-04-29 10:36:54.098159 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1001@192.168.7.41:5060 [8216aab9-d6c7-423d-a00e-887512d99fa0]
2016-04-29 10:36:54.098159 [DEBUG] mod_sofia.c:4776 (sofia/internal/1001@192.168.7.41:5060) State Change CS_NEW -> CS_INIT
2016-04-29 10:36:54.098159 [NOTICE] switch_ivr_originate.c:2763 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@192.168.7.41:5060) Running State Change CS_INIT
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@192.168.7.41:5060) State INIT
2016-04-29 10:36:54.098159 [DEBUG] mod_sofia.c:88 sofia/internal/1001@192.168.7.41:5060 SOFIA INIT
2016-04-29 10:36:54.098159 [DEBUG] sofia_glue.c:1257 sofia/internal/1001@192.168.7.41:5060 sending invite version: 1.6.6 -13-d2d0b32 64bit
Local SDP:
v=0
o=FreeSWITCH 1461876240 1461876241 IN IP4 192.168.7.66
s=FreeSWITCH
c=IN IP4 192.168.7.66
t=0 0
m=audio 21174 RTP/AVP 9 0 8 101
a=rtpmap:9 G722/8000
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
m=video 24984 RTP/AVP 96 111 34
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CIF4=1;CIF=1;QCIF=1;SQCIF=1;CUSTOM=352,240,1;CUSTOM=704,480,1;CUSTOM=1024,768,1;CUSTOM=800,600,1;CUSTOM=640,480,1;T
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:34 H263/90000
b=AS:1024
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 ccm tmmbr
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtcp-fb:111 ccm fir
a=rtcp-fb:111 ccm tmmbr
a=rtcp-fb:111 nack
a=rtcp-fb:111 nack pli
a=rtcp-fb:34 ccm fir
a=rtcp-fb:34 ccm tmmbr
a=rtcp-fb:34 nack
a=rtcp-fb:34 nack pli

2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@192.168.7.41:5060 Standard INIT
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@192.168.7.41:5060) State Change CS_INIT -> CS_ROUTING
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@192.168.7.41:5060) State INIT going to sleep
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@192.168.7.41:5060) Running State Change CS_ROUTING
2016-04-29 10:36:54.098159 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@192.168.7.41:5060 entering state [calling][0]
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@192.168.7.41:5060) State ROUTING
2016-04-29 10:36:54.098159 [DEBUG] mod_sofia.c:141 sofia/internal/1001@192.168.7.41:5060 SOFIA ROUTING
2016-04-29 10:36:54.098159 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1001@192.168.7.41:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@192.168.7.41:5060) State ROUTING going to sleep
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@192.168.7.41:5060) Running State Change CS_CONSUME_MEDIA
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/1001@192.168.7.41:5060) State CONSUME_MEDIA
2016-04-29 10:36:54.098159 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/1001@192.168.7.41:5060) State CONSUME_MEDIA going to sleep
2016-04-29 10:36:54.108179 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@192.168.7.41:5060 entering state [proceeding][180]
2016-04-29 10:36:54.108179 [NOTICE] sofia.c:6862 Ring-Ready sofia/internal/1001@192.168.7.41:5060!
2016-04-29 10:36:54.108179 [DEBUG] switch_channel.c:3340 (sofia/internal/1001@192.168.7.41:5060) Callstate Change DOWN -> RINGING
2016-04-29 10:36:54.118008 [INFO] switch_ivr_originate.c:1216 Sending early media
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 119@8000
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/1000@192.168.7.66 G722/8000 20 ms 160 samples 64000 bits 1 channels
2016-04-29 10:36:54.118008 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@192.168.7.66 Original read codec set to G722:9
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 119@8000
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4485 sofia/internal/1000@192.168.7.66 Set 2833 dtmf send payload to 119 recv payload to 119
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263-1998:115] +++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263:34] +++ is saved as a match
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263:34]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[VP8:99]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263-1998:115]
2016-04-29 10:36:54.118008 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263:34]
2016-04-29 10:36:54.128235 [NOTICE] avcodec.c:965 codec: id=28 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2016-04-29 10:36:54.128235 [NOTICE] avcodec.c:965 codec: id=28 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:2716 Set VIDEO Codec sofia/internal/1000@192.168.7.66 H264/90000 0 ms
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/1000@192.168.7.66] 192.168.7.66 port 19904 -> 192.168.7.40 port 25000 codec: 9 ms: 20
2016-04-29 10:36:54.128235 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:6332 sofia/internal/1000@192.168.7.66 Set 2833 dtmf send payload to 119
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:6339 sofia/internal/1000@192.168.7.66 Set 2833 dtmf receive payload to 119
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:6362 sofia/internal/1000@192.168.7.66 Set rtp dtmf delay to 40
2016-04-29 10:36:54.128235 [DEBUG] switch_rtp.c:3813 Starting video timer.
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:6551 VIDEO RTP [sofia/internal/1000@192.168.7.66] 192.168.7.66:28034->192.168.7.40:25002 codec: 111 ms: 0 [SUCCESS]
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:5206 sofia/internal/1000@192.168.7.66 Starting Video thread
2016-04-29 10:36:54.128235 [INFO] switch_core_media.c:6630 Activating VIDEO RTCP PORT 0 interval 2000 mux 0
2016-04-29 10:36:54.128235 [DEBUG] switch_rtp.c:4181 RTCP send rate is: 2000 and packet rate is: 90000 Remote Port: 25003
2016-04-29 10:36:54.128235 [DEBUG] switch_rtp.c:2472 Setting RTCP remote addr to 192.168.7.40:25003 2
2016-04-29 10:36:54.128235 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1000@192.168.7.66!
2016-04-29 10:36:54.128235 [DEBUG] switch_channel.c:3468 (sofia/internal/1000@192.168.7.66) Callstate Change RINGING -> EARLY
2016-04-29 10:36:54.128235 [DEBUG] mod_sofia.c:2330 Ring SDP:
v=0
o=FreeSWITCH 1461877510 1461877511 IN IP4 192.168.7.66
s=FreeSWITCH
c=IN IP4 192.168.7.66
t=0 0
m=audio 19904 RTP/AVP 9 119
a=rtpmap:9 G722/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-16
a=ptime:20
a=sendrecv
m=video 28034 RTP/AVP 111
a=rtpmap:111 H264/90000
a=rtcp:28035 IN IP4 192.168.7.66
b=AS:1024
a=rtcp-fb:111 ccm fir
a=rtcp-fb:111 ccm tmmbr
a=rtcp-fb:111 nack
a=rtcp-fb:111 nack pli

2016-04-29 10:36:54.128235 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [early][183]
2016-04-29 10:36:54.128235 [DEBUG] switch_ivr_originate.c:1274 Raw Codec Activation Success L16@16000hz 1 channel 20ms
2016-04-29 10:36:54.128235 [DEBUG] switch_core_codec.c:221 sofia/internal/1000@192.168.7.66 Push codec L16:100
2016-04-29 10:36:54.128235 [DEBUG] switch_ivr_originate.c:1343 Play Ringback Tone [%(2000,4000,440,480)]
2016-04-29 10:36:54.128235 [DEBUG] switch_core_media.c:5056 sofia/internal/1000@192.168.7.66 Video thread started. Echo is off
2016-04-29 10:36:54.698261 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@192.168.7.41:5060 entering state [completing][200]
2016-04-29 10:36:54.698261 [DEBUG] sofia.c:6770 Remote SDP:
v=0
o=- 1462764575 1462764575 IN IP4 192.168.7.41
s=MRD=MRE MRC-V=1.0.1
c=IN IP4 192.168.7.41
b=AS:1024
t=0 0
m=audio 3230 RTP/AVP 118 115 114 113 102 101 103 99 98 97 9 18 15 0 8 119
a=rtpmap:118 SIRENLPR/48000/1
a=fmtp:118 bitrate=64000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:114 G7221/32000
a=fmtp:114 bitrate=32000
a=rtpmap:113 G7221/32000
a=fmtp:113 bitrate=24000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:101 G7221/16000
a=fmtp:101 bitrate=24000
a=rtpmap:103 G7221/16000
a=fmtp:103 bitrate=16000
a=rtpmap:99 SIREN14/16000
a=fmtp:99 bitrate=48000
a=rtpmap:98 SIREN14/16000
a=fmtp:98 bitrate=32000
a=rtpmap:97 SIREN14/16000
a=fmtp:97 bitrate=24000
a=rtpmap:9 G722/8000
a=fmtp:9 bitrate=64000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:15 G728/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-15
m=video 3232 RTP/AVP 111 109 110 96 34 31 106 105 116
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:109 H264/90000
a=fmtp:109 profile-level-id=42801f; max-br=20010; sar=13
a=rtpmap:110 H264/90000
a=fmtp:110 profile-level-id=42801f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CIF4=1;CIF=1;QCIF=1;SQCIF=1;CUSTOM=352,240,1;CUSTOM=704,480,1;CUSTOM=1024,768,1;CUSTOM=800,600,1;CUSTOM=640,480,1;T
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1;SQCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtpmap:106 H264-SVC/90000
a=fmtp:106 profile-level-id=56001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:105 H264-SVC/90000
a=fmtp:105 profile-level-id=53e01f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:116 vnd.polycom.lpr/9000
a=fmtp:116 V=2;minPP=0;PP=150;RS=52;RP=10;PS=1400
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=rtcp-fb:* ccm tmmbr
a=vnd.polycom.forceVideoMode:9

2016-04-29 10:36:54.698261 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@192.168.7.41:5060 entering state [ready][200]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 119@8000
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/1001@192.168.7.41:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
2016-04-29 10:36:54.698261 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@192.168.7.41:5060 Original read codec set to G722:9
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 119@8000
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4485 sofia/internal/1001@192.168.7.41:5060 Set 2833 dtmf send payload to 119 recv payload to 101
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263-1998:115] +++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263:34] +++ is saved as a match
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263:34]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263-1998:115]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263:34]
2016-04-29 10:36:54.698261 [NOTICE] avcodec.c:965 codec: id=28 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2016-04-29 10:36:54.698261 [NOTICE] avcodec.c:965 codec: id=28 H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:2716 Set VIDEO Codec sofia/internal/1001@192.168.7.41:5060 H264/90000 0 ms
2016-04-29 10:36:54.698261 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/1001@192.168.7.41:5060] 192.168.7.66 port 21174 -> 192.168.7.41 port 3230 codec: 9 ms: 20
2016-04-29 10:36:54.708194 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
2016-04-29 10:36:54.708194 [DEBUG] switch_core_media.c:6332 sofia/internal/1001@192.168.7.41:5060 Set 2833 dtmf send payload to 119
2016-04-29 10:36:54.708194 [DEBUG] switch_core_media.c:6339 sofia/internal/1001@192.168.7.41:5060 Set 2833 dtmf receive payload to 101
2016-04-29 10:36:54.708194 [DEBUG] switch_core_media.c:6362 sofia/internal/1001@192.168.7.41:5060 Set rtp dtmf delay to 40
2016-04-29 10:36:54.708194 [DEBUG] switch_rtp.c:3813 Starting video timer.
2016-04-29 10:36:54.708194 [DEBUG] switch_core_media.c:6551 VIDEO RTP [sofia/internal/1001@192.168.7.41:5060] 192.168.7.66:24984->192.168.7.41:3232 codec: 111 ms: 0 [SUCCESS]
2016-04-29 10:36:54.708194 [DEBUG] switch_core_media.c:5206 sofia/internal/1001@192.168.7.41:5060 Starting Video thread
2016-04-29 10:36:54.708194 [INFO] switch_core_media.c:6630 Activating VIDEO RTCP PORT 0 interval 2000 mux 0
2016-04-29 10:36:54.708194 [DEBUG] switch_rtp.c:4181 RTCP send rate is: 2000 and packet rate is: 90000 Remote Port: 3233
2016-04-29 10:36:54.708194 [DEBUG] switch_rtp.c:2472 Setting RTCP remote addr to 192.168.7.41:3233 2
2016-04-29 10:36:54.708194 [NOTICE] sofia.c:7724 Channel [sofia/internal/1001@192.168.7.41:5060] has been answered
2016-04-29 10:36:54.708194 [DEBUG] switch_channel.c:3767 (sofia/internal/1001@192.168.7.41:5060) Callstate Change RINGING -> ACTIVE
2016-04-29 10:36:54.708194 [DEBUG] switch_core_media.c:5056 sofia/internal/1001@192.168.7.41:5060 Video thread started. Echo is off
2016-04-29 10:36:54.728209 [DEBUG] switch_core_codec.c:246 sofia/internal/1000@192.168.7.66 Restore previous codec G722:9.
2016-04-29 10:36:54.728209 [DEBUG] mod_sofia.c:799 Local SDP sofia/internal/1000@192.168.7.66:
v=0
o=FreeSWITCH 1461877510 1461877512 IN IP4 192.168.7.66
s=FreeSWITCH
c=IN IP4 192.168.7.66
t=0 0
m=audio 19904 RTP/AVP 9 119
a=rtpmap:9 G722/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-16
a=ptime:20
a=sendrecv
m=video 28034 RTP/AVP 111
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtcp:28035 IN IP4 192.168.7.66
b=AS:1024
a=rtcp-fb:111 ccm fir
a=rtcp-fb:111 ccm tmmbr
a=rtcp-fb:111 nack
a=rtcp-fb:111 nack pli

2016-04-29 10:36:54.728209 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [completed][200]
2016-04-29 10:36:54.728209 [NOTICE] switch_ivr_originate.c:3550 Channel [sofia/internal/1000@192.168.7.66] has been answered
2016-04-29 10:36:54.728209 [DEBUG] switch_channel.c:3767 (sofia/internal/1000@192.168.7.66) Callstate Change EARLY -> ACTIVE
2016-04-29 10:36:54.728209 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/1001@192.168.7.41:5060]
2016-04-29 10:36:54.738028 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/1001@192.168.7.41:5060]
2016-04-29 10:36:54.738028 [DEBUG] switch_ivr_bridge.c:1591 (sofia/internal/1001@192.168.7.41:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2016-04-29 10:36:54.738028 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@192.168.7.41:5060) Running State Change CS_EXCHANGE_MEDIA
2016-04-29 10:36:54.738028 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1001@192.168.7.41:5060) State EXCHANGE_MEDIA
2016-04-29 10:36:54.738028 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA
2016-04-29 10:36:54.858234 [DEBUG] switch_rtp.c:1826 rtcp_stats_init: video ssrc[376959779] base_seq[0]
2016-04-29 10:36:54.858234 [DEBUG] switch_rtp.c:6654 Correct video ip/port confirmed.
2016-04-29 10:36:54.878200 [INFO] avcodec.c:1040 initializing encoder 352x288
using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
profile Constrained Baseline, level 4.1
2016-04-29 10:36:54.928236 [INFO] avcodec.c:1040 initializing encoder 352x288
using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX
profile Constrained Baseline, level 4.1
2016-04-29 10:36:54.928236 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [ready][200]
2016-04-29 10:36:54.948213 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [calling][0]
2016-04-29 10:36:54.968220 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
2016-04-29 10:36:55.158296 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@192.168.7.41:5060 entering state [calling][0]
2016-04-29 10:36:55.168064 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@192.168.7.41:5060 entering state [ready][200]
2016-04-29 10:36:55.168064 [DEBUG] sofia.c:6767 Duplicate SDP
v=0
o=- 1462764575 1462764575 IN IP4 192.168.7.41
s=MRD=MRE MRC-V=1.0.1
c=IN IP4 192.168.7.41
b=AS:1024
t=0 0
m=audio 3230 RTP/AVP 118 115 114 113 102 101 103 99 98 97 9 18 15 0 8 119
a=rtpmap:118 SIRENLPR/48000/1
a=fmtp:118 bitrate=64000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:114 G7221/32000
a=fmtp:114 bitrate=32000
a=rtpmap:113 G7221/32000
a=fmtp:113 bitrate=24000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:101 G7221/16000
a=fmtp:101 bitrate=24000
a=rtpmap:103 G7221/16000
a=fmtp:103 bitrate=16000
a=rtpmap:99 SIREN14/16000
a=fmtp:99 bitrate=48000
a=rtpmap:98 SIREN14/16000
a=fmtp:98 bitrate=32000
a=rtpmap:97 SIREN14/16000
a=fmtp:97 bitrate=24000
a=rtpmap:9 G722/8000
a=fmtp:9 bitrate=64000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:15 G728/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-15
m=video 3232 RTP/AVP 111 109 110 96 34 31 106 105 116
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:109 H264/90000
a=fmtp:109 profile-level-id=42801f; max-br=20010; sar=13
a=rtpmap:110 H264/90000
a=fmtp:110 profile-level-id=42801f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CIF4=1;CIF=1;QCIF=1;SQCIF=1;CUSTOM=352,240,1;CUSTOM=704,480,1;CUSTOM=1024,768,1;CUSTOM=800,600,1;CUSTOM=640,480,1;T
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1;SQCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtpmap:106 H264-SVC/90000
a=fmtp:106 profile-level-id=56001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:105 H264-SVC/90000
a=fmtp:105 profile-level-id=53e01f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:116 vnd.polycom.lpr/9000
a=fmtp:116 V=2;minPP=0;PP=150;RS=52;RP=10;PS=1400
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=rtcp-fb:* ccm tmmbr
a=vnd.polycom.forceVideoMode:9

2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 119@8000
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 119@8000
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4485 sofia/internal/1001@192.168.7.41:5060 Set 2833 dtmf send payload to 119 recv payload to 101
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263-1998:115] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263:34] +++ is saved as a match
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263-1998:115]
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263:34]
2016-04-29 10:36:55.168064 [DEBUG] sofia.c:7567 Processing updated SDP
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:6016 Audio params are unchanged for sofia/internal/1001@192.168.7.41:5060.
2016-04-29 10:36:55.168064 [DEBUG] switch_core_media.c:6409 Video params are unchanged for sofia/internal/1001@192.168.7.41:5060.
2016-04-29 10:36:55.428292 [DEBUG] switch_rtp.c:1826 rtcp_stats_init: video ssrc[1489045394] base_seq[0]
2016-04-29 10:36:55.428292 [DEBUG] switch_rtp.c:6654 Correct video ip/port confirmed.
2016-04-29 10:36:55.988107 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [ready][200]
2016-04-29 10:36:55.988107 [DEBUG] sofia.c:6770 Remote SDP:
v=0
o=- 1462764602 1462764603 IN IP4 192.168.7.40
s=MRD=MRE MRC-V=1.0.1
c=IN IP4 192.168.7.40
b=AS:1088
t=0 0
m=audio 25000 RTP/AVP 118 115 114 113 102 101 103 99 98 97 9 18 15 0 8 119
a=rtpmap:118 SIRENLPR/48000/1
a=fmtp:118 bitrate=64000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:114 G7221/32000
a=fmtp:114 bitrate=32000
a=rtpmap:113 G7221/32000
a=fmtp:113 bitrate=24000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:101 G7221/16000
a=fmtp:101 bitrate=24000
a=rtpmap:103 G7221/16000
a=fmtp:103 bitrate=16000
a=rtpmap:99 SIREN14/16000
a=fmtp:99 bitrate=48000
a=rtpmap:98 SIREN14/16000
a=fmtp:98 bitrate=32000
a=rtpmap:97 SIREN14/16000
a=fmtp:97 bitrate=24000
a=rtpmap:9 G722/8000
a=fmtp:9 bitrate=64000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:15 G728/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-15
m=video 25002 RTP/AVP 111 109 110 96 34 31 106 105 116
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:109 H264/90000
a=fmtp:109 profile-level-id=42801f; max-br=20010; sar=13
a=rtpmap:110 H264/90000
a=fmtp:110 profile-level-id=42801f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CIF4=1;CIF=1;QCIF=1;SQCIF=1;CUSTOM=352,240,1;CUSTOM=704,480,1;CUSTOM=1024,768,1;CUSTOM=800,600,1;CUSTOM=640,480,1;T
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1;SQCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtpmap:106 H264-SVC/90000
a=fmtp:106 profile-level-id=56001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:105 H264-SVC/90000
a=fmtp:105 profile-level-id=53e01f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:116 vnd.polycom.lpr/9000
a=fmtp:116 V=2;minPP=0;PP=150;RS=52;RP=10;PS=1400
a=content:main
a=label:1
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=rtcp-fb:* ccm tmmbr
a=vnd.polycom.forceVideoMode:9
m=application 0 UDP/BFCP 19
m=application 0 RTP/AVP 19

2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 119@8000
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 119@8000
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4485 sofia/internal/1000@192.168.7.66 Set 2833 dtmf send payload to 119 recv payload to 119
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263-1998:115] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263:34] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] sofia.c:7567 Processing updated SDP
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:6016 Audio params are unchanged for sofia/internal/1000@192.168.7.66.
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:6409 Video params are unchanged for sofia/internal/1000@192.168.7.66.
2016-04-29 10:36:55.988107 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [received][100]
2016-04-29 10:36:55.988107 [DEBUG] sofia.c:6770 Remote SDP:
v=0
o=- 1462764602 1462764604 IN IP4 192.168.7.40
s=MRD=MRE MRC-V=1.0.1
c=IN IP4 192.168.7.40
b=AS:1920
t=0 0
m=audio 25000 RTP/AVP 118 115 114 113 102 101 103 99 98 97 9 18 15 0 8 119
a=rtpmap:118 SIRENLPR/48000/1
a=fmtp:118 bitrate=64000
a=rtpmap:115 G7221/32000
a=fmtp:115 bitrate=48000
a=rtpmap:114 G7221/32000
a=fmtp:114 bitrate=32000
a=rtpmap:113 G7221/32000
a=fmtp:113 bitrate=24000
a=rtpmap:102 G7221/16000
a=fmtp:102 bitrate=32000
a=rtpmap:101 G7221/16000
a=fmtp:101 bitrate=24000
a=rtpmap:103 G7221/16000
a=fmtp:103 bitrate=16000
a=rtpmap:99 SIREN14/16000
a=fmtp:99 bitrate=48000
a=rtpmap:98 SIREN14/16000
a=fmtp:98 bitrate=32000
a=rtpmap:97 SIREN14/16000
a=fmtp:97 bitrate=24000
a=rtpmap:9 G722/8000
a=fmtp:9 bitrate=64000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:15 G728/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:119 telephone-event/8000
a=fmtp:119 0-15
m=video 25002 RTP/AVP 111 109 110 96 34 31 106 105 116
a=rtpmap:111 H264/90000
a=fmtp:111 profile-level-id=64001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:109 H264/90000
a=fmtp:109 profile-level-id=42801f; max-br=20010; sar=13
a=rtpmap:110 H264/90000
a=fmtp:110 profile-level-id=42801f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CIF4=1;CIF=1;QCIF=1;SQCIF=1;CUSTOM=352,240,1;CUSTOM=704,480,1;CUSTOM=1024,768,1;CUSTOM=800,600,1;CUSTOM=640,480,1;T
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1;SQCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtpmap:106 H264-SVC/90000
a=fmtp:106 profile-level-id=56001f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:105 H264-SVC/90000
a=fmtp:105 profile-level-id=53e01f; packetization-mode=1; max-br=20010; sar=13
a=rtpmap:116 vnd.polycom.lpr/9000
a=fmtp:116 V=2;minPP=0;PP=150;RS=52;RP=10;PS=1400
a=content:main
a=label:1
a=vnd.polycom.forceVideoMode:9
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=rtcp-fb:* ccm tmmbr
m=application 25008 TCP/BFCP *
a=floorctrl:c-s
a=setup:actpass
a=connection:new
m=application 0 RTP/AVP 19

2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIRENLPR:118:48000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:115:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:114:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:113:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:102:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:101:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G7221:103:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:99:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [SIREN14:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G728:15:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 119@8000
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 119@8000
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4485 sofia/internal/1000@192.168.7.66 Set 2833 dtmf send payload to 119 recv payload to 119
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:111]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:109]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H264:99] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264:110]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263-1998:115] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263-1998:96]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H263:34]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4639 Video Codec Compare [H263:34] +++ is saved as a match
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H261:31]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:106]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [H264-SVC:105]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[VP8:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263-1998:115]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H264:99]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:4627 Video Codec Compare [vnd.polycom.lpr:116]/[H263:34]
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:6016 Audio params are unchanged for sofia/internal/1000@192.168.7.66.
2016-04-29 10:36:55.988107 [DEBUG] switch_core_media.c:6409 Video params are unchanged for sofia/internal/1000@192.168.7.66.
2016-04-29 10:36:55.988107 [DEBUG] sofia.c:7507 Processing updated SDP
2016-04-29 10:36:55.988107 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [completed][200]
2016-04-29 10:36:56.198330 [DEBUG] switch_rtp.c:1826 rtcp_stats_init: video ssrc[269605169] base_seq[10]
2016-04-29 10:36:56.388221 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@192.168.7.66 entering state [ready][200]
2016-04-29 10:36:57.998403 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
2016-04-29 10:37:06.748071 [NOTICE] sofia.c:952 Hangup sofia/internal/1000@192.168.7.66 [CS_EXECUTE] [NORMAL_CLEARING]
2016-04-29 10:37:06.748071 [DEBUG] switch_ivr_bridge.c:719 Ending video thread.
2016-04-29 10:37:06.748071 [DEBUG] switch_ivr_bridge.c:767 Ending video thread.
2016-04-29 10:37:06.748071 [WARNING] switch_rtp.c:5951 Received an unsupported RTCP packet version 3
2016-04-29 10:37:06.748071 [DEBUG] switch_ivr_bridge.c:699 sofia/internal/1000@192.168.7.66 ending bridge by request from write function
2016-04-29 10:37:06.748071 [DEBUG] switch_ivr_bridge.c:719 Ending video thread.
2016-04-29 10:37:06.748071 [DEBUG] switch_ivr_bridge.c:767 Ending video thread.
2016-04-29 10:37:06.748071 [DEBUG] switch_ivr_bridge.c:212 sofia/internal/1000@192.168.7.66 video thread ended.
2016-04-29 10:37:06.748071 [DEBUG] switch_core_media.c:5173 sofia/internal/1000@192.168.7.66 Video thread ended
2016-04-29 10:37:06.758719 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1000@192.168.7.66]
2016-04-29 10:37:06.767986 [DEBUG] switch_ivr_bridge.c:212 sofia/internal/1001@192.168.7.41:5060 video thread ended.
2016-04-29 10:37:06.767986 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1001@192.168.7.41:5060]
2016-04-29 10:37:06.767986 [NOTICE] switch_ivr_bridge.c:881 Hangup sofia/internal/1001@192.168.7.41:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2016-04-29 10:37:06.767986 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1001@192.168.7.41:5060) State EXCHANGE_MEDIA going to sleep
2016-04-29 10:37:06.767986 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@192.168.7.41:5060) Running State Change CS_HANGUP
2016-04-29 10:37:06.767986 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1001@192.168.7.41:5060) Callstate Change ACTIVE -> HANGUP
2016-04-29 10:37:06.767986 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@192.168.7.41:5060) State HANGUP
2016-04-29 10:37:06.767986 [DEBUG] mod_sofia.c:425 sofia/internal/1001@192.168.7.41:5060 Overriding SIP cause 480 with 200 from the other leg
2016-04-29 10:37:06.767986 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1001@192.168.7.41:5060 hanging up, cause: NORMAL_CLEARING
2016-04-29 10:37:06.778421 [DEBUG] mod_sofia.c:484 Sending BYE to sofia/internal/1001@192.168.7.41:5060
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@192.168.7.41:5060 Standard HANGUP, cause: NORMAL_CLEARING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@192.168.7.41:5060) State HANGUP going to sleep
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1001@192.168.7.41:5060) State Change CS_HANGUP -> CS_REPORTING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@192.168.7.41:5060) Running State Change CS_REPORTING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1001@192.168.7.41:5060) State REPORTING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1001@192.168.7.41:5060 Standard REPORTING, cause: NORMAL_CLEARING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1001@192.168.7.41:5060) State REPORTING going to sleep
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1001@192.168.7.41:5060) State Change CS_REPORTING -> CS_DESTROY
2016-04-29 10:37:06.778421 [DEBUG] switch_core_session.c:1646 Session 388 (sofia/internal/1001@192.168.7.41:5060) Locked, Waiting on external entities
2016-04-29 10:37:06.778421 [WARNING] switch_rtp.c:5951 Received an unsupported RTCP packet version 3
2016-04-29 10:37:06.778421 [DEBUG] switch_ivr_bridge.c:1692 sofia/internal/1000@192.168.7.66 skip receive message [UNBRIDGE] (channel is hungup already)
2016-04-29 10:37:06.778421 [DEBUG] switch_core_session.c:2796 sofia/internal/1000@192.168.7.66 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@192.168.7.66) State EXECUTE going to sleep
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_HANGUP
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1000@192.168.7.66) Callstate Change ACTIVE -> HANGUP
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@192.168.7.66) State HANGUP
2016-04-29 10:37:06.778421 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1000@192.168.7.66 hanging up, cause: NORMAL_CLEARING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@192.168.7.66 Standard HANGUP, cause: NORMAL_CLEARING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@192.168.7.66) State HANGUP going to sleep
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1000@192.168.7.66) State Change CS_HANGUP -> CS_REPORTING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@192.168.7.66) Running State Change CS_REPORTING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@192.168.7.66) State REPORTING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1000@192.168.7.66 Standard REPORTING, cause: NORMAL_CLEARING
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@192.168.7.66) State REPORTING going to sleep
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1000@192.168.7.66) State Change CS_REPORTING -> CS_DESTROY
2016-04-29 10:37:06.778421 [DEBUG] switch_core_session.c:1646 Session 387 (sofia/internal/1000@192.168.7.66) Locked, Waiting on external entities
2016-04-29 10:37:06.778421 [NOTICE] switch_core_session.c:1664 Session 387 (sofia/internal/1000@192.168.7.66) Ended
2016-04-29 10:37:06.778421 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1000@192.168.7.66 [CS_DESTROY]
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1000@192.168.7.66) Running State Change CS_DESTROY
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.7.66) State DESTROY
2016-04-29 10:37:06.778421 [DEBUG] mod_sofia.c:341 sofia/internal/1000@192.168.7.66 SOFIA DESTROY
frame I:1     Avg QP:10.00  size:  1051
mb I  I16..4: 99.7%  0.0%  0.3%
coded y,uvDC,uvAC intra: 0.1% 0.0% 0.0%
i16 v,h,dc,p: 95%  0%  5%  0%
i4 v,h,dc,ddl,ddr,vr,hd,vl,hu:  0%  0% 100%  0%  0%  0%  0%  0%  0%
i8c dc,h,v,p: 100%  0%  0%  0%
kb/s:756.72
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1000@192.168.7.66 Standard DESTROY
2016-04-29 10:37:06.778421 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@192.168.7.66) State DESTROY going to sleep
2016-04-29 10:37:06.788264 [DEBUG] switch_core_media.c:5173 sofia/internal/1001@192.168.7.41:5060 Video thread ended
2016-04-29 10:37:06.788264 [NOTICE] switch_core_session.c:1664 Session 388 (sofia/internal/1001@192.168.7.41:5060) Ended
2016-04-29 10:37:06.788264 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1001@192.168.7.41:5060 [CS_DESTROY]
2016-04-29 10:37:06.788264 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1001@192.168.7.41:5060) Running State Change CS_DESTROY
2016-04-29 10:37:06.788264 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@192.168.7.41:5060) State DESTROY
2016-04-29 10:37:06.788264 [DEBUG] mod_sofia.c:341 sofia/internal/1001@192.168.7.41:5060 SOFIA DESTROY
frame I:1     Avg QP:10.00  size:  1051
mb I  I16..4: 99.7%  0.0%  0.3%
coded y,uvDC,uvAC intra: 0.1% 0.0% 0.0%
i16 v,h,dc,p: 95%  0%  5%  0%
i4 v,h,dc,ddl,ddr,vr,hd,vl,hu:  0%  0% 100%  0%  0%  0%  0%  0%  0%
i8c dc,h,v,p: 100%  0%  0%  0%
kb/s:756.72
2016-04-29 10:37:06.788264 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1001@192.168.7.41:5060 Standard DESTROY
2016-04-29 10:37:06.788264 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@192.168.7.41:5060) State DESTROY going to sleep

[END] 2016-5-9 11:30:44