Remote video track is sometimes black

patricktemple commented 4 years ago

Hi,

Background We use Twilio Video for a connection between our iOS app and a web frontend (in Chrome). iOS sends out two video tracks, an audio track, and a data track. Web renders the incoming media tracks and sends out a video track (the webcam), an audio track, and a data track.

This might be a bug in the iOS SDK or signaling server, but most of my debugging was done from the JS perspective so I'm filing it here. We use iOS SDK 3.0.0 (with the OS version 13.3.1 for me) and web 2.3.0 (in Chrome 80.0.3987.149 on Mac 10.14.6).

The problem Sometimes, one of the video feeds that our web frontend receives is black. This happens maybe 1/20 of the time and is usually fixed by refreshing the browser.

When this problem does happen, here’s what I’ve noticed from the web's perspective:

The remote participant joins, and all their tracks are subscribed as expected.
The MediaStreamTracks all have readyState live, and they’re attached to the <video> elements as they should be (i.e. the video.srcObject is the track)
On the iOS side, I see from log events that we are pushing frames as we should, using VideoSink.onVideoFrame
Even if I go into chrome://webrtc-internals, everything looks normal. Here are the stats for the track that’s blank:

You can see the bitrate of 200Kbps and a nonzero frame rate. I can even tell that the bitrate is proportional to the complexity of the video it’s streaming. If the source video is nearly a solid color, the bitrate drops dramatically, and if the video has more going on, the bitrate rises. So it seems that Chrome is receiving frames of the real video, but won’t render them.

But I do see one other difference when the video fails to appear: its MediaStreamTrack is muted. Shortly after attaching the track, its unmute event fires, but no video appears and then mute fires a few seconds later. The healthy tracks on the other hand unmute and then stay that way.

(I’m talking about the muted property of the MediaStreamTrack itself and not the <video> element. The <video> is also muted intentionally to work around Chrome’s autoplay policy, but that seems unrelated.)

So it seems that something’s going wrong that’s preventing this track from being playable. The spec tells me the muted field is readonly and indicates that there’s a problem playing the track, though everything from the iOS client and the webrtc-internals look fine to me.

Here’s the room ID for a call that had the problem: RMea05caa1d8a88fcbe97f2382ff5a2719

Since it’s intermittent, I had to refresh the browser a bunch of times to get this to problem to happen during that session. So you should see the following in the logs, in order:

iOS first joins the room
Web joins and disconnects a number of times, successfully
Web joins and experiences this problem (just once, at the end)
Call is ended

I think I can write a workaround for this now. I can probably detect the problem via the muted property and fix it by refreshing the page, or maybe just by leaving the Room and reconnecting. It’d be good to figure out what the real source of the bug is, though.

Thanks for your help, Patrick

VincentSim commented 4 years ago

Hi Patrick,

It looks like this issue https://github.com/twilio/twilio-video.js/issues/927 but you use IOS sdk....

patricktemple commented 4 years ago

I was able to write the workaround, but boy it's a mess and is a best-effort heuristic since other things can also trigger MediaStreamTrack.muted (like if the connection gets really bad).

I see this in both twilio-video-js 2.3.0 as well as 2.0.0. However, when I first tried upgrading to 2.0.0, I ran into https://github.com/twilio/twilio-video.js/issues/857 which you fixed. It had identical user-facing symptoms though something else was happening under the hood. But it meant that once you fixed that but and I finally did upgrade to 2.0.0, I spent some time testing to look for this exact symptom, and I didn't see it back then. It's tough to be sure since it's intermittent, but it suggests that the problem was introduced either server-side or in Chrome since then (early Feb).

Also, one of the times I saw this, I refreshed the page and saw a different error on the next page load. No idea if it's related though; it's the only time I saw it:

