[Freeswitch-users] Record a WebRTC call

Nathan Stratton nathan at robotics.net
Wed Jun 3 21:45:47 UTC 2020


Great question, I tried OPUS and PCMU and get the same result. I am running
FreeSWITCH 10.0.3 on Centos 8.


2020-06-03 17:43:48.876061 [NOTICE] switch_channel.c:1118 New Channel
sofia/external/2000 at 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 at 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 at 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 at sip.vocinity.com]
2020-06-03 17:43:48.876061 [DEBUG] switch_core_state_machine.c:604
(sofia/external/2000 at 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 at 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 at 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-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=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-time
a=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
a=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07
a=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space
a=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 at 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 at 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 at sip.vocinity.com) State INIT
2020-06-03 17:43:48.916064 [DEBUG] mod_sofia.c:93 sofia/external/
2000 at sip.vocinity.com SOFIA INIT
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:40
sofia/external/2000 at sip.vocinity.com Standard INIT
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:48
(sofia/external/2000 at 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 at 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 at 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 at sip.vocinity.com) Callstate Change DOWN -> RINGING
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:644
(sofia/external/2000 at sip.vocinity.com) State ROUTING
2020-06-03 17:43:48.916064 [DEBUG] mod_sofia.c:154 sofia/external/
2000 at sip.vocinity.com SOFIA ROUTING
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:236
sofia/external/2000 at 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 at sip.vocinity.com parsing [default->global]
continue=true
Dialplan: sofia/external/2000 at sip.vocinity.com Absolute Condition [global]
Dialplan: sofia/external/2000 at sip.vocinity.com Action info()
Dialplan: sofia/external/2000 at sip.vocinity.com parsing
[default->Local_Users] continue=false
Dialplan: sofia/external/2000 at sip.vocinity.com Regex (PASS) [Local_Users]
destination_number(1000) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/external/2000 at sip.vocinity.com Action
export(dialed_extension=1000)
Dialplan: sofia/external/2000 at sip.vocinity.com Action
export(nolocal:absolute_codec_string=OPUS,VP8)
Dialplan: sofia/external/2000 at sip.vocinity.com Action
set(hangup_after_bridge=true)
Dialplan: sofia/external/2000 at sip.vocinity.com Action set(call_timeout=20)
Dialplan: sofia/external/2000 at sip.vocinity.com Action
set(bypass_media=true)
Dialplan: sofia/external/2000 at sip.vocinity.com Action
set(record_concat_video=true)
Dialplan: sofia/external/2000 at sip.vocinity.com Action
set(enable_file_write_buffering=false)
Dialplan: sofia/external/2000 at sip.vocinity.com Action answer()
Dialplan: sofia/external/2000 at sip.vocinity.com Action
record_session(/recordings/${strftime(%Y%m%d%H%M%S)}_${caller_id_number}_${destination_number}.mp4)

