[Freeswitch-users] Record a WebRTC call

Sat Jun 6 11:57:00 UTC 2020

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>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20200606/a71e4725/attachment-0001.html>