[Freeswitch-users] Record a WebRTC call

Sun Jun 7 22:36:13 UTC 2020

Yes, Debian does work, because it has mod_av and Centos does not.

><>
nathan stratton

On Sat, Jun 6, 2020 at 7:58 AM Brian West <brian at freeswitch.com> wrote:

> Use Debian!
>
> On Wed, Jun 3, 2020 at 17:38 Nathan Stratton <nathan at robotics.net> wrote:
>
>> 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
>>
>> _________________________________________________________________________
>>
>> 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/20200607/9a3a3fbe/attachment-0001.html>