Dialplan: sofia/external/2000 at 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 at 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 at 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 at 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 at sip.vocinity.com) State EXECUTE
2020-06-03 17:43:48.916064 [DEBUG] mod_sofia.c:209 sofia/external/
2000 at sip.vocinity.com SOFIA EXECUTE
2020-06-03 17:43:48.916064 [DEBUG] switch_core_state_machine.c:329
sofia/external/2000 at sip.vocinity.com Standard EXECUTE
EXECUTE [depth=0] sofia/external/2000 at 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 at 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 at 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 at 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 at 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 at 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 at sip.vocinity.com
>;tag=2dtu94frdc]
variable_sip_full_to: [<sip:1000 at 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 at sip.vocinity.com]
variable_sip_req_host: [sip.vocinity.com]
variable_sip_to_user: [1000]
variable_sip_to_uri: [1000 at 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 at 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-time
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=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-time
a=extmap:13 urn:3gpp:video-orientation
a=extmap:3
http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay
a=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type
a=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing
a=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07
a=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space
a=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 at 48000h@20i at 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 at 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 at 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 at sip.vocinity.com
set(hangup_after_bridge=true)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000 at sip.vocinity.com [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/external/2000 at sip.vocinity.com set(call_timeout=20)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000 at sip.vocinity.com [call_timeout]=[20]
EXECUTE [depth=0] sofia/external/2000 at sip.vocinity.com
set(bypass_media=true)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000 at sip.vocinity.com [bypass_media]=[true]
EXECUTE [depth=0] sofia/external/2000 at sip.vocinity.com
set(record_concat_video=true)
2020-06-03 17:43:48.916064 [DEBUG] mod_dptools.c:1672 SET sofia/external/
2000 at sip.vocinity.com [record_concat_video]=[true]
EXECUTE [depth=0] sofia/external/2000 at 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 at sip.vocinity.com [enable_file_write_buffering]=[false]
EXECUTE [depth=0] sofia/external/2000 at 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 at 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 at 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 at 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:41704
2020-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:58455
2020-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:58455
2020-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:41704
2020-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:41704
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4102 sofia/external/
2000 at 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:41704
2020-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 at 48000
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:5909 sofia/external/
2000 at 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 at 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:57009
2020-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:33483
2020-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:57009
2020-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:33483
2020-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:57009
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:4102 sofia/external/
2000 at 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:57009
2020-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 at 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:41704
2020-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 at 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 at 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 at 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 at 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 at sip.vocinity.com Starting Video thread
2020-06-03 17:43:48.916064 [DEBUG] switch_core_media.c:7494 sofia/external/
2000 at 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:57009
2020-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 at sip.vocinity.com!
2020-06-03 17:43:48.936038 [DEBUG] switch_channel.c:3565 (sofia/external/
2000 at 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 at sip.vocinity.com.
2020-06-03 17:43:48.936038 [DEBUG] switch_core_media.c:9357 Video params
are unchanged for sofia/external/2000 at sip.vocinity.com.
2020-06-03 17:43:48.936038 [DEBUG] mod_sofia.c:898 Local SDP sofia/external/
2000 at 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 at sip.vocinity.com entering state [completed][200]
2020-06-03 17:43:48.956044 [DEBUG] sofia.c:7325 Channel sofia/external/
2000 at 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 at sip.vocinity.com] has been answered
2020-06-03 17:43:49.036093 [DEBUG] switch_channel.c:3865 (sofia/external/
2000 at sip.vocinity.com) Callstate Change EARLY -> ACTIVE
EXECUTE [depth=0] sofia/external/2000 at 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 at sip.vocinity.com bridge(user/
1000 at sip.vocinity.com)

><>
nathan stratton


On Wed, Jun 3, 2020 at 4:07 PM Brian West <brian at freeswitch.com> wrote:

> Need the full debug log, there is no PCMU at 44.1khz so how did
> that happen?
>
> On Wed, Jun 3, 2020 at 2:22 PM Nathan Stratton <nathan at robotics.net>
> wrote:
>
>>
>> I have tried mod_mp4 and mod_mp4v2, but give the same error:
>>
>> 2020-06-03 15:03:50.265573 [NOTICE] switch_ivr_originate.c:3794 Channel
>> [sofia/external/2000 at sip.vocinity.com] has been answered
>> EXECUTE [depth=0] sofia/external/2000 at sip.vocinity.com
>> record_session(/recordings/20200603150346_2000.mp4)
>> 2020-06-03 15:03:50.265573 [DEBUG] mod_mp4v2.c:357 sample rate: 44100,
>> channels: 1
>> 2020-06-03 15:03:50.265573 [INFO] mod_mp4v2.c:402 Opening File
>> [/tmp/bde19022-b1fb-405b-9417-34b10994c775.mp4] 44100hz  with VIDEO
>> 2020-06-03 15:03:50.265573 [WARNING] switch_core_codec.c:727 Codec PCMU
>> Exists but not at the desired implementation. 44100hz 20ms 1ch
>> 2020-06-03 15:03:50.265573 [ERR] mod_mp4v2.c:415 Audio Codec Activation
>> Fail
>> 2020-06-03 15:03:50.265573 [WARNING] switch_core_file.c:359 Spool dir is
>> set.  Make sure [/tmp/bde19022-b1fb-405b-9417-34b10994c775.mp4] is also a
>> valid path
>> 2020-06-03 15:03:50.265573 [ERR] switch_ivr_async.c:2818 Error opening
>> /recordings/20200603150346_2000.mp4
>> 2020-06-03 15:03:50.265573 [DEBUG] switch_channel.c:3865 (sofia/external/
>> 2000 at sip.vocinity.com) Callstate Change EARLY -> ACTIVE
>>
>> ><>
>> nathan stratton
>> _________________________________________________________________________
>>
>> The FreeSWITCH project is sponsored by SignalWire https://signalwire.com
>> Enhance your FreeSWITCH install with disruptive priced SMS and PSTN
>> services.
>> Build your next product on our scalable cloud platform.
>>
>> Join our online community to chat in real time
>> https://signalwire.community
>>
>> Professional FreeSWITCH Services
>> sales at freeswitch.com
>> https://freeswitch.com
>>
>> Official FreeSWITCH Sites
>> https://freeswitch.com/oss
>> https://freeswitch.org/confluence
>> https://cluecon.com
>>
>> FreeSWITCH-users mailing list
>> FreeSWITCH-users at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> https://freeswitch.com
>
>
>
> --
>
> Brian West | Co-founder and Developer
>
> Need Commercial support? email sales at freeswitch.com
>
> FreeSWITCH Solutions | 17345 Civic Drive #2531 Brookfield, WI 53045
> <https://maps.google.com/?q=17345+Civic+Drive+%232531+Brookfield,+WI+53045&entry=gmail&source=g>
>
> Email: brian at freeswitch.com
>
> Mobile: 918-424-9378
>
> Website: https://www.FreeSWITCH.com <https://www.freeswitch.com/>
>
> [image: https://www.facebook.com/signalwireinc?src=email]
> <https://www.facebook.com/freeswitch> [image:
> https://twitter.com/freeswitch] <https://twitter.com/freeswitch>
> _________________________________________________________________________
>
> The FreeSWITCH project is sponsored by SignalWire https://signalwire.com
> Enhance your FreeSWITCH install with disruptive priced SMS and PSTN
> services.
> Build your next product on our scalable cloud platform.
>
> Join our online community to chat in real time
> https://signalwire.community
>
> Professional FreeSWITCH Services
> sales at freeswitch.com
> https://freeswitch.com
>
> Official FreeSWITCH Sites
> https://freeswitch.com/oss
> https://freeswitch.org/confluence
> https://cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> https://freeswitch.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20200603/7280b87b/attachment-0001.html>


More information about the FreeSWITCH-users mailing list