log.js:138 2020-04-01 15:18:13.507Z | WARN in [PeerConnectionV2 #1: ee4f94e8-6d22-4e59-847f-51fee7998fc4]: Calling setLocalDescription with an RTCSessionDescription of type "answer" failed with the error "Failed to execute 'setLocalDescription' on 'RTCPeerConnection': Failed to set local answer sdp: ".
log @ log.js:138
warn @ log.js:199
(anonymous) @ peerconnection.js:1072
Promise.catch (async)
_setLocalDescription @ peerconnection.js:1071
(anonymous) @ peerconnection.js:557
Promise.then (async)
_answer @ peerconnection.js:540
(anonymous) @ peerconnection.js:646
Promise.then (async)
_handleGlare @ peerconnection.js:644
_updateDescription @ peerconnection.js:1204
(anonymous) @ peerconnection.js:1467
gotKey @ statemachine.js:156
Promise.then (async)
bracket @ statemachine.js:154
update @ peerconnection.js:1453
(anonymous) @ peerconnectionmanager.js:399
(anonymous) @ peerconnectionmanager.js:394
Promise.then (async)
update @ peerconnectionmanager.js:393
_update @ room.js:421
emit @ events.js:146
handleMessage @ twilioconnectiontransport.js:536
emit @ events.js:146
_handleMessage @ twilioconnection.js:332
(anonymous) @ twilioconnection.js:232
log.js:138 2020-04-01 15:18:13.507Z | WARN in [PeerConnectionV2 #1: ee4f94e8-6d22-4e59-847f-51fee7998fc4]: The SDP was v=0
o=- 1152993190217517475 4 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1 2 video3
a=msid-semantic: WMS
m=audio 55906 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126
c=IN IP4 192.168.2.1
a=rtcp:9 IN IP4 0.0.0.0
a=candidate:90380972 1 udp 2122260223 192.168.2.1 55906 typ host generation 0 network-id 1 network-cost 50
a=candidate:1654867310 1 udp 2122194687 192.168.0.15 52165 typ host generation 0 network-id 2 network-cost 10
a=ice-ufrag:GpzR
a=ice-pwd:OhqYnYT7Z6TMVj9JchQPO6Hx
a=ice-options:trickle
a=fingerprint:sha-256 5B:D6:75:EF:50:CA:FD:85:BB:3C:84:0E:BB:B9:E7:FA:1B:C2:9A:20:69:2E:BD:90:0E:3F:5A:4C:12:E6:1F:13
a=setup:passive
a=mid:0
a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
a=recvonly
a=rtcp-mux
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
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
m=video 9 UDP/TLS/RTP/SAVPF 96 97 99 107 115 98 125 114 116
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:GpzR
a=ice-pwd:OhqYnYT7Z6TMVj9JchQPO6Hx
a=ice-options:trickle
a=fingerprint:sha-256 5B:D6:75:EF:50:CA:FD:85:BB:3C:84:0E:BB:B9:E7:FA:1B:C2:9A:20:69:2E:BD:90:0E:3F:5A:4C:12:E6:1F:13
a=setup:passive
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=sendrecv
a=msid:- 7ebb636f-4a13-4831-9b45-89b6bc93634f
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
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=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:115 rtx/90000
a=fmtp:115 apt=114
a=rtpmap:98 VP9/90000
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=fmtp:98 profile-id=0
a=rtpmap:125 H264/90000
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=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:114 red/90000
a=rtpmap:116 ulpfec/90000
a=ssrc-group:FID 1268573280 622411348
a=ssrc:1268573280 cname:3MmBnuZ0TzJhCS9b
a=ssrc:622411348 cname:3MmBnuZ0TzJhCS9b
m=application 9 DTLS/SCTP 5000
c=IN IP4 0.0.0.0
b=AS:30
a=ice-ufrag:GpzR
a=ice-pwd:OhqYnYT7Z6TMVj9JchQPO6Hx
a=ice-options:trickle
a=fingerprint:sha-256 5B:D6:75:EF:50:CA:FD:85:BB:3C:84:0E:BB:B9:E7:FA:1B:C2:9A:20:69:2E:BD:90:0E:3F:5A:4C:12:E6:1F:13
a=setup:passive
a=mid:2
a=sctpmap:5000 webrtc-datachannel 1024
m=video 9 UDP/TLS/RTP/SAVPF 96 97 99 107 115 98 125 114 116
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:GpzR
a=ice-pwd:OhqYnYT7Z6TMVj9JchQPO6Hx
a=ice-options:trickle
a=fingerprint:sha-256 5B:D6:75:EF:50:CA:FD:85:BB:3C:84:0E:BB:B9:E7:FA:1B:C2:9A:20:69:2E:BD:90:0E:3F:5A:4C:12:E6:1F:13
a=setup:passive
a=mid:video3
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=recvonly
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
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=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:99 rtx/90000
a=fmtp:99 apt=98
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=125
a=rtpmap:115 rtx/90000
a=fmtp:115 apt=114
a=rtpmap:98 VP9/90000
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=fmtp:98 profile-id=0
a=rtpmap:125 H264/90000
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=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:114 red/90000
a=rtpmap:116 ulpfec/90000

log @ log.js:138
warn @ log.js:199
(anonymous) @ peerconnection.js:1074
Promise.catch (async)
_setLocalDescription @ peerconnection.js:1071
(anonymous) @ peerconnection.js:557
Promise.then (async)
_answer @ peerconnection.js:540
(anonymous) @ peerconnection.js:646
Promise.then (async)
_handleGlare @ peerconnection.js:644
_updateDescription @ peerconnection.js:1204
(anonymous) @ peerconnection.js:1467
gotKey @ statemachine.js:156
Promise.then (async)
bracket @ statemachine.js:154
update @ peerconnection.js:1453
(anonymous) @ peerconnectionmanager.js:399
(anonymous) @ peerconnectionmanager.js:394
Promise.then (async)
update @ peerconnectionmanager.js:393
_update @ room.js:421
emit @ events.js:146
handleMessage @ twilioconnectiontransport.js:536
emit @ events.js:146
_handleMessage @ twilioconnection.js:332
(anonymous) @ twilioconnection.js:232
twilioerror.js:35 Uncaught (in promise) TwilioError: Client is unable to create or apply a local media description
    at MediaClientLocalDescFailedError.TwilioError (http://localhost:4000/-/telemed/static/vendors~main.js:293215:23)
    at new MediaClientLocalDescFailedError (http://localhost:4000/-/telemed/static/vendors~main.js:292976:147)
    at http://localhost:4000/-/telemed/static/vendors~main.js:280745:75
    at async Promise.all (index 1)
    at async Promise.all (index 0)

mding5692 commented 4 years ago

Hi, our company (not Twilio, we're a Twilio Video customer) had the same issue before as well. I think this is related to how Twilio Video API handles the browsers' autoplay policies that don't allow video media to load or play if they have sound. I think we fixed it by having a button that the user pressed everytime to connect to the videocall so it declares user intention to autoplay a video as we don't see anymore customers reporting the issue to us in chat support whereas before we would have hundreds of customers a day reporting this issue.

Not sure if this can help but providing my two cents if you are in a rush.

See: https://developers.google.com/web/updates/2017/09/autoplay-policy-changes https://webkit.org/blog/7734/auto-play-policy-changes-for-macos/ https://hacks.mozilla.org/2019/02/firefox-66-to-block-automatically-playing-audible-video-and-audio/

Should be mentioned in their docs or their quickstart guides that at least that you need some kind of join room button.

patricktemple commented 4 years ago

Hey Michael,

Thanks for the pointer. We've had our share of struggles with the autoplay policy in the past. One such issue had similar symptoms, where we saw two black videos on the screen because autoplay was blocking it. In that case, we solved the issue by adding muted to the video elements.

I think this issue is not related to autoplay, though. We've kept muted on the video elements, but it's the muted property on the MediaStreamTrack that's signaling a problem with the video content. Normally those should be false even if the element has muted=true.

makarandp0 commented 4 years ago

Hello @patricktemple -

Thank you for writing about this issue. I looked at the room sid (RMea05caa1d8a88fcbe97f2382ff5a2719) you provided. Unfortunately server logs do not give much clues on why chrome failed to render the track in this case. This being a peer-to-peer room we have very limited visibility into the media traffic. I noticed that chrome participant connected 62 times and you mentioned on the last attempt it failed to see the video tracks. From our insights records, I do see media flowing on iOS video track to this participant (PA3fe0ebc3daa261137226c7c3bbd8829c).

Your finding of mediaStream track being in muted state is interesting. Please share more room-sids if you encounter this issue again.

Thanks, Makarand

patricktemple commented 4 years ago

Thanks for looking into this Makarand. I can repro easily if I just program my page to refresh after 5 seconds if neither track is muted, and leave it spinning for 2-30 minutes (it varies widely). Eventually it'll detect the problem and stop when I come back to it.

I tried this on Chrome canary too (v83) and got my hopes up that this was fixed. But after about 20 minutes the issue did finally appear, and then it did much more often in the next 5 minutes. I don't know what to make of the wide variance in frequency.

Sounds like the server logs don't provide much useful info. Is there something else that I could collect next time along with the room SID?

What you see about the iOS video track flowing matches what I saw in webrtc-internals (both video tracks flowed fine there but one was black). Yet something convinces Chrome that the track is broken.

will-ks commented 4 years ago

Hi, I believe I'm seeing exactly the same issue. It's also very inconsistent and is fixed by refreshing.

Here's a room SID where I encountered it: RM88749e0d92233390a49f191f75332c46

The video mediatrack was also muted for me. Also, the track.once('started') callback never fired for the video track, but I could also see from chrome://webrtc-internals that bytes & frames were being received.

Edit:

Here is the debug log from room RM85c032c592b74dc59f8a4dab05d9693f where it also occurred:

log.js:138 2020-05-25 13:39:14.942Z | INFO in [connect #1]: Connecting to a Room
log.js:138 2020-05-25 13:39:14.942Z | DEBUG in [connect #1]: Options: {wsServer: "wss://global.vss.twilio.com/signaling", abortOnIceServersTimeout: false, automaticSubscription: true, dominantSpeaker: false, createLocalTracks: ƒ, …}
log.js:138 2020-05-25 13:39:14.943Z | INFO in [NTSIceServerSource #1]: Created a new NTSIceServerSource
log.js:138 2020-05-25 13:39:14.943Z | DEBUG in [NTSIceServerSource #1]: ECS server: https://ecs.us1.twilio.com
log.js:138 2020-05-25 13:39:14.943Z | INFO in [connect #1]: LocalTracks were not provided, so they will be acquired automatically before connecting to the Room. LocalTracks will be released if connecting to the Room fails or if the Room is disconnected
log.js:138 2020-05-25 13:39:14.971Z | INFO in [createLocalTracks #1]: Call to getUserMedia successful; got MediaStreamTracks: [MediaStreamTrack]
log.js:138 2020-05-25 13:39:14.972Z | DEBUG in [LocalAudioTrack #1: undefined]: Initializing
log.js:138 2020-05-25 13:39:14.973Z | DEBUG in [connect #1]: Creating a new LocalParticipant: LocalParticipantV2 {\_events: {…}, \_eventsCount: 1, \_maxListeners: undefined, …}
log.js:138 2020-05-25 13:39:14.974Z | INFO in [LocalParticipant #1]: Created a new Participant
log.js:138 2020-05-25 13:39:14.975Z | INFO in [LocalParticipant #1]: Added a new LocalAudioTrack: ab5ed93d-d4b3-45e8-af09-187091250aad
log.js:138 2020-05-25 13:39:14.976Z | DEBUG in [LocalParticipant #1]: LocalAudioTrack: LocalAudioTrack {kind: "audio", name: "ab5ed93d-d4b3-45e8-af09-187091250aad", mediaStreamTrack: MediaStreamTrack, id: "ab5ed93d-d4b3-45e8-af09-187091250aad", …}
log.js:138 2020-05-25 13:39:14.976Z | INFO in [connect #1]: Getting ICE servers
log.js:138 2020-05-25 13:39:14.976Z | DEBUG in [connect #1]: Options: {wsServer: "wss://global.vss.twilio.com/signaling", abortOnIceServersTimeout: false, automaticSubscription: true, dominantSpeaker: false, createLocalTracks: ƒ, …}
log.js:138 2020-05-25 13:39:14.976Z | INFO in [NTSIceServerSource #1]: Starting
log.js:138 2020-05-25 13:39:14.977Z | DEBUG in [NTSIceServerSource #1]: Getting ECS configuration
log.js:138 2020-05-25 13:39:14.979Z | DEBUG in [LocalAudioTrack #1: ab5ed93d-d4b3-45e8-af09-187091250aad]: Started
log.js:138 2020-05-25 13:39:15.120Z | INFO in [NTSIceServerSource #1]: Got ICE servers: [{"urls":"turn:global.turn.twilio.com:3478?transport=udp","username":"8eab01d0f76f3cb4f17da6b20bc81d37db14e0dccea9768de1bd6e1c343a44dc","credential":"stJ8+dTL+27DH2pvnnDhd0A9SzbuDGITEfSKBjyyslI="},{"urls":"turns:global.turn.twilio.com:443?transport=tcp","username":"8eab01d0f76f3cb4f17da6b20bc81d37db14e0dccea9768de1bd6e1c343a44dc","credential":"stJ8+dTL+27DH2pvnnDhd0A9SzbuDGITEfSKBjyyslI="}]
log.js:138 2020-05-25 13:39:15.120Z | INFO in [NTSIceServerSource #1]: Getting ICE servers again in 14400 seconds
log.js:138 2020-05-25 13:39:15.121Z | INFO in [connect #1]: Got ICE servers
log.js:138 2020-05-25 13:39:15.121Z | DEBUG in [connect #1]: ICE servers: (2) [{…}, {…}]
log.js:138 2020-05-25 13:39:15.121Z | DEBUG in [connect #1]: Creating a new RoomSignaling
log.js:138 2020-05-25 13:39:15.121Z | DEBUG in [connect #1]: RoomSignaling params: {token: "eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImN0eSI6InR3aW…UyIn0.BzxMSj6Iyj_HHzGOxW07KzSOK4WR2XIeB3ldB3knl-A"}
log.js:138 2020-05-25 13:39:15.138Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Created a new WebSocket: WebSocket {url: "wss://global.vss.twilio.com/signaling", readyState: 0, bufferedAmount: 0, onopen: null, onerror: null, …}
log.js:138 2020-05-25 13:39:15.460Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: WebSocket opened: WebSocket {url: "wss://global.vss.twilio.com/signaling", readyState: 1, bufferedAmount: 0, onopen: null, onerror: null, …}
log.js:138 2020-05-25 13:39:15.461Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"id":"61ac1c99-6fbb-4be2-a886-6ea0e4d54333","timeout":5000,"type":"hello"}
log.js:138 2020-05-25 13:39:15.523Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"negotiatedTimeout":5000,"type":"welcome"}
log.js:138 2020-05-25 13:39:15.524Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"name":null,"participant":{"revision":2,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"}]},"peer_connections":[{"description":{"type":"offer","revision":1,"sdp":"v=0\r\no=- 4627483725877734519 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1\r\na=msid-semantic: WMS\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendrecv\r\na=msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:1440628111 cname:3cmbWEUmTKCxDgMw\r\na=ssrc:1440628111 msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=ssrc:1440628111 mslabel:-\r\na=ssrc:1440628111 label:ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 122 121 107 109 120 119 115 98 100 102 127 125 108 124 123 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:102 H264/90000\r\na=rtcp-fb:102 goog-remb\r\na=rtcp-fb:102 transport-cc\r\na=rtcp-fb:102 ccm fir\r\na=rtcp-fb:102 nack\r\na=rtcp-fb:102 nack pli\r\na=fmtp:102 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42001f\r\na=rtpmap:122 rtx/90000\r\na=fmtp:122 apt=102\r\na=rtpmap:127 H264/90000\r\na=rtcp-fb:127 goog-remb\r\na=rtcp-fb:127 transport-cc\r\na=rtcp-fb:127 ccm fir\r\na=rtcp-fb:127 nack\r\na=rtcp-fb:127 nack pli\r\na=fmtp:127 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42001f\r\na=rtpmap:121 rtx/90000\r\na=fmtp:121 apt=127\r\na=rtpmap:125 H264/90000\r\na=rtcp-fb:125 goog-remb\r\na=rtcp-fb:125 transport-cc\r\na=rtcp-fb:125 ccm fir\r\na=rtcp-fb:125 nack\r\na=rtcp-fb:125 nack pli\r\na=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:107 rtx/90000\r\na=fmtp:107 apt=125\r\na=rtpmap:108 H264/90000\r\na=rtcp-fb:108 goog-remb\r\na=rtcp-fb:108 transport-cc\r\na=rtcp-fb:108 ccm fir\r\na=rtcp-fb:108 nack\r\na=rtcp-fb:108 nack pli\r\na=fmtp:108 level-asymmetry-allowed=1;packetization-mode=0;profile-level-id=42e01f\r\na=rtpmap:109 rtx/90000\r\na=fmtp:109 apt=108\r\na=rtpmap:124 H264/90000\r\na=rtcp-fb:124 goog-remb\r\na=rtcp-fb:124 transport-cc\r\na=rtcp-fb:124 ccm fir\r\na=rtcp-fb:124 nack\r\na=rtcp-fb:124 nack pli\r\na=fmtp:124 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=4d0032\r\na=rtpmap:120 rtx/90000\r\na=fmtp:120 apt=124\r\na=rtpmap:123 H264/90000\r\na=rtcp-fb:123 goog-remb\r\na=rtcp-fb:123 transport-cc\r\na=rtcp-fb:123 ccm fir\r\na=rtcp-fb:123 nack\r\na=rtcp-fb:123 nack pli\r\na=fmtp:123 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=640032\r\na=rtpmap:119 rtx/90000\r\na=fmtp:119 apt=123\r\na=rtpmap:114 red/90000\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:116 ulpfec/90000\r\n"},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"type":"connect","version":2,"ice_servers":"success","publisher":{"name":"twilio-video.js","sdk_version":"2.3.0","user_agent":"Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_4) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/83.0.4103.61 Safari/537.36"},"media_signaling":{"track_priority":{"transports":[{"type":"data-channel"}]},"track_switch_off":{"transports":[{"type":"data-channel"}]}},"subscribe":{"rules":[{"type":"include","all":true}],"revision":1},"format":"unified","token":"eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImN0eSI6InR3aWxpby1mcGE7dj0xIn0.eyJqdGkiOiJTSzIzYTc2OGVjNjRiMGYyNzA5YzZkMDgwZDVhMGI4NmQ2LTE1OTA0MTM5NTQiLCJncmFudHMiOnsidmlkZW8iOnsicm9vbSI6IlJNODVjMDMyYzU5MmI3NGRjNTlmOGE0ZGFiMDVkOTY5M2YifSwiaWRlbnRpdHkiOiJyZXNwb25kZXIifSwiaXNzIjoiU0syM2E3NjhlYzY0YjBmMjcwOWM2ZDA4MGQ1YTBiODZkNiIsImV4cCI6MTU5MDQyMTA4OSwibmJmIjoxNTkwNDEzOTU0LCJzdWIiOiJBQ2M1YzAyYzljYWU1YjMyOTQzNGNkNzI1YzE4OGE5MjUyIn0.BzxMSj6Iyj_HHzGOxW07KzSOK4WR2XIeB3ldB3knl-A"},"type":"msg"}
log.js:138 2020-05-25 13:39:15.880Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"connected","sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"}],"revision":2,"state":"connected"},"participants":[{"sid":"PA503e086e94c6b862543f22c4212c9e24","identity":"caller","tracks":[{"kind":"video","priority":"standard","id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","enabled":true,"sid":"MT9d1b60745f15f53061ab2cc24b8b9460","name":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","state":"ready"},{"kind":"audio","priority":"standard","id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","enabled":true,"sid":"MT6bfc36428a39a009ce0c8832a908a25d","name":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribed":{"revision":32,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":2,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"}]},"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","options":{"signaling_region":"ie1","session_timeout":30,"signaling_edge":"dublin"}},"type":"msg"}
log.js:138 2020-05-25 13:39:15.881Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Updated ICE reconnection timeout period: 30000
log.js:138 2020-05-25 13:39:15.884Z | INFO in [LocalParticipant #1]: Added a new AudioTrackPublication: MT748e5edf85b1aff7b12d11741a2ab01a
log.js:138 2020-05-25 13:39:15.884Z | DEBUG in [LocalParticipant #1]: AudioTrackPublication: LocalAudioTrackPublication {trackName: "ab5ed93d-d4b3-45e8-af09-187091250aad", trackSid: "MT748e5edf85b1aff7b12d11741a2ab01a", kind: "audio", …}
log.js:138 2020-05-25 13:39:15.884Z | DEBUG in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: Transitioned to state: connected
log.js:138 2020-05-25 13:39:15.885Z | DEBUG in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: Transitioned to state: connected
log.js:138 2020-05-25 13:39:15.885Z | INFO in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: reconnected
log.js:138 2020-05-25 13:39:15.886Z | DEBUG in [Room #1: RM85c032c592b74dc59f8a4dab05d9693f]: Creating a new RemoteParticipant for each ParticipantSignaling in the RoomSignaling
log.js:138 2020-05-25 13:39:15.887Z | INFO in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: Created a new Participant: caller
log.js:138 2020-05-25 13:39:15.887Z | INFO in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: Added a new VideoTrackPublication: MT9d1b60745f15f53061ab2cc24b8b9460
log.js:138 2020-05-25 13:39:15.888Z | DEBUG in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: VideoTrackPublication: RemoteVideoTrackPublication {trackName: "9a96f00f-fc38-41dd-93a3-1ecddd7641f4", trackSid: "MT9d1b60745f15f53061ab2cc24b8b9460", kind: "video", …}
log.js:138 2020-05-25 13:39:15.888Z | INFO in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: Added a new AudioTrackPublication: MT6bfc36428a39a009ce0c8832a908a25d
log.js:138 2020-05-25 13:39:15.888Z | DEBUG in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: AudioTrackPublication: RemoteAudioTrackPublication {trackName: "cc3445b5-aecf-465d-bff3-b51f4e014cbf", trackSid: "MT6bfc36428a39a009ce0c8832a908a25d", kind: "audio", …}
log.js:138 2020-05-25 13:39:15.888Z | INFO in [Room #1: RM85c032c592b74dc59f8a4dab05d9693f]: A new RemoteParticipant connected: RemoteParticipant {audioTracks: Map(1), dataTracks: Map(0), …}
log.js:138 2020-05-25 13:39:15.889Z | DEBUG in [Room #1: RM85c032c592b74dc59f8a4dab05d9693f]: Setting up RemoteParticipant creation for all subsequent ParticipantSignalings that connect to the RoomSignaling
log.js:138 2020-05-25 13:39:15.889Z | INFO in [Room #1: RM85c032c592b74dc59f8a4dab05d9693f]: Created a new Room: RM85c032c592b74dc59f8a4dab05d9693f
log.js:138 2020-05-25 13:39:15.889Z | DEBUG in [Room #1: RM85c032c592b74dc59f8a4dab05d9693f]: Initial RemoteParticipants: [RemoteParticipant]
log.js:138 2020-05-25 13:39:15.889Z | DEBUG in [connect #1]: Creating a new Room: Room {localParticipant: LocalParticipant, name: "RM85c032c592b74dc59f8a4dab05d9693f", participants: Map(1), …}
log.js:138 2020-05-25 13:39:15.890Z | INFO in [connect #1]: Connected to Room: [Room #1: RM85c032c592b74dc59f8a4dab05d9693f]
log.js:138 2020-05-25 13:39:15.890Z | INFO in [connect #1]: Room name: RM85c032c592b74dc59f8a4dab05d9693f
log.js:138 2020-05-25 13:39:15.890Z | DEBUG in [connect #1]: Room: Room {localParticipant: LocalParticipant, name: "RM85c032c592b74dc59f8a4dab05d9693f", participants: Map(1), …}
log.js:138 2020-05-25 13:39:15.904Z | INFO in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: Added a new DataTrack: a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2
log.js:138 2020-05-25 13:39:15.905Z | DEBUG in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: DataTrack: LocalDataTrack {kind: "data", name: "a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2", id: "a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2", maxPacketLifeTime: null, maxRetransmits: null, …}
log.js:138 2020-05-25 13:39:15.905Z | INFO in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: Added a new LocalDataTrack: a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2
log.js:138 2020-05-25 13:39:15.906Z | DEBUG in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: LocalDataTrack: LocalDataTrack {kind: "data", name: "a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2", id: "a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2", maxPacketLifeTime: null, maxRetransmits: null, …}
log.js:138 2020-05-25 13:39:15.928Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"}],"revision":2,"state":"connected"},"participants":[{"sid":"PA1f8bcef40546b34b36a19668e37fde39","identity":"responder","tracks":[{"kind":"data","priority":"standard","id":"d32cd2fd-71cf-4916-985d-9a60df5c257c","enabled":true,"sid":"MTf12c5fb434ddaa5b8005d4e6c4da8e6c","name":"5968cdac-afeb-4940-b891-fdb343131882","state":"ready"},{"kind":"audio","priority":"standard","id":"2eb49786-a553-40db-b839-948514da2f54","enabled":true,"sid":"MT5b055bba91a6f6fe7b9623abf735ec96","name":"6a3972c7-3949-4d9c-8cff-c7ec5305062b","state":"ready"}],"revision":4,"state":"disconnected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":32,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":2,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:15.929Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"}],"revision":2,"state":"connected"},"participants":[],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":31,"tracks":[{"id":"d32cd2fd-71cf-4916-985d-9a60df5c257c","sid":"MTf12c5fb434ddaa5b8005d4e6c4da8e6c"},{"id":"2eb49786-a553-40db-b839-948514da2f54","sid":"MT5b055bba91a6f6fe7b9623abf735ec96"},{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":2,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:15.931Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.141Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}],"revision":3,"state":"connected"},"participants":[],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":33,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":3,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.142Z | INFO in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: Added a new DataTrackPublication: MTd757ad392fe2cd6dafde73649a6ba07f
log.js:138 2020-05-25 13:39:16.142Z | DEBUG in [LocalParticipant #1: PAca2d69c852840fe8e13f17d5ed787cf2]: DataTrackPublication: LocalDataTrackPublication {trackName: "a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2", trackSid: "MTd757ad392fe2cd6dafde73649a6ba07f", kind: "data", …}
log.js:138 2020-05-25 13:39:16.297Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","peer_connections":[{"id":"23733563-f458-4e0f-9079-d82f46700383","description":{"type":"answer","sdp":"v=0\r\no=- 2096224016594447216 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1\r\na=msid-semantic: WMS\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:active\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:active\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=inactive\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\n","revision":1}}],"sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}],"revision":3,"state":"connected"},"participants":[{"sid":"PA503e086e94c6b862543f22c4212c9e24","identity":"caller","tracks":[{"kind":"video","priority":"standard","id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","enabled":true,"sid":"MT9d1b60745f15f53061ab2cc24b8b9460","name":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","state":"ready"},{"kind":"audio","priority":"standard","id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","enabled":true,"sid":"MT6bfc36428a39a009ce0c8832a908a25d","name":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":33,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":3,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.304Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: ICE gathering state is "gathering"
log.js:138 2020-05-25 13:39:16.304Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Starting ICE gathering timeout: 15000
log.js:138 2020-05-25 13:39:16.304Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.305Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:2445384676 1 udp 2122260223 192.168.1.26 54770 typ host generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":1},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.308Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.308Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:2445384676 1 udp 2122260223 192.168.1.26 54770 typ host generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:1591995618 1 udp 2122194687 172.20.159.191 51993 typ host generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":2},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.312Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"description":{"type":"offer","revision":2,"sdp":"v=0\r\no=- 4627483725877734519 3 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2\r\na=msid-semantic: WMS\r\nm=audio 54770 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 192.168.1.26\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:2445384676 1 udp 2122260223 192.168.1.26 54770 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:1591995618 1 udp 2122194687 172.20.159.191 51993 typ host generation 0 network-id 2 network-cost 50\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendrecv\r\na=msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:1440628111 cname:3cmbWEUmTKCxDgMw\r\na=ssrc:1440628111 msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=ssrc:1440628111 mslabel:-\r\na=ssrc:1440628111 label:ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=application 9 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:2\r\na=sctp-port:5000\r\na=max-message-size:262144\r\n"},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.352Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.352Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":3},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.373Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.374Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":4},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.405Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.405Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":5},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.405Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.405Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:274731026 1 tcp 1518214911 172.20.159.191 9 typ host tcptype active generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":6},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.588Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","peer_connections":[{"id":"23733563-f458-4e0f-9079-d82f46700383","ice":{"ufrag":"P6jF","revision":1,"candidates":[{"candidate":"candidate:1591995618 1 udp 2113937151 172.20.159.191 64313 typ host generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0}]}}],"sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}],"revision":3,"state":"connected"},"participants":[{"sid":"PA503e086e94c6b862543f22c4212c9e24","identity":"caller","tracks":[{"kind":"video","priority":"standard","id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","enabled":true,"sid":"MT9d1b60745f15f53061ab2cc24b8b9460","name":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","state":"ready"},{"kind":"audio","priority":"standard","id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","enabled":true,"sid":"MT6bfc36428a39a009ce0c8832a908a25d","name":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":33,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":3,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.589Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: ICE connection state is "checking"
log.js:138 2020-05-25 13:39:16.590Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","peer_connections":[{"id":"23733563-f458-4e0f-9079-d82f46700383","description":{"type":"offer","sdp":"v=0\r\no=- 2096224016594447216 3 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2 3\r\na=msid-semantic: WMS\r\nm=audio 64313 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 172.20.159.191\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:1591995618 1 udp 2113937151 172.20.159.191 64313 typ host generation 0 network-cost 999\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:actpass\r\na=mid:2\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendrecv\r\na=msid:- cc3445b5-aecf-465d-bff3-b51f4e014cbf\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:1093351876 cname:dwDUuebmlXKKBR1Q\r\na=ssrc:1093351876 msid:- cc3445b5-aecf-465d-bff3-b51f4e014cbf\r\na=ssrc:1093351876 mslabel:-\r\na=ssrc:1093351876 label:cc3445b5-aecf-465d-bff3-b51f4e014cbf\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:actpass\r\na=mid:3\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendrecv\r\na=msid:- 9a96f00f-fc38-41dd-93a3-1ecddd7641f4\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:114 red/90000\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:116 ulpfec/90000\r\na=ssrc-group:FID 141275990 3346472094\r\na=ssrc:141275990 cname:dwDUuebmlXKKBR1Q\r\na=ssrc:141275990 msid:- 9a96f00f-fc38-41dd-93a3-1ecddd7641f4\r\na=ssrc:141275990 mslabel:-\r\na=ssrc:141275990 label:9a96f00f-fc38-41dd-93a3-1ecddd7641f4\r\na=ssrc:3346472094 cname:dwDUuebmlXKKBR1Q\r\na=ssrc:3346472094 msid:- 9a96f00f-fc38-41dd-93a3-1ecddd7641f4\r\na=ssrc:3346472094 mslabel:-\r\na=ssrc:3346472094 label:9a96f00f-fc38-41dd-93a3-1ecddd7641f4\r\n","revision":2}}],"sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}],"revision":3,"state":"connected"},"participants":[{"sid":"PA503e086e94c6b862543f22c4212c9e24","identity":"caller","tracks":[{"kind":"video","priority":"standard","id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","enabled":true,"sid":"MT9d1b60745f15f53061ab2cc24b8b9460","name":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","state":"ready"},{"kind":"audio","priority":"standard","id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","enabled":true,"sid":"MT6bfc36428a39a009ce0c8832a908a25d","name":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":33,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":3,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.591Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Glare detected; rolling back
log.js:138 2020-05-25 13:39:16.593Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.594Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:274731026 1 tcp 1518214911 172.20.159.191 9 typ host tcptype active generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:4085101586 1 udp 41885695 52.215.127.224 42454 typ relay raddr 85.191.106.238 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":7},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.606Z | DEBUG in [RemoteAudioTrack #3: undefined]: Initializing
log.js:138 2020-05-25 13:39:16.606Z | INFO in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: Added a new AudioTrack: cc3445b5-aecf-465d-bff3-b51f4e014cbf
log.js:138 2020-05-25 13:39:16.606Z | DEBUG in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: AudioTrack: RemoteAudioTrack {kind: "audio", name: "cc3445b5-aecf-465d-bff3-b51f4e014cbf", mediaStreamTrack: MediaStreamTrack, …}
log.js:138 2020-05-25 13:39:16.607Z | DEBUG in [RemoteAudioTrack #3: MT6bfc36428a39a009ce0c8832a908a25d]: Attempting to attach to element: <audio autoplay></audio>
log.js:138 2020-05-25 13:39:16.621Z | DEBUG in [RemoteVideoTrack #4: undefined]: Initializing
log.js:138 2020-05-25 13:39:16.622Z | INFO in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: Added a new VideoTrack: 9a96f00f-fc38-41dd-93a3-1ecddd7641f4
log.js:138 2020-05-25 13:39:16.622Z | DEBUG in [RemoteParticipant #2: PA503e086e94c6b862543f22c4212c9e24]: VideoTrack: RemoteVideoTrack {kind: "video", name: "9a96f00f-fc38-41dd-93a3-1ecddd7641f4", mediaStreamTrack: MediaStreamTrack, dimensions: {…}, …}
log.js:138 2020-05-25 13:39:16.622Z | DEBUG in [RemoteVideoTrack #4: MT9d1b60745f15f53061ab2cc24b8b9460]: Attempting to attach to element: <video autoplay playsinline></video>
log.js:138 2020-05-25 13:39:16.651Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: ICE connection state is "connected"
log.js:138 2020-05-25 13:39:16.651Z | DEBUG in [RemoteAudioTrack #3: MT6bfc36428a39a009ce0c8832a908a25d]: Started
log.js:138 2020-05-25 13:39:16.652Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: Clearing ICE gathering timeout
log.js:138 2020-05-25 13:39:16.652Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:274731026 1 tcp 1518214911 172.20.159.191 9 typ host tcptype active generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:4085101586 1 udp 41885695 52.215.127.224 42454 typ relay raddr 85.191.106.238 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:4085101586 1 udp 41820159 52.215.127.224 59623 typ relay raddr 185.108.105.78 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","revision":8},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.653Z | DEBUG in [PeerConnectionV2 #1: 23733563-f458-4e0f-9079-d82f46700383]: ICE gathering state is "complete"
log.js:138 2020-05-25 13:39:16.653Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:274731026 1 tcp 1518214911 172.20.159.191 9 typ host tcptype active generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:4085101586 1 udp 41885695 52.215.127.224 42454 typ relay raddr 85.191.106.238 rport 54770 generation 0 ufrag oH+6 network-id 1 network-cost 10","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:4085101586 1 udp 41820159 52.215.127.224 59623 typ relay raddr 185.108.105.78 rport 51993 generation 0 ufrag oH+6 network-id 2 network-cost 50","sdpMid":"0","sdpMLineIndex":0}],"ufrag":"oH+6","complete":true,"revision":9},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.776Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"description":{"type":"answer","revision":2,"sdp":"v=0\r\no=- 4627483725877734519 4 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2 3\r\na=msid-semantic: WMS\r\nm=audio 42454 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 52.215.127.224\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:2445384676 1 udp 2122260223 192.168.1.26 54770 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:1591995618 1 udp 2122194687 172.20.159.191 51993 typ host generation 0 network-id 2 network-cost 50\r\na=candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 network-id 1 network-cost 10\r\na=candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 network-id 2 network-cost 50\r\na=candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 network-id 1 network-cost 10\r\na=candidate:274731026 1 tcp 1518214911 172.20.159.191 9 typ host tcptype active generation 0 network-id 2 network-cost 50\r\na=candidate:4085101586 1 udp 41885695 52.215.127.224 42454 typ relay raddr 85.191.106.238 rport 54770 generation 0 network-id 1 network-cost 10\r\na=candidate:4085101586 1 udp 41820159 52.215.127.224 59623 typ relay raddr 185.108.105.78 rport 51993 generation 0 network-id 2 network-cost 50\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:passive\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendonly\r\na=msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:1440628111 cname:3cmbWEUmTKCxDgMw\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:passive\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=inactive\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:passive\r\na=mid:2\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:passive\r\na=mid:3\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\n"},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:16.784Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"description":{"type":"offer","revision":3,"sdp":"v=0\r\no=- 4627483725877734519 5 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2 3 4\r\na=msid-semantic: WMS\r\nm=audio 42454 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 52.215.127.224\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:2445384676 1 udp 2122260223 192.168.1.26 54770 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:1591995618 1 udp 2122194687 172.20.159.191 51993 typ host generation 0 network-id 2 network-cost 50\r\na=candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 network-id 1 network-cost 10\r\na=candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 network-id 2 network-cost 50\r\na=candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 network-id 1 network-cost 10\r\na=candidate:274731026 1 tcp 1518214911 172.20.159.191 9 typ host tcptype active generation 0 network-id 2 network-cost 50\r\na=candidate:4085101586 1 udp 41885695 52.215.127.224 42454 typ relay raddr 85.191.106.238 rport 54770 generation 0 network-id 1 network-cost 10\r\na=candidate:4085101586 1 udp 41820159 52.215.127.224 59623 typ relay raddr 185.108.105.78 rport 51993 generation 0 network-id 2 network-cost 50\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=sendrecv\r\na=msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:1440628111 cname:3cmbWEUmTKCxDgMw\r\na=ssrc:1440628111 msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=ssrc:1440628111 mslabel:-\r\na=ssrc:1440628111 label:ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:2\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:3\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=application 9 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:actpass\r\na=mid:4\r\na=sctp-port:5000\r\na=max-message-size:262144\r\n"},"id":"23733563-f458-4e0f-9079-d82f46700383"}],"participant":{"revision":3,"tracks":[{"enabled":true,"id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","kind":"audio","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","priority":"standard"},{"enabled":true,"id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","kind":"data","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","priority":"standard"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:17.153Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","peer_connections":[{"id":"23733563-f458-4e0f-9079-d82f46700383","ice":{"ufrag":"P6jF","revision":3,"candidates":[{"candidate":"candidate:1591995618 1 udp 2113937151 172.20.159.191 64313 typ host generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:842163049 1 udp 1677729535 185.108.105.78 64313 typ srflx raddr 172.20.159.191 rport 64313 generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:4085101586 1 udp 33562623 52.215.127.224 23865 typ relay raddr 185.108.105.78 rport 64313 generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0}]}}],"sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}],"revision":3,"state":"connected"},"participants":[{"sid":"PA503e086e94c6b862543f22c4212c9e24","identity":"caller","tracks":[{"kind":"video","priority":"standard","id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","enabled":true,"sid":"MT9d1b60745f15f53061ab2cc24b8b9460","name":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","state":"ready"},{"kind":"audio","priority":"standard","id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","enabled":true,"sid":"MT6bfc36428a39a009ce0c8832a908a25d","name":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":33,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":3,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:17.202Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","peer_connections":[{"id":"23733563-f458-4e0f-9079-d82f46700383","ice":{"ufrag":"P6jF","revision":5,"candidates":[{"candidate":"candidate:1591995618 1 udp 2113937151 172.20.159.191 64313 typ host generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:842163049 1 udp 1677729535 185.108.105.78 64313 typ srflx raddr 172.20.159.191 rport 64313 generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:4085101586 1 udp 33562623 52.215.127.224 23865 typ relay raddr 185.108.105.78 rport 64313 generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0},{"candidate":"candidate:2741962135 1 udp 7935 52.215.127.224 39920 typ relay raddr 185.108.105.78 rport 60428 generation 0 ufrag P6jF network-cost 999","sdpMid":"0","sdpMLineIndex":0}],"complete":true}}],"sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}],"revision":3,"state":"connected"},"participants":[{"sid":"PA503e086e94c6b862543f22c4212c9e24","identity":"caller","tracks":[{"kind":"video","priority":"standard","id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","enabled":true,"sid":"MT9d1b60745f15f53061ab2cc24b8b9460","name":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","state":"ready"},{"kind":"audio","priority":"standard","id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","enabled":true,"sid":"MT6bfc36428a39a009ce0c8832a908a25d","name":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":33,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":3,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:18.061Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","peer_connections":[{"id":"23733563-f458-4e0f-9079-d82f46700383","description":{"type":"answer","sdp":"v=0\r\no=- 2096224016594447216 4 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2 3 4\r\na=msid-semantic: WMS\r\nm=audio 23865 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 52.215.127.224\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:1591995618 1 udp 2113937151 172.20.159.191 64313 typ host generation 0 network-cost 999\r\na=candidate:842163049 1 udp 1677729535 185.108.105.78 64313 typ srflx raddr 172.20.159.191 rport 64313 generation 0 network-cost 999\r\na=candidate:4085101586 1 udp 33562623 52.215.127.224 23865 typ relay raddr 185.108.105.78 rport 64313 generation 0 network-cost 999\r\na=candidate:2741962135 1 udp 7935 52.215.127.224 39920 typ relay raddr 185.108.105.78 rport 60428 generation 0 network-cost 999\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:active\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:active\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=inactive\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:active\r\na=mid:2\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=inactive\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:active\r\na=mid:3\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=inactive\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=application 9 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 0.0.0.0\r\nb=AS:30\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:active\r\na=mid:4\r\na=sctp-port:5000\r\na=max-message-size:262144\r\n","revision":3}}],"sid":"RM85c032c592b74dc59f8a4dab05d9693f","name":"RM85c032c592b74dc59f8a4dab05d9693f","participant":{"sid":"PAca2d69c852840fe8e13f17d5ed787cf2","identity":"responder","tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}],"revision":3,"state":"connected"},"participants":[{"sid":"PA503e086e94c6b862543f22c4212c9e24","identity":"caller","tracks":[{"kind":"video","priority":"standard","id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","enabled":true,"sid":"MT9d1b60745f15f53061ab2cc24b8b9460","name":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","state":"ready"},{"kind":"audio","priority":"standard","id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","enabled":true,"sid":"MT6bfc36428a39a009ce0c8832a908a25d","name":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":33,"tracks":[{"id":"cc3445b5-aecf-465d-bff3-b51f4e014cbf","sid":"MT6bfc36428a39a009ce0c8832a908a25d"},{"id":"9a96f00f-fc38-41dd-93a3-1ecddd7641f4","sid":"MT9d1b60745f15f53061ab2cc24b8b9460"}]},"published":{"revision":3,"tracks":[{"kind":"audio","priority":"standard","id":"ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d","enabled":true,"sid":"MT748e5edf85b1aff7b12d11741a2ab01a","name":"ab5ed93d-d4b3-45e8-af09-187091250aad","state":"ready"},{"kind":"data","priority":"standard","id":"a4734b6f-c3bc-4032-bdb0-dbc8e3ebce0b","enabled":true,"sid":"MTd757ad392fe2cd6dafde73649a6ba07f","name":"a6fdbe1b-24f1-4bc2-b8d2-da4b02a4b6b2","state":"ready"}]}},"type":"msg"}
log.js:138 2020-05-25 13:39:18.081Z | DEBUG in [RemoteAudioTrack #3: MT6bfc36428a39a009ce0c8832a908a25d]: Ended
log.js:138 2020-05-25 13:39:18.364Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"body":{"version":2,"type":"update","peer_connections":[{"id":"23733563-f458-4e0f-9079-d82f46700383","description":{"type":"offer","sdp":"v=0\r\no=- 2096224016594447216 5 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2 3 4 5 6\r\na=msid-semantic: WMS\r\nm=audio 23865 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 52.215.127.224\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:1591995618 1 udp 2113937151 172.20.159.191 64313 typ host generation 0 network-cost 999\r\na=candidate:842163049 1 udp 1677729535 185.108.105.78 64313 typ srflx raddr 172.20.159.191 rport 64313 generation 0 network-cost 999\r\na=candidate:4085101586 1 udp 33562623 52.215.127.224 23865 typ relay raddr 185.108.105.78 rport 64313 generation 0 network-cost 999\r\na=candidate:2741962135 1 udp 7935 52.215.127.224 39920 typ relay raddr 185.108.105.78 rport 60428 generation 0 network-cost 999\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:actpass\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:actpass\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:P6jF\r\na=ice-pwd:6BGEinC6lFLULJsECmAQQz/e\r\na=ice-options:trickle\r\na=fingerprint:sha-256 39:DE:1B:25:32:44:91:12:0F:85:C5:5A:41:11:38:6F:62:1C:C2:68:99:AF:AD:10:26:76:BC:01:20:BA:EB:DC\r\na=setup:actpass\r\na=mid:2\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=recvonly\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-e
log.js:138 2020-05-25 13:39:18.389Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"body":{"session":"c5c02c9cae5b329434cd725c188a925285c032c592b74dc59f8a4dab05d9693fca2d69c852840fe8e13f17d5ed787cf27e56d53456ed319ca8f1739ef829f164","type":"update","version":2,"peer_connections":[{"description":{"type":"answer","revision":4,"sdp":"v=0\r\no=- 4627483725877734519 6 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE 0 1 2 3 4 5 6\r\na=msid-semantic: WMS\r\nm=audio 42454 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 52.215.127.224\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:2445384676 1 udp 2122260223 192.168.1.26 54770 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:1591995618 1 udp 2122194687 172.20.159.191 51993 typ host generation 0 network-id 2 network-cost 50\r\na=candidate:1741808176 1 udp 1686052607 85.191.106.238 54770 typ srflx raddr 192.168.1.26 rport 54770 generation 0 network-id 1 network-cost 10\r\na=candidate:2456736625 1 udp 1685987071 185.108.105.78 51993 typ srflx raddr 172.20.159.191 rport 51993 generation 0 network-id 2 network-cost 50\r\na=candidate:3745670932 1 tcp 1518280447 192.168.1.26 9 typ host tcptype active generation 0 network-id 1 network-cost 10\r\na=candidate:274731026 1 tcp 1518214911 172.20.159.191 9 typ host tcptype active generation 0 network-id 2 network-cost 50\r\na=candidate:4085101586 1 udp 41885695 52.215.127.224 42454 typ relay raddr 85.191.106.238 rport 54770 generation 0 network-id 1 network-cost 10\r\na=candidate:4085101586 1 udp 41820159 52.215.127.224 59623 typ relay raddr 185.108.105.78 rport 51993 generation 0 network-id 2 network-cost 50\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:passive\r\na=mid:0\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=sendonly\r\na=msid:- ddc58cc7-fc8e-4bb7-b199-8fc667bd4d3d\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=ssrc:1440628111 cname:3cmbWEUmTKCxDgMw\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 101 115 98 100 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:passive\r\na=mid:1\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=extmap:8 http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07\r\na=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/color-space\r\na=extmap:4 urn:ietf:params:rtp-hdrext:sdes:mid\r\na=extmap:5 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id\r\na=extmap:6 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id\r\na=inactive\r\na=rtcp-mux\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:101 rtx/90000\r\na=fmtp:101 apt=100\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=fmtp:98 profile-id=0\r\na=rtpmap:100 VP9/90000\r\na=rtcp-fb:100 goog-remb\r\na=rtcp-fb:100 transport-cc\r\na=rtcp-fb:100 ccm fir\r\na=rtcp-fb:100 nack\r\na=rtcp-fb:100 nack pli\r\na=fmtp:100 profile-id=2\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-ufrag:oH+6\r\na=ice-pwd:bukTjrgShg7nn2PfoC5z6hHh\r\na=ice-options:trickle\r\na=fingerprint:sha-256 21:8E:EB:A8:12:37:02:70:DB:1A:FC:3A:99:3D:FE:CC:BE:6C:AA:DB:C3:67:FE:2A:0E:E4:53:E1:4E:A0:88:8E\r\na=setup:passive\
log.js:138 2020-05-25 13:39:20.062Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:20.524Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:24.648Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:25.525Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:29.151Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:30.526Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:33.750Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:35.528Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:38.348Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:40.528Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:42.952Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:45.529Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"type":"heartbeat"}
log.js:138 2020-05-25 13:39:47.646Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}
 2020-05-25 13:39:50.529Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Outgoing: {"type":"heartbeat"}
 2020-05-25 13:39:52.148Z | DEBUG in [TwilioConnection #1: wss://global.vss.twilio.com/signaling]: Incoming: {"type":"heartbeat"}

patricktemple commented 4 years ago

I keep seeing this. One more thing I noticed in the chrome://webrtc-internals. While the RTCInboundRTPVideoStream seems like everything's fine, the RTCMediaStreamTrack_receiver does show signs of the problem:

Just in case this reveals anything useful.

Also I think this bug is Chrome-only and I haven't yet reproduced it on Safari.

patricktemple commented 4 years ago

I started looking at Chrome's debug logs (from its own binary, not the JS console logs) and see a few hints in there. I ran session that worked fine at first (videos loaded) but I refreshed a few times. On the 4th load, ones of the videos failed.

Once the problem arises I start seeing this in the Chrome logs (actually, I used the latest Chromium so its line numbers would match the recent source):

[45012:78851:0613/152849.423569:ERROR:call.cc(1305)] receive_rtp_config_ lookup failed for ssrc 3390597395
[45012:78851:0613/152849.423630:INFO:webrtc_video_engine.cc(534)] Creating default receive stream for SSRC=3390597395.

Which comes from here: https://chromium.googlesource.com/external/webrtc/+/master/call/call.cc#1305

A bit later, I see this:

[45012:78851:0613/152850.016984:ERROR:rtp_demuxer.cc(88)] Unable to add sink = 0x7fab4f1bf128 due conflicting criteria {mid: <empty>, rsid: <empty>, ssrcs: [3390597395, ], payload_types = []}
[45012:78851:0613/152850.017013:ERROR:rtp_stream_receiver_controller.cc(26)] RtpStreamReceiverController::Receiver::Receiver: Sink could not be added for SSRC=3390597395.

Which comes from https://chromium.googlesource.com/external/webrtc/+/master/call/rtp_stream_receiver_controller.cc#27

And then, a whole lot of this:

[45012:36355:0613/152850.238052:WARNING:webrtc_video_engine.cc(2977)] VideoReceiveStream not connected to a VideoSink.

Those first two logs only first appeared when the problem arose. The last one did show up sporadically even in successful calls (maybe only on page startup), but increased a ton in frequency once one of the videos failed.

I've sent the full Chrome logs to your support team to share with you.

I don't know if this bug is in Chrome or Twilio iOS SDK in sending the data, but hopefully this info is useful to you. Right now I'm working on building Chromium from source so I can keep debugging further.

patricktemple commented 4 years ago

I'm getting an idea of what's happening here.

The first video always starts fine, but we're sometimes having RTP packets come in for the second video stream before that stream is initialized. Normally, for a given SSRC, I'll first see a call to CreateVideoReceiveStream: https://chromium.googlesource.com/external/webrtc/+/master/call/call.cc#929

Then later, a bunch of calls to DeliverRtp as media flows in: https://chromium.googlesource.com/external/webrtc/+/master/call/call.cc#1274

But when this bug occurs, some DeliverRtp calls for the SSRC sneak in before CreateVideoReceiveStream. Then DeliverRtp fails to find a matching stream, so it makes a "default stream" which seems useless and gets things stuck in a bad state. In a release build, it eventually results in those VideoReceiveStream not connected to a VideoSink. errors I mentioned above, and the video goes into a muted state. In my debug build of Chromium, it actually crashes the tab (that "Oh, snap!" message). Maybe Chromium has some DCHECK that fails? Unclear, since I don't see any kind of fatal error in the logs.

Anyway, I traced the call to CreateVideoReceiveStream back up and see it's ultimately triggered by the call to PeerConnection.SetRemoteDescription: https://chromium.googlesource.com/external/webrtc/+/master/pc/peer_connection.cc#2977

This always gets called twice on room startup. When everything succeeds, I see the sequence of events goes like this: 1) PeerConnection::SetRemoteDescription 2) Call::CreateVideoReceiveStream with some SSRC (let's call it A) 3) DeliverRtp events start to trickle in for SSRC A... 4) PeerConnection::SetRemoteDescription is called again 5) Call::CreateVideoReceiveStream with SSRC B for the second video feed 6) DelivertRtp for SSRC B start to appear as well

But when the bug arises, the order is changed: 1) PeerConnection::SetRemoteDescription 2) Call::CreateVideoReceiveStream with SSRC A 3) DeliverRtp starts to trickle in for SSRC A 4) DeliverRtp called for SSRC B even though it's not initialized 5) PeerConnection::SetRemoteDescription again 6) Call::CreateVideoReceiveStream with SSRC B for the second video feed, but it's too late, the stream is in a bad state

So it seems like the first call to SetRemoteDescription causes the RTP channels to open for all feeds, and then the iOS client starts sending packets for the second video feed, even though it hasn't yet been set up on the web side.

That was all in the Chromium C++ world. I did a sanity check of the Twilio JS side of things and confirm that setRemoteDescription is indeed called twice on the peer connection:

{
  "type": "answer",
  "sdp": "v=0\r\no=- 6999952230256362861 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS *\r\na=group:BUNDLE 0 1 2\r\nm=video 9 UDP/TLS/RTP/SAVPF 96 97 99 107 115 98 125 114 116\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-options:trickle\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:107 rtx/90000\r\na=fmtp:107 apt=125\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=rtpmap:125 H264/90000\r\na=rtcp-fb:125 goog-remb\r\na=rtcp-fb:125 transport-cc\r\na=rtcp-fb:125 ccm fir\r\na=rtcp-fb:125 nack\r\na=rtcp-fb:125 nack pli\r\na=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\na=setup:active\r\na=msid:9Ec4d5D2d88bBaD9cd12adAE7fED3f34 337Cdf9d7A073d274bBd6Fd5fAdF3c24\r\na=mid:0\r\na=sendrecv\r\na=ice-ufrag:QY8S\r\na=ice-pwd:qz4YTO1X/WS1Zt6/sacTIsXI\r\na=fingerprint:sha-256 62:EF:05:AF:1C:05:61:3B:FA:E1:13:BF:79:A7:4D:D5:97:5B:19:F6:F2:D2:6E:39:F0:3B:E4:A0:2C:67:D4:A6\r\na=ssrc-group:FID 1975356323 3274979286\r\na=ssrc:1975356323 cname:MhkMmxR65kttDU+H\r\na=ssrc:1975356323 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:1975356323 label:337Cdf9d7A073d274bBd6Fd5fAdF3c24\r\na=ssrc:3274979286 cname:MhkMmxR65kttDU+H\r\na=ssrc:3274979286 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:3274979286 label:337Cdf9d7A073d274bBd6Fd5fAdF3c24\r\na=rtcp-mux\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-options:trickle\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=setup:active\r\na=msid:9Ec4d5D2d88bBaD9cd12adAE7fED3f34 8933FFb13a2d92e65B6Ae7cC0Da2EcEe\r\na=mid:1\r\na=sendonly\r\na=ice-ufrag:QY8S\r\na=ice-pwd:qz4YTO1X/WS1Zt6/sacTIsXI\r\na=fingerprint:sha-256 62:EF:05:AF:1C:05:61:3B:FA:E1:13:BF:79:A7:4D:D5:97:5B:19:F6:F2:D2:6E:39:F0:3B:E4:A0:2C:67:D4:A6\r\na=ssrc:644192413 cname:MhkMmxR65kttDU+H\r\na=ssrc:644192413 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:644192413 label:8933FFb13a2d92e65B6Ae7cC0Da2EcEe\r\na=rtcp-mux\r\nm=application 9 UDP/DTLS/SCTP webrtc-datachannel\r\nc=IN IP4 0.0.0.0\r\na=ice-options:trickle\r\na=sctp-port:5000\r\na=setup:active\r\na=mid:2\r\na=sendrecv\r\na=ice-ufrag:QY8S\r\na=ice-pwd:qz4YTO1X/WS1Zt6/sacTIsXI\r\na=fingerprint:sha-256 62:EF:05:AF:1C:05:61:3B:FA:E1:13:BF:79:A7:4D:D5:97:5B:19:F6:F2:D2:6E:39:F0:3B:E4:A0:2C:67:D4:A6\r\n"
}

And:

{
  "type": "offer",
  "sdp": "v=0\r\no=- 6999952230256362861 3 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS *\r\na=group:BUNDLE 0 1 2 video3\r\nm=video 54599 UDP/TLS/RTP/SAVPF 96 97 99 107 115 98 125 114 116\r\nc=IN IP4 192.168.0.16\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:1880550016 1 udp 2122260223 192.168.0.16 54599 typ host generation 0 network-id 1 network-cost 10\r\na=ice-options:trickle\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:107 rtx/90000\r\na=fmtp:107 apt=125\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=rtpmap:125 H264/90000\r\na=rtcp-fb:125 goog-remb\r\na=rtcp-fb:125 transport-cc\r\na=rtcp-fb:125 ccm fir\r\na=rtcp-fb:125 nack\r\na=rtcp-fb:125 nack pli\r\na=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\na=setup:actpass\r\na=msid:9Ec4d5D2d88bBaD9cd12adAE7fED3f34 337Cdf9d7A073d274bBd6Fd5fAdF3c24\r\na=mid:0\r\na=sendrecv\r\na=ice-ufrag:QY8S\r\na=ice-pwd:qz4YTO1X/WS1Zt6/sacTIsXI\r\na=fingerprint:sha-256 62:EF:05:AF:1C:05:61:3B:FA:E1:13:BF:79:A7:4D:D5:97:5B:19:F6:F2:D2:6E:39:F0:3B:E4:A0:2C:67:D4:A6\r\na=ssrc-group:FID 1975356323 3274979286\r\na=ssrc:1975356323 cname:MhkMmxR65kttDU+H\r\na=ssrc:1975356323 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:1975356323 label:337Cdf9d7A073d274bBd6Fd5fAdF3c24\r\na=ssrc:3274979286 cname:MhkMmxR65kttDU+H\r\na=ssrc:3274979286 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:3274979286 label:337Cdf9d7A073d274bBd6Fd5fAdF3c24\r\na=rtcp-mux\r\nm=audio 9 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 0.0.0.0\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=ice-options:trickle\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:110 telephone-event/48000\r\na=rtpmap:112 telephone-event/32000\r\na=rtpmap:113 telephone-event/16000\r\na=rtpmap:126 telephone-event/8000\r\na=setup:actpass\r\na=msid:9Ec4d5D2d88bBaD9cd12adAE7fED3f34 8933FFb13a2d92e65B6Ae7cC0Da2EcEe\r\na=mid:1\r\na=sendrecv\r\na=ice-ufrag:QY8S\r\na=ice-pwd:qz4YTO1X/WS1Zt6/sacTIsXI\r\na=fingerprint:sha-256 62:EF:05:AF:1C:05:61:3B:FA:E1:13:BF:79:A7:4D:D5:97:5B:19:F6:F2:D2:6E:39:F0:3B:E4:A0:2C:67:D4:A6\r\na=ssrc:644192413 cname:MhkMmxR65kttDU+H\r\na=ssrc:644192413 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:644192413 label:8933FFb13a2d92e65B6Ae7cC0Da2EcEe\r\na=rtcp-mux\r\nm=application 9 DTLS/SCTP 5000\r\nc=IN IP4 0.0.0.0\r\na=ice-options:trickle\r\na=sctpmap:5000 webrtc-datachannel 1024\r\na=setup:actpass\r\na=mid:2\r\na=sendrecv\r\na=ice-ufrag:QY8S\r\na=ice-pwd:qz4YTO1X/WS1Zt6/sacTIsXI\r\na=fingerprint:sha-256 62:EF:05:AF:1C:05:61:3B:FA:E1:13:BF:79:A7:4D:D5:97:5B:19:F6:F2:D2:6E:39:F0:3B:E4:A0:2C:67:D4:A6\r\nm=video 54599 UDP/TLS/RTP/SAVPF 96 97 99 107 115 98 125 114 116\r\nc=IN IP4 192.168.0.16\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:1880550016 1 udp 2122260223 192.168.0.16 54599 typ host generation 0 network-id 1 network-cost 10\r\na=ice-options:trickle\r\na=extmap:14 urn:ietf:params:rtp-hdrext:toffset\r\na=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=extmap:13 urn:3gpp:video-orientation\r\na=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01\r\na=extmap:12 http://www.webrtc.org/experiments/rtp-hdrext/playout-delay\r\na=extmap:11 http://www.webrtc.org/experiments/rtp-hdrext/video-content-type\r\na=extmap:7 http://www.webrtc.org/experiments/rtp-hdrext/video-timing\r\na=rtcp-rsize\r\na=rtpmap:96 VP8/90000\r\na=rtcp-fb:96 goog-remb\r\na=rtcp-fb:96 transport-cc\r\na=rtcp-fb:96 ccm fir\r\na=rtcp-fb:96 nack\r\na=rtcp-fb:96 nack pli\r\na=rtpmap:97 rtx/90000\r\na=fmtp:97 apt=96\r\na=rtpmap:99 rtx/90000\r\na=fmtp:99 apt=98\r\na=rtpmap:107 rtx/90000\r\na=fmtp:107 apt=125\r\na=rtpmap:115 rtx/90000\r\na=fmtp:115 apt=114\r\na=rtpmap:98 VP9/90000\r\na=rtcp-fb:98 goog-remb\r\na=rtcp-fb:98 transport-cc\r\na=rtcp-fb:98 ccm fir\r\na=rtcp-fb:98 nack\r\na=rtcp-fb:98 nack pli\r\na=rtpmap:125 H264/90000\r\na=rtcp-fb:125 goog-remb\r\na=rtcp-fb:125 transport-cc\r\na=rtcp-fb:125 ccm fir\r\na=rtcp-fb:125 nack\r\na=rtcp-fb:125 nack pli\r\na=fmtp:125 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f\r\na=rtpmap:114 red/90000\r\na=rtpmap:116 ulpfec/90000\r\na=setup:actpass\r\na=msid:9Ec4d5D2d88bBaD9cd12adAE7fED3f34 3D3F1d7F8Cb3dA4e4CeBEeb3Adf1b50e\r\na=mid:video3\r\na=sendrecv\r\na=ice-ufrag:QY8S\r\na=ice-pwd:qz4YTO1X/WS1Zt6/sacTIsXI\r\na=fingerprint:sha-256 62:EF:05:AF:1C:05:61:3B:FA:E1:13:BF:79:A7:4D:D5:97:5B:19:F6:F2:D2:6E:39:F0:3B:E4:A0:2C:67:D4:A6\r\na=ssrc-group:FID 1824046026 3945816386\r\na=ssrc:1824046026 cname:MhkMmxR65kttDU+H\r\na=ssrc:1824046026 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:1824046026 label:3D3F1d7F8Cb3dA4e4CeBEeb3Adf1b50e\r\na=ssrc:3945816386 cname:MhkMmxR65kttDU+H\r\na=ssrc:3945816386 mslabel:9Ec4d5D2d88bBaD9cd12adAE7fED3f34\r\na=ssrc:3945816386 label:3D3F1d7F8Cb3dA4e4CeBEeb3Adf1b50e\r\na=rtcp-mux\r\n"
}

I'm not sure what the correct behavior is here. Should Twilio iOS not be sending packets on the other video feed until later? Should Chrome be handling this better? Is there something wrong with the remote descriptions that Twilio JS is providing here?

makarandp0 commented 4 years ago

Thank you for the great investigative work @patricktemple! I will take a look into this soon. Also tagging our tech lead @manjeshbhargav if he can spot anything obvious here.

manjeshbhargav commented 4 years ago

Hi @patricktemple ,

Thanks for your detailed investigation. I'm looping in @ceaglest , our resident iOS SDK engineer. Chris, please take a look and see if you have any insights regarding this.

Thanks,

Manjesh

makarandp0 commented 4 years ago

Hey @patricktemple, You mentioned earlier that

I can repro easily if I just program my page to refresh after 5 seconds

Can you share some instructions on how to repro this. It would be very helpful if I can repro this locally. You also mentioned that you have iOS and Chrome as participants, does this issue repro with chrome on both sides of the call - or iOS is necessary for the repro ?

patricktemple commented 4 years ago

Sure I'll try to see if I can repro this Chrome to Chrome, and spin up a small project I can share with you if that works.

patricktemple commented 4 years ago

It does not seem to repro when both sides are using Chrome. I haven't yet tried to repro this in a sample project I could share, but I think this would boil it down to the essentials:

Make an iOS app (Twilio 3.2.5) that joins a Twilio P2P room and publishes two video tracks up front (they're listed in the call to connect). We use a custom frame capturer with isScreencast=false on both and don't specify a codec, if that matters. Both video tracks are something like 500px width/height and 24 fps.

Make a JS app that connects to this room on page load (you could just hardcode the room name and token). Have it receive the two incoming video tracks and display them on the DOM.

This might be enough to trigger the issue to appear intermittently. To force it to happen, just add this logic on the JS side after the remote tracks have been attached to the DOM:

setTimeout(() => {
  if (!track1.mediaStreamTrack.muted && !track2.mediaStreamTrack.muted) {
    window.location.reload();
  }
}, 5000);

Then let this run for 5-30 minutes. Basically, this gives the tracks a few seconds to connect and encounter the issue. If no tracks are muted, it means the bug didn't appear, so it refreshes to try again. Eventually, it should encounter the problem and stay there, so when you return to the page you'll see one of the video tracks is black.

Our app has other tracks (two way audio, webcam coming from JS, two way data track) but I'm hoping those aren't relevant to this issue.

patricktemple commented 4 years ago

I am able to reproduce this by modifying your quickstart apps.

iOS Start with your ReplayKit example. You just need to add a second local video track, right in connectToRoom:

let camera = CameraSource()
let frontCamera = CameraSource.captureDevice(position: .front)
let cameraTrack = LocalVideoTrack(source: camera!, enabled: true, name: "Camera")
camera!.startCapture(device: frontCamera!) { (captureDevice, videoFormat, error) in
  // Error
}

Then include this in the initial list of tracks in the connectOptions. I also changed the "Screen" track to have isScreencast: false, though not sure if that matters.

JS Modify it so that:

Connect immediately on page load, skipping the modal (hardcode the room name)
Look for incoming video tracks called Camera and Screen and put them both on the DOM
Check for the error condition and refresh, as I described in my last update.

I put up a fork for the JS. Here's a commit that does that: https://github.com/patricktemple/video-quickstart-js/commit/cf746fe144ef6fbd3e4e267980f5c8ba4684fa2a

It hardcodes the room name to "test" so make sure the iOS access token uses the same room name.

I ran this with Chrome logging on and the pattern looked mostly the same as in our real app. One difference was that I did see the message receive_rtp_config_ lookup failed occur multiple times when using quickstart, whereas on our app I saw it just once when the bug finally occurred. But other than that, looks similar, and I see lots of the same VideoReceiveStream not connected to a VideoSink. flooding the logs once the bug shows up.

ceaglest commented 4 years ago

Hi @patricktemple,

Thanks for the analysis and detailed instructions for reproducing this bug! Sorry that I have been slow to respond, the iOS Video team has been trying our best to get ready for iOS 14 and iPadOS 14 since WWDC last week.

Our next sprint starts Monday, and I'd be happy to reproduce the problem using your patch to ReplayKitExample. I have only just started to digest the information but it seems like a translation issue coupled with the Plan-B iOS SDK (possibly?) adding Tracks in a 2nd answer that is leading to RTP arriving early on unexpected SSRCs. When two unified plan Participants are communicating they only add Tracks in offers which prevents the race from occurring.

Best, Chris

patricktemple commented 4 years ago

Ok sounds great. Thanks for taking a look into this!

patricktemple commented 4 years ago

Hey Chris, any luck reproducing this?

Just a bit more background: since we launched this this iOS<-->JS P2P configuration for customers, our logs in the field showing something like 15% of calls failing to connect, or having partial connection issues like this one. Seems to be a mix of a bunch of different things, besides this bug. See also https://github.com/twilio/twilio-video-ios/issues/88 which covers:

Sometimes iOS fails immediately with roomDidFailToConnect with either a 53000 signalling connection error, or a DNS error. This one seems related to the network and happens repeatedly when the affected users retry.
Sometimes the iOS client connects, but it takes a long time for these remote participant tracks to appear on web (like 15s), or not all the tracks ever show up.

I suspect there's a bunch of different issues here, some related to the customers' network, some which might be SDK bugs. I'm just trying to chip away at these bugs to make the whole thing much more reliable.

Thanks, Patrick

ceaglest commented 4 years ago

Hey Patrick,

Yes we can reproduce this specific issue internally. For the iOS signaling connection failures where Participants never connect to the Room, that seems like a different class of problem.

Best, Chris

amaroamaral commented 4 years ago

Hi, @ceaglest @manjeshbhargav @makarandp0 any progress here? Any news about it?

mrancys commented 4 years ago

up

ceaglest commented 3 years ago

Hi,

We are transitioning the mobile SDKs to unified plan, which should resolve scenarios where mobile and JS participants cannot negotiate media properly. I will provide an update on this ticket once new mobile releases are available. No changes to JS should be required.

Best, Chris

makarandp0 commented 3 years ago

Update: We released mobile sdks with unified plan support iOS and android. These versions should address those interop issues.

Please open new issue if you are still seeing these issues on newer versions. Thanks, Makarand

twilio / twilio-video.js

Remote video track is sometimes black #931