[Freeswitch-users] Record a WebRTC call
David Villasmil
david.villasmil.work at gmail.com
Sun Jun 7 23:38:09 UTC 2020
there’s a reason freeswitch documentation clearly states it should be used
on Debian
;)
On Mon, 8 Jun 2020 at 00:07, Nathan Stratton <nathan at robotics.net> wrote:
> 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
>
> _________________________________________________________________________
>
> 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
--
Regards,
David Villasmil
email: david.villasmil.work at gmail.com
phone: +34669448337
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20200608/a907433c/attachment-0001.html>
More information about the FreeSWITCH-users
mailing list