2020-06-03 17:43:48.876061 [NOTICE] switch_channel.c:1118 New Channel sofia/external/
2000@sip.vocinity.com [f57d5c39-9c82-4139-8c94-4b34f48acef0]
2020-06-03 17:43:48.876061 [DEBUG] switch_core_state_machine.c:585 (sofia/external/
2000@sip.vocinity.com) Running State Change CS_NEW (Cur 1 Tot 3)
2020-06-03 17:43:48.876061 [DEBUG] sofia.c:10279 sofia/external/
2000@sip.vocinity.com receiving invite from
50.210.153.253:33392 version: 1.10.3 -release 64bit
2020-06-03 17:43:48.876061 [DEBUG] sofia.c:10373 verifying acl "internal" for ip/port
50.210.153.253:0.
2020-06-03 17:43:48.876061 [DEBUG] sofia_reg.c:1790 Send challenge for [
1000@sip.vocinity.com]
2020-06-03 17:43:48.876061 [DEBUG] switch_core_state_machine.c:604 (sofia/external/
2000@sip.vocinity.com) State NEW
2020-06-03 17:43:48.876061 [DEBUG] sofia.c:2434 detaching session f57d5c39-9c82-4139-8c94-4b34f48acef0
2020-06-03 17:43:48.896052 [DEBUG] sofia.c:2544 Re-attaching to session f57d5c39-9c82-4139-8c94-4b34f48acef0
2020-06-03 17:43:48.916064 [DEBUG] sofia.c:10279 sofia/external/
2000@sip.vocinity.com receiving invite from
50.210.153.253:33392 version: 1.10.3 -release 64bit
2020-06-03 17:43:48.916064 [DEBUG] sofia.c:10373 verifying acl "internal" for ip/port
50.210.153.253:0.
2020-06-03 17:43:48.916064 [DEBUG] sofia.c:11476 Setting NAT mode based on via received
2020-06-03 17:43:48.916064 [DEBUG] sofia.c:7325 Channel sofia/external/
2000@sip.vocinity.com entering state [received][100]
2020-06-03 17:43:48.916064 [DEBUG] sofia.c:7335 Remote SDP:
v=0
o=- 4361465521890668688 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=msid-semantic: WMS OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
m=audio 41704 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 50.210.153.253
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
a=rtcp:58455 IN IP4 50.210.153.253
a=candidate:1978755251 1 udp 2122260223 10.88.65.80 41704 typ host generation 0 network-id 1 network-cost 10
a=candidate:1978755251 2 udp 2122260222 10.88.65.80 58455 typ host generation 0 network-id 1 network-cost 10
a=candidate:3702450022 2 udp 1686052606 50.210.153.253 58455 typ srflx raddr 10.88.65.80 rport 58455 generation 0 network-id 1 network-cost 10
a=candidate:3702450022 1 udp 1686052607 50.210.153.253 41704 typ srflx raddr 10.88.65.80 rport 41704 generation 0 network-id 1 network-cost 10
a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=candidate:997364803 2 tcp 1518280446 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=ice-ufrag:CbXl
a=ice-pwd:3HF4+k0xW10Eoa9QpHIEKh6I
a=ice-options:trickle
a=fingerprint:sha-256 96:34:14:58:BA:22:B6:B8:7B:41:92:D0:6F:70:1C:BA:25:BA:3C:03:4E:47:85:CC:33:FB:DB:EA:98:4E:52:89
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-timea=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 6bb8fc4b-068d-4461-9819-c28dfea86bf8
a=rtcp-mux
a=rtcp-fb:111 transport-cc
a=ssrc:1537550124 cname:H+vuxJdK27w4PqCr
a=ssrc:1537550124 msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 6bb8fc4b-068d-4461-9819-c28dfea86bf8
a=ssrc:1537550124 mslabel:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
a=ssrc:1537550124 label:6bb8fc4b-068d-4461-9819-c28dfea86bf8
m=video 57009 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 122 127 121 125 107 108 109 124 120 123
c=IN IP4 50.210.153.253
a=rtpmap:96 VP8/90000
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:98 VP9/90000
a=fmtp:98 profile-id=0
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:100 VP9/90000
a=fmtp:100 profile-id=2
a=rtpmap:101 rtx/90000
a=fmtp:101 apt=100
a=rtpmap:102 H264/90000
a=fmtp:102 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
a=rtpmap:122 rtx/90000
a=fmtp:122 apt=102
a=rtpmap:127 H264/90000
a=fmtp:127 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
a=rtpmap:121 rtx/90000
a=fmtp:121 apt=127
a=rtpmap:125 H264/90000
a=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:108 H264/90000
a=fmtp:108 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
a=rtpmap:109 rtx/90000
a=fmtp:109 apt=108
a=rtpmap:124 red/90000
a=rtpmap:120 rtx/90000
a=fmtp:120 apt=124
a=rtpmap:123 ulpfec/90000
a=rtcp:33483 IN IP4 50.210.153.253
a=candidate:1978755251 1 udp 2122260223 10.88.65.80 57009 typ host generation 0 network-id 1 network-cost 10
a=candidate:1978755251 2 udp 2122260222 10.88.65.80 33483 typ host generation 0 network-id 1 network-cost 10
a=candidate:3702450022 1 udp 1686052607 50.210.153.253 57009 typ srflx raddr 10.88.65.80 rport 57009 generation 0 network-id 1 network-cost 10
a=candidate:3702450022 2 udp 1686052606 50.210.153.253 33483 typ srflx raddr 10.88.65.80 rport 33483 generation 0 network-id 1 network-cost 10
a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=candidate:997364803 2 tcp 1518280446 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=ice-ufrag:CbXl
a=ice-pwd:3HF4+k0xW10Eoa9QpHIEKh6I
a=ice-options:trickle
a=fingerprint:sha-256 96:34:14:58:BA:22:B6:B8:7B:41:92:D0:6F:70:1C:BA:25:BA:3C:03:4E:47:85:CC:33:FB:DB:EA:98:4E:52:89
a=setup:actpass
a=mid:1
a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
a=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-timea=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01a=extmap:12
http://www.webrtc.org/experiments/rtp-hdrext/playout-delaya=extmap:11
http://www.webrtc.org/experiments/rtp-hdrext/video-content-typea=extmap:7
http://www.webrtc.org/experiments/rtp-hdrext/video-timinga=extmap:8
http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07a=extmap:9
http://www.webrtc.org/experiments/rtp-hdrext/color-spacea=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 85c1ee18-b111-4183-8949-af3e72cbd1fe
a=rtcp-mux
a=rtcp-rsize
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtcp-fb:98 goog-remb
a=rtcp-fb:98 transport-cc
a=rtcp-fb:98 ccm fir
a=rtcp-fb:98 nack
a=rtcp-fb:98 nack pli
a=rtcp-fb:100 goog-remb
a=rtcp-fb:100 transport-cc
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=rtcp-fb:102 goog-remb
a=rtcp-fb:102 transport-cc
a=rtcp-fb:102 ccm fir
a=rtcp-fb:102 nack
a=rtcp-fb:102 nack pli
a=rtcp-fb:127 goog-remb
a=rtcp-fb:127 transport-cc
a=rtcp-fb:127 ccm fir
a=rtcp-fb:127 nack
a=rtcp-fb:127 nack pli
a=rtcp-fb:125 goog-remb
a=rtcp-fb:125 transport-cc
a=rtcp-fb:125 ccm fir
a=rtcp-fb:125 nack
a=rtcp-fb:125 nack pli
a=rtcp-fb:108 goog-remb
a=rtcp-fb:108 transport-cc
a=rtcp-fb:108 ccm fir
a=rtcp-fb:108 nack
a=rtcp-fb:108 nack pli
a=ssrc-group:FID 2988188772 2811984399
a=ssrc:2988188772 cname:H+vuxJdK27w4PqCr
a=ssrc:2988188772 msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 85c1ee18-b111-4183-8949-af3e72cbd1fe
a=ssrc:2988188772 mslabel:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
a=ssrc:2988188772 label:85c1ee18-b111-4183-8949-af3e72cbd1fe
a=ssrc:2811984399 cname:H+vuxJdK27w4PqCr
a=ssrc:2811984399 msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 85c1ee18-b111-4183-8949-af3e72cbd1fe
a=ssrc:2811984399 mslabel:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
a=ssrc:2811984399 label:85c1ee18-b111-4183-8949-af3e72cbd1fe
2020-06-03 17:43:48.916064 [DEBUG] sofia.c:7738 (sofia/external/
2000@sip.vocinity.com) State Change CS_NEW -> CS_INIT
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:585 (sofia/external/
2000@sip.vocinity.com) Running State Change CS_INIT (Cur 1 Tot 3)
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:628 (sofia/external/
2000@sip.vocinity.com) State INIT
2020-06-03 17:43:48.916064 [DEBUG] mod_sofia.c:93 sofia/external/
2000@sip.vocinity.com SOFIA INIT
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:40 sofia/external/
2000@sip.vocinity.com Standard INIT
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:48 (sofia/external/
2000@sip.vocinity.com) State Change CS_INIT -> CS_ROUTING
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:628 (sofia/external/
2000@sip.vocinity.com) State INIT going to sleep
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:585 (sofia/external/
2000@sip.vocinity.com) Running State Change CS_ROUTING (Cur 1 Tot 3)
2020-06-03 17:43:48.916064 [DEBUG] switch_channel.c:2332 (sofia/external/
2000@sip.vocinity.com) Callstate Change DOWN -> RINGING
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:644 (sofia/external/
2000@sip.vocinity.com) State ROUTING
2020-06-03 17:43:48.916064 [DEBUG] mod_sofia.c:154 sofia/external/
2000@sip.vocinity.com SOFIA ROUTING
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:236 sofia/external/
2000@sip.vocinity.com Standard ROUTING
2020-06-03 17:43:48.916064 [INFO] mod_dialplan_xml.c:637 Processing Nathan <2000>->1000 in context default
Dialplan: sofia/external/
2000@sip.vocinity.com parsing [default->global] continue=true
Dialplan: sofia/external/
2000@sip.vocinity.com Absolute Condition [global]
Dialplan: sofia/external/
2000@sip.vocinity.com Action info()
Dialplan: sofia/external/
2000@sip.vocinity.com parsing [default->Local_Users] continue=false
Dialplan: sofia/external/
2000@sip.vocinity.com Regex (PASS) [Local_Users] destination_number(1000) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/external/
2000@sip.vocinity.com Action export(dialed_extension=1000)
Dialplan: sofia/external/
2000@sip.vocinity.com Action export(nolocal:absolute_codec_string=OPUS,VP8)
Dialplan: sofia/external/
2000@sip.vocinity.com Action set(hangup_after_bridge=true)
Dialplan: sofia/external/
2000@sip.vocinity.com Action set(call_timeout=20)
Dialplan: sofia/external/
2000@sip.vocinity.com Action set(bypass_media=true)
Dialplan: sofia/external/
2000@sip.vocinity.com Action set(record_concat_video=true)
Dialplan: sofia/external/
2000@sip.vocinity.com Action set(enable_file_write_buffering=false)
Dialplan: sofia/external/
2000@sip.vocinity.com Action answer()
Dialplan: sofia/external/
2000@sip.vocinity.com Action record_session(/recordings/${strftime(%Y%m%d%H%M%S)}_${caller_id_number}_${destination_number}.mp4)
Dialplan: sofia/external/
2000@sip.vocinity.com Action bridge(user/${dialed_extension}@${domain_name})
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:287 (sofia/external/
2000@sip.vocinity.com) State Change CS_ROUTING -> CS_EXECUTE
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:644 (sofia/external/
2000@sip.vocinity.com) State ROUTING going to sleep
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:585 (sofia/external/
2000@sip.vocinity.com) Running State Change CS_EXECUTE (Cur 1 Tot 3)
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:651 (sofia/external/
2000@sip.vocinity.com) State EXECUTE
2020-06-03 17:43:48.916064 [DEBUG] mod_sofia.c:209 sofia/external/
2000@sip.vocinity.com SOFIA EXECUTE
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:329 sofia/external/
2000@sip.vocinity.com Standard EXECUTE
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com info()
2020-06-03 17:43:48.916064 [INFO] mod_dptools.c:1885 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-Call-State: [RINGING]
Channel-State-Number: [4]
Channel-Name: [sofia/external/
2000@sip.vocinity.com]
Unique-ID: [f57d5c39-9c82-4139-8c94-4b34f48acef0]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Channel-HIT-Dialplan: [true]
Channel-Call-UUID: [f57d5c39-9c82-4139-8c94-4b34f48acef0]
Answer-State: [ringing]
Caller-Direction: [inbound]
Caller-Logical-Direction: [inbound]
Caller-Username: [2000]
Caller-Dialplan: [XML]
Caller-Caller-ID-Name: [Nathan]
Caller-Caller-ID-Number: [2000]
Caller-Orig-Caller-ID-Name: [Nathan]
Caller-Orig-Caller-ID-Number: [2000]
Caller-Network-Addr: [50.210.153.253]
Caller-ANI: [2000]
Caller-Destination-Number: [1000]
Caller-Unique-ID: [f57d5c39-9c82-4139-8c94-4b34f48acef0]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/external/
2000@sip.vocinity.com]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1591220628916064]
Caller-Channel-Created-Time: [1591220628916064]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Channel-Resurrect-Time: [0]
Caller-Channel-Bridged-Time: [0]
Caller-Channel-Last-Hold: [0]
Caller-Channel-Hold-Accum: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
variable_direction: [inbound]
variable_uuid: [f57d5c39-9c82-4139-8c94-4b34f48acef0]
variable_session_id: [3]
variable_sip_from_user: [2000]
variable_sip_from_uri: [
2000@sip.vocinity.com]
variable_sip_from_host: [
sip.vocinity.com]
variable_video_media_flow: [disabled]
variable_audio_media_flow: [disabled]
variable_text_media_flow: [disabled]
variable_channel_name: [sofia/external/
2000@sip.vocinity.com]
variable_sip_call_id: [thge6sa0p8j1kfqmfftt]
variable_sip_local_network_addr: [204.89.189.50]
variable_sip_network_ip: [50.210.153.253]
variable_sip_network_port: [33392]
variable_sip_invite_stamp: [1591220628916064]
variable_sip_received_ip: [50.210.153.253]
variable_sip_received_port: [33392]
variable_sip_via_protocol: [wss]
variable_sip_authorized: [true]
variable_Event-Name: [REQUEST_PARAMS]
variable_Core-UUID: [2a296e4d-c0f7-4b8d-b924-f3ea8900f309]
variable_FreeSWITCH-Hostname: [
appserver.vocinity.com]
variable_FreeSWITCH-Switchname: [
appserver.vocinity.com]
variable_FreeSWITCH-IPv4: [204.89.189.50]
variable_FreeSWITCH-IPv6: [::1]
variable_Event-Date-Local: [2020-06-03 17:43:48]
variable_Event-Date-GMT: [Wed, 03 Jun 2020 21:43:48 GMT]
variable_Event-Date-Timestamp: [1591220628916064]
variable_Event-Calling-File: [sofia.c]
variable_Event-Calling-Function: [sofia_handle_sip_i_invite]
variable_Event-Calling-Line-Number: [10541]
variable_Event-Sequence: [1347]
variable_sip_number_alias: [2000]
variable_sip_auth_username: [2000]
variable_sip_auth_realm: [
sip.vocinity.com]
variable_number_alias: [2000]
variable_requested_user_name: [2000]
variable_requested_domain_name: [
sip.vocinity.com]
variable_user_name: [2000]
variable_domain_name: [
sip.vocinity.com]
variable_sip_from_user_stripped: [2000]
variable_sip_from_tag: [2dtu94frdc]
variable_sofia_profile_name: [external]
variable_sofia_profile_url: [
sip:mod_sofia@204.89.189.50:5060]
variable_recovery_profile_name: [external]
variable_sip_full_via: [SIP/2.0/WSS jos34dedpjg3.invalid;branch=z9hG4bK8396701;received=50.210.153.253;rport=33392]
variable_sip_from_display: [Nathan]
variable_sip_full_from: ["Nathan" <
sip:2000@sip.vocinity.com>;tag=2dtu94frdc]
variable_sip_full_to: [<
sip:1000@sip.vocinity.com>]
variable_sip_allow: [INVITE, ACK, CANCEL, BYE, UPDATE, MESSAGE, OPTIONS, REFER, INFO, NOTIFY]
variable_sip_req_user: [1000]
variable_sip_req_uri: [
1000@sip.vocinity.com]
variable_sip_req_host: [
sip.vocinity.com]
variable_sip_to_user: [1000]
variable_sip_to_uri: [
1000@sip.vocinity.com]
variable_sip_to_host: [
sip.vocinity.com]
variable_sip_contact_params: [transport=ws;ob]
variable_sip_contact_user: [cb60h33n]
variable_sip_contact_uri: [cb60h33n@jos34dedpjg3.invalid]
variable_sip_contact_host: [jos34dedpjg3.invalid]
variable_rtp_use_codec_string: [VP8,OPUS]
variable_sip_user_agent: [JsSIP 3.3.11]
variable_sip_via_host: [jos34dedpjg3.invalid]
variable_sip_via_rport: [33392]
variable_max_forwards: [69]
variable_sip_nat_detected: [true]
variable_switch_r_sdp: [v=0
o=- 4361465521890668688 2 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=msid-semantic: WMS OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
m=audio 41704 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 50.210.153.253
a=rtpmap:111 opus/48000/2
a=fmtp:111 minptime=10;useinbandfec=1
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:110 telephone-event/48000
a=rtpmap:112 telephone-event/32000
a=rtpmap:113 telephone-event/16000
a=rtpmap:126 telephone-event/8000
a=rtcp:58455 IN IP4 50.210.153.253
a=candidate:1978755251 1 udp 2122260223 10.88.65.80 41704 typ host generation 0 network-id 1 network-cost 10
a=candidate:1978755251 2 udp 2122260222 10.88.65.80 58455 typ host generation 0 network-id 1 network-cost 10
a=candidate:3702450022 2 udp 1686052606 50.210.153.253 58455 typ srflx raddr 10.88.65.80 rport 58455 generation 0 network-id 1 network-cost 10
a=candidate:3702450022 1 udp 1686052607 50.210.153.253 41704 typ srflx raddr 10.88.65.80 rport 41704 generation 0 network-id 1 network-cost 10
a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=candidate:997364803 2 tcp 1518280446 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=ice-ufrag:CbXl
a=ice-pwd:3HF4+k0xW10Eoa9QpHIEKh6I
a=ice-options:trickle
a=fingerprint:sha-256 96:34:14:58:BA:22:B6:B8:7B:41:92:D0:6F:70:1C:BA:25:BA:3C:03:4E:47:85:CC:33:FB:DB:EA:98:4E:52:89
a=setup:actpass
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-timea=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 6bb8fc4b-068d-4461-9819-c28dfea86bf8
a=rtcp-mux
a=rtcp-fb:111 transport-cc
a=ssrc:1537550124 cname:H+vuxJdK27w4PqCr
a=ssrc:1537550124 msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 6bb8fc4b-068d-4461-9819-c28dfea86bf8
a=ssrc:1537550124 mslabel:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
a=ssrc:1537550124 label:6bb8fc4b-068d-4461-9819-c28dfea86bf8
m=video 57009 UDP/TLS/RTP/SAVPF 96 97 98 99 100 101 102 122 127 121 125 107 108 109 124 120 123
c=IN IP4 50.210.153.253
a=rtpmap:96 VP8/90000
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:98 VP9/90000
a=fmtp:98 profile-id=0
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:100 VP9/90000
a=fmtp:100 profile-id=2
a=rtpmap:101 rtx/90000
a=fmtp:101 apt=100
a=rtpmap:102 H264/90000
a=fmtp:102 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f
a=rtpmap:122 rtx/90000
a=fmtp:122 apt=102
a=rtpmap:127 H264/90000
a=fmtp:127 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f
a=rtpmap:121 rtx/90000
a=fmtp:121 apt=127
a=rtpmap:125 H264/90000
a=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:108 H264/90000
a=fmtp:108 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f
a=rtpmap:109 rtx/90000
a=fmtp:109 apt=108
a=rtpmap:124 red/90000
a=rtpmap:120 rtx/90000
a=fmtp:120 apt=124
a=rtpmap:123 ulpfec/90000
a=rtcp:33483 IN IP4 50.210.153.253
a=candidate:1978755251 1 udp 2122260223 10.88.65.80 57009 typ host generation 0 network-id 1 network-cost 10
a=candidate:1978755251 2 udp 2122260222 10.88.65.80 33483 typ host generation 0 network-id 1 network-cost 10
a=candidate:3702450022 1 udp 1686052607 50.210.153.253 57009 typ srflx raddr 10.88.65.80 rport 57009 generation 0 network-id 1 network-cost 10
a=candidate:3702450022 2 udp 1686052606 50.210.153.253 33483 typ srflx raddr 10.88.65.80 rport 33483 generation 0 network-id 1 network-cost 10
a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=candidate:997364803 2 tcp 1518280446 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10
a=ice-ufrag:CbXl
a=ice-pwd:3HF4+k0xW10Eoa9QpHIEKh6I
a=ice-options:trickle
a=fingerprint:sha-256 96:34:14:58:BA:22:B6:B8:7B:41:92:D0:6F:70:1C:BA:25:BA:3C:03:4E:47:85:CC:33:FB:DB:EA:98:4E:52:89
a=setup:actpass
a=mid:1
a=extmap:14 urn:ietf:params:rtp-hdrext:toffset
a=extmap:2
http://www.webrtc.org/experiments/rtp-hdrext/abs-send-timea=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01a=extmap:12
http://www.webrtc.org/experiments/rtp-hdrext/playout-delaya=extmap:11
http://www.webrtc.org/experiments/rtp-hdrext/video-content-typea=extmap:7
http://www.webrtc.org/experiments/rtp-hdrext/video-timinga=extmap:8
http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07a=extmap:9
http://www.webrtc.org/experiments/rtp-hdrext/color-spacea=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 85c1ee18-b111-4183-8949-af3e72cbd1fe
a=rtcp-mux
a=rtcp-rsize
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=rtcp-fb:98 goog-remb
a=rtcp-fb:98 transport-cc
a=rtcp-fb:98 ccm fir
a=rtcp-fb:98 nack
a=rtcp-fb:98 nack pli
a=rtcp-fb:100 goog-remb
a=rtcp-fb:100 transport-cc
a=rtcp-fb:100 ccm fir
a=rtcp-fb:100 nack
a=rtcp-fb:100 nack pli
a=rtcp-fb:102 goog-remb
a=rtcp-fb:102 transport-cc
a=rtcp-fb:102 ccm fir
a=rtcp-fb:102 nack
a=rtcp-fb:102 nack pli
a=rtcp-fb:127 goog-remb
a=rtcp-fb:127 transport-cc
a=rtcp-fb:127 ccm fir
a=rtcp-fb:127 nack
a=rtcp-fb:127 nack pli
a=rtcp-fb:125 goog-remb
a=rtcp-fb:125 transport-cc
a=rtcp-fb:125 ccm fir
a=rtcp-fb:125 nack
a=rtcp-fb:125 nack pli
a=rtcp-fb:108 goog-remb
a=rtcp-fb:108 transport-cc
a=rtcp-fb:108 ccm fir
a=rtcp-fb:108 nack
a=rtcp-fb:108 nack pli
a=ssrc-group:FID 2988188772 2811984399
a=ssrc:2988188772 cname:H+vuxJdK27w4PqCr
a=ssrc:2988188772 msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 85c1ee18-b111-4183-8949-af3e72cbd1fe
a=ssrc:2988188772 mslabel:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
a=ssrc:2988188772 label:85c1ee18-b111-4183-8949-af3e72cbd1fe
a=ssrc:2811984399 cname:H+vuxJdK27w4PqCr
a=ssrc:2811984399 msid:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe 85c1ee18-b111-4183-8949-af3e72cbd1fe
a=ssrc:2811984399 mslabel:OcWbCdxejuLBVflMqvjtAt1wAXcFmeaI3Yxe
a=ssrc:2811984399 label:85c1ee18-b111-4183-8949-af3e72cbd1fe
]
variable_ep_codec_string: [mod_opus.opus@48000h@20i@2c,CORE_VPX_MODULE.VP8]
variable_endpoint_disposition: [DELAYED NEGOTIATION]
variable_DP_MATCH: [ARRAY::1000|:1000]
variable_call_uuid: [f57d5c39-9c82-4139-8c94-4b34f48acef0]
variable_current_application: [info]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com export(dialed_extension=1000)
2020-06-03 17:43:48.916064 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [dialed_extension]=[1000]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com export(nolocal:absolute_codec_string=OPUS,VP8)
2020-06-03 17:43:48.916064 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) (REMOTE ONLY) [absolute_codec_string]=[OPUS,VP8]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com set(hangup_after_bridge=true)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000@sip.vocinity.com [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com set(call_timeout=20)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000@sip.vocinity.com [call_timeout]=[20]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com set(bypass_media=true)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000@sip.vocinity.com [bypass_media]=[true]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com set(record_concat_video=true)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000@sip.vocinity.com [record_concat_video]=[true]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com set(enable_file_write_buffering=false)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000@sip.vocinity.com [enable_file_write_buffering]=[false]
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com answer()
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:111:48000:20:0:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:105:16000:20:0:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [CN:13:8000:20:0:1]/[opus:116:48000:20:0:1]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 110@48000
2020-06-03 17:43:48.916064 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps]
2020-06-03 17:43:48.916064 [DEBUG] mod_opus.c:613 Opus encoder: set bitrate to local settings [72000bps]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:3837 Set Codec sofia/external/
2000@sip.vocinity.com opus/48000 20 ms 960 samples 0 bits 1 channels
2020-06-03 17:43:48.916064 [DEBUG] switch_core_codec.c:111 sofia/external/
2000@sip.vocinity.com Original read codec set to opus:116
2020-06-03 17:43:48.916064 [WARNING] switch_core_media.c:4252 NO candidate ACL defined, Defaulting to wan.auto
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: host addr:
10.88.65.80:417042020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 2 proto: udp type: host addr:
10.88.65.80:584552020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 2 proto: udp type: srflx addr:
50.210.153.253:584552020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save audio Candidate cid: 1 proto: udp type: srflx addr:
50.210.153.253:417042020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4327 Searching for rtp candidate.
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4336 Choose rtp candidate, index 1,
50.210.153.253:417042020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4102 sofia/external/
2000@sip.vocinity.com choosing family v4
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4347 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute
50.210.153.253:417042020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4399 setting remote audio ice addr to index 1
50.210.153.253:41704 based on candidate
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4434 Setting remote rtcp audio addr to
50.210.153.253:41704 based on candidate
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 110@48000
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5909 sofia/external/
2000@sip.vocinity.com Set 2833 dtmf send payload to 110 recv payload to 110
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [VP8:96]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6226 Video Codec Compare [VP8:96] +++ is saved as a match
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:97]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [VP9:98]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:99]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [VP9:100]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:101]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [H264:102]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:122]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [H264:127]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:121]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [H264:125]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:107]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [H264:108]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:109]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [red:124]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [rtx:120]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:6194 Video Codec Compare [ulpfec:123]/[VP8:99]
2020-06-03 17:43:48.916064 [DEBUG] switch_vpx.c:703 VPX VER:v1.8.1 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:8
2020-06-03 17:43:48.916064 [DEBUG] switch_vpx.c:703 VPX VER:v1.8.1 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:8
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:3647 Set VIDEO Codec sofia/external/
2000@sip.vocinity.com VP8/90000 0 ms
2020-06-03 17:43:48.916064 [WARNING] switch_core_media.c:4252 NO candidate ACL defined, Defaulting to wan.auto
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save video Candidate cid: 1 proto: udp type: host addr:
10.88.65.80:570092020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save video Candidate cid: 2 proto: udp type: host addr:
10.88.65.80:334832020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save video Candidate cid: 1 proto: udp type: srflx addr:
50.210.153.253:570092020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4282 Save video Candidate cid: 2 proto: udp type: srflx addr:
50.210.153.253:334832020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4327 Searching for rtp candidate.
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4336 Choose rtp candidate, index 1,
50.210.153.253:570092020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4102 sofia/external/
2000@sip.vocinity.com choosing family v4
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4347 Choose same candidate, index 2, for rtcp based on rtcp-mux attribute
50.210.153.253:570092020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4399 setting remote video ice addr to index 1
50.210.153.253:57009 based on candidate
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4434 Setting remote rtcp video addr to
50.210.153.253:57009 based on candidate
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/external/
2000@sip.vocinity.com] 204.89.189.50 port 31674 -> 50.210.153.253 port 41704 codec: 111 ms: 20
2020-06-03 17:43:48.916064 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 960 bytes per 20ms
2020-06-03 17:43:48.916064 [INFO] switch_core_media.c:8843 Activating Audio ICE
2020-06-03 17:43:48.916064 [NOTICE] switch_rtp.c:4915 Activating RTP audio ICE: CbXl:U5MYK20Qqyc82ZOo
50.210.153.253:417042020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:8883 Activating RTCP PORT 41704
2020-06-03 17:43:48.916064 [DEBUG] switch_rtp.c:4811 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 41704
2020-06-03 17:43:48.916064 [INFO] switch_core_media.c:8894 Skipping RTCP ICE (Same as RTP)
2020-06-03 17:43:48.916064 [INFO] switch_rtp.c:3741 Activate RTP/RTCP audio DTLS client
2020-06-03 17:43:48.916064 [INFO] switch_rtp.c:3908 Changing audio DTLS state from OFF to HANDSHAKE
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:8975 sofia/external/
2000@sip.vocinity.com Set 2833 dtmf send payload to 110
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:8982 sofia/external/
2000@sip.vocinity.com Set 2833 dtmf receive payload to 110
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:9005 sofia/external/
2000@sip.vocinity.com Set rtp dtmf delay to 40
2020-06-03 17:43:48.916064 [DEBUG] switch_rtp.c:4428 Starting video timer.
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:9493 VIDEO RTP [sofia/external/
2000@sip.vocinity.com] 204.89.189.50:25172->
50.210.153.253:57009 codec: 96 ms: 0 [SUCCESS]
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:7628 sofia/external/
2000@sip.vocinity.com Starting Video thread
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:7494 sofia/external/
2000@sip.vocinity.com Video thread started. Echo is off
2020-06-03 17:43:48.916064 [INFO] switch_core_media.c:9537 Activating Video ICE
2020-06-03 17:43:48.916064 [NOTICE] switch_rtp.c:4915 Activating RTP video ICE: CbXl:I30diMDiE7MKVu9K
50.210.153.253:570092020-06-03 17:43:48.916064 [INFO] switch_core_media.c:9574 Activating VIDEO RTCP PORT 57009 interval 1000 mux 1
2020-06-03 17:43:48.916064 [DEBUG] switch_rtp.c:4811 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 57009
2020-06-03 17:43:48.916064 [INFO] switch_core_media.c:9587 Skipping VIDEO RTCP ICE (Same as VIDEO RTP)
2020-06-03 17:43:48.916064 [INFO] switch_rtp.c:3741 Activate RTP/RTCP video DTLS client
2020-06-03 17:43:48.936038 [INFO] switch_rtp.c:3908 Changing video DTLS state from OFF to HANDSHAKE
2020-06-03 17:43:48.936038 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/
2000@sip.vocinity.com!
2020-06-03 17:43:48.936038 [DEBUG] switch_channel.c:3565 (sofia/external/
2000@sip.vocinity.com) Callstate Change RINGING -> EARLY
2020-06-03 17:43:48.936038 [DEBUG] switch_core_media.c:8643 Audio params are unchanged for sofia/external/
2000@sip.vocinity.com.
2020-06-03 17:43:48.936038 [DEBUG] switch_core_media.c:9357 Video params are unchanged for sofia/external/
2000@sip.vocinity.com.
2020-06-03 17:43:48.936038 [DEBUG] mod_sofia.c:898 Local SDP sofia/external/
2000@sip.vocinity.com:
v=0
o=FreeSWITCH 1591188954 1591188955 IN IP4 204.89.189.50
s=FreeSWITCH
c=IN IP4 204.89.189.50
t=0 0
a=msid-semantic: WMS 61vceH5KXGIwoVpwfOlmePpcgxHvSEWP
m=audio 31674 UDP/TLS/RTP/SAVPF 111 110
a=rtpmap:111 opus/48000/2
a=fmtp:111 useinbandfec=1; minptime=10
a=rtpmap:110 telephone-event/48000
a=ptime:20
a=sendrecv
a=fingerprint:sha-256 29:ED:E1:76:E1:39:35:5E:1D:A5:BE:8F:F3:84:E1:CE:1C:35:B7:F1:AA:B4:A1:32:F8:2F:E5:C3:27:45:17:0B
a=setup:active
a=rtcp-mux
a=rtcp:31674 IN IP4 204.89.189.50
a=ice-ufrag:U5MYK20Qqyc82ZOo
a=ice-pwd:7wPN9Qhjq6ILcscWnPLYHCFY
a=candidate:4547453843 1 udp 659136 204.89.189.50 31674 typ host generation 0
a=end-of-candidates
a=ssrc:458504716 cname:wiSAPNbDkKZyvfUA
a=ssrc:458504716 msid:61vceH5KXGIwoVpwfOlmePpcgxHvSEWP a0
a=ssrc:458504716 mslabel:61vceH5KXGIwoVpwfOlmePpcgxHvSEWP
a=ssrc:458504716 label:61vceH5KXGIwoVpwfOlmePpcgxHvSEWPa0
m=video 25172 UDP/TLS/RTP/SAVPF 96
b=AS:2048
a=rtpmap:96 VP8/90000
a=sendrecv
a=fingerprint:sha-256 29:ED:E1:76:E1:39:35:5E:1D:A5:BE:8F:F3:84:E1:CE:1C:35:B7:F1:AA:B4:A1:32:F8:2F:E5:C3:27:45:17:0B
a=setup:active
a=rtcp-mux
a=rtcp:25172 IN IP4 204.89.189.50
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 nack pli
a=ssrc:3957883082 cname:wiSAPNbDkKZyvfUA
a=ssrc:3957883082 msid:61vceH5KXGIwoVpwfOlmePpcgxHvSEWP v0
a=ssrc:3957883082 mslabel:61vceH5KXGIwoVpwfOlmePpcgxHvSEWP
a=ssrc:3957883082 label:61vceH5KXGIwoVpwfOlmePpcgxHvSEWPv0
a=ice-ufrag:I30diMDiE7MKVu9K
a=ice-pwd:ALJgaBw2dFw2dVzLPpPY2j6w
a=candidate:2237072367 1 udp 659136 204.89.189.50 25172 typ host generation 0
a=end-of-candidates
2020-06-03 17:43:48.936038 [DEBUG] sofia.c:7325 Channel sofia/external/
2000@sip.vocinity.com entering state [completed][200]
2020-06-03 17:43:48.956044 [DEBUG] sofia.c:7325 Channel sofia/external/
2000@sip.vocinity.com entering state [ready][200]
2020-06-03 17:43:49.016061 [INFO] switch_rtp.c:3278 Changing audio DTLS state from HANDSHAKE to SETUP
2020-06-03 17:43:49.036093 [INFO] switch_rtp.c:3185 audio Fingerprint Verified.
2020-06-03 17:43:49.036093 [INFO] switch_rtp.c:4217 Activating audio Secure RTP SEND
2020-06-03 17:43:49.036093 [INFO] switch_rtp.c:4195 Activating audio Secure RTP RECV
2020-06-03 17:43:49.036093 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
2020-06-03 17:43:49.036093 [INFO] switch_rtp.c:3227 Changing audio DTLS state from SETUP to READY
2020-06-03 17:43:49.036093 [DEBUG] switch_core_sqldb.c:2836 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
2020-06-03 17:43:49.036093 [NOTICE] mod_dptools.c:1406 Channel [sofia/external/
2000@sip.vocinity.com] has been answered
2020-06-03 17:43:49.036093 [DEBUG] switch_channel.c:3865 (sofia/external/
2000@sip.vocinity.com) Callstate Change EARLY -> ACTIVE
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com record_session(/recordings/20200603174349_2000_1000.mp4)
2020-06-03 17:43:49.036093 [DEBUG] mod_mp4v2.c:357 sample rate: 44100, channels: 1
2020-06-03 17:43:49.036093 [INFO] mod_mp4v2.c:402 Opening File [/tmp/f0126da6-871b-4924-b105-ea29c426db9a.mp4] 44100hz with VIDEO
2020-06-03 17:43:49.036093 [WARNING] switch_core_codec.c:727 Codec PCMU Exists but not at the desired implementation. 44100hz 20ms 1ch
2020-06-03 17:43:49.036093 [ERR] mod_mp4v2.c:415 Audio Codec Activation Fail
2020-06-03 17:43:49.036093 [WARNING] switch_core_file.c:359 Spool dir is set. Make sure [/tmp/f0126da6-871b-4924-b105-ea29c426db9a.mp4] is also a valid path
2020-06-03 17:43:49.036093 [ERR] switch_ivr_async.c:2818 Error opening /recordings/20200603174349_2000_1000.mp4
EXECUTE [depth=0] sofia/external/
2000@sip.vocinity.com bridge(user/
1000@sip.vocinity.com)
