search

twilio / video-quickstart-ios

Twilio Video Quickstart for iOS
https://www.twilio.com/docs/api/video
MIT License
460 stars 177 forks source link

Audio is not working after waiting for the participant for more then 15 minutes (AGAIN) #531

Closed mialex closed 3 years ago

mialex commented 4 years ago

Description

Hello, some time ago I've posted an issue with the audio which stopped working https://github.com/twilio/video-quickstart-ios/issues/484 at that moment it was fixed and was working, but not with the latest version

Steps to Reproduce & Code & Expected Behavior & Actual Behavior

Please refer to the previous issue to get more details https://github.com/twilio/video-quickstart-ios/issues/484

Versions

All relevant version information for the issue.

Video iOS SDK

TwilioVideo 3.5.0 via CocoaPods

Xcode

11.5

iOS Version

13.5.1

iOS Device

iPad Pro

Logs

...
2020-07-23 18:22:41.867645+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:22:43.792155+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:22:44.193355+0300 EmbeddedMultiVideo[1587:1403987] ERROR:Twilio:[Core](0x16c1af000): Timeout receiving data from sdkgw.us1.twilio.com:443, Timeout.
2020-07-23 18:22:44.194765+0300 EmbeddedMultiVideo[1587:1403987] WARN:Twilio:[Core](0x16c1af000): Error 3 connecting to Insights server: Timeout
2020-07-23 18:22:44.525405+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): Delayed retry after InsightsPublisher error.
2020-07-23 18:22:44.527214+0300 EmbeddedMultiVideo[1587:1404131] INFO:Twilio:[Core](0x16b99f000): Connecting to sdkgw.us1.twilio.com:443.
2020-07-23 18:22:45.220588+0300 EmbeddedMultiVideo[1587:1404131] INFO:Twilio:[Core](0x16b99f000): Connected to sdkgw.us1.twilio.com:443.
2020-07-23 18:22:45.222190+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Invoking cancelled closure.
2020-07-23 18:22:45.458802+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): Invoking cancelled closure.
2020-07-23 18:22:46.373795+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:22:48.593909+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:22:50.878991+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:22:53.396554+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}

...

2020-07-23 18:23:41.057772+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:23:41.436623+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:23:45.221489+0300 EmbeddedMultiVideo[1587:1404131] ERROR:Twilio:[Core](0x16b99f000): Timeout receiving data from sdkgw.us1.twilio.com:443, Timeout.
2020-07-23 18:23:45.222148+0300 EmbeddedMultiVideo[1587:1404131] WARN:Twilio:[Core](0x16b99f000): Error 3 connecting to Insights server: Timeout
2020-07-23 18:23:45.223134+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Invoking cancelled closure.
2020-07-23 18:23:45.661802+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:23:46.241706+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:23:50.167335+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:23:51.046509+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}

...

2020-07-23 18:29:36.928579+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:29:40.993239+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:29:41.733879+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:29:44.936943+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Default audio device received AVAudioSessionInterruptionTypeBegan.
2020-07-23 18:29:45.499847+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:29:46.539165+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:29:50.107636+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}```

// The issue happens after this error when we wait for the participant to join the call in 12+ minutes

2020-07-23 18:29:44.936943+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Default audio device received AVAudioSessionInterruptionTypeBegan.

// Here is the log after the participant has joined and audio was not working


2020-07-23 18:36:39.643039+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:36:40.721682+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:36:42.671688+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"body":{"version":2,"type":"update","sid":"RMf37bef6752f5165a38bcda7a5a0047cb","name":"RMf37bef6752f5165a38bcda7a5a0047cb","participant":{"sid":"PAa63d388bcd354b3ed22dd908096973f0","identity":"user-183250-0","tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}],"revision":1,"state":"connected"},"participants":[{"sid":"PAa8e67deab1820bb2f91e4035703db504","identity":"clinic-212-0","tracks":[{"kind":"audio","priority":"standard","id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","enabled":true,"sid":"MT5b13021b181027c6902f38008ebaa932","name":"d8452c13-a48d-4f77-a480-6e1d88cc619f","state":"ready"},{"kind":"video","priority":"standard","id":"78923aef-b816-44f9-8291-0f69fc6720c6","enabled":true,"sid":"MT6839c77744e5540b8fd1c89479345af8","name":"0e7f7dba-d419-4e87-b89a-d9f8b04a4a64","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":4,"tracks":[{"id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","sid":"MT5b13021b181027c6902f38008ebaa932"},{"id":"78923aef-b816-44f9-8291-0f69fc6720c6","sid":"MT6839c77744e5540b8fd1c89479345af8"}]},"published":{"revision":1,"tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}]}},"type":"msg"}
2020-07-23 18:36:42.680998+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): RemoteParticipantImpl::RemoteParticipantImpl: clinic-212-0, sid: PAa8e67deab1820bb2f91e4035703db504
2020-07-23 18:36:42.681491+0300 EmbeddedMultiVideo[1587:1401351] INFO:Twilio:[Core](0x104c69840): Remote participant clinic-212-0 added an audio track with sid: MT5b13021b181027c6902f38008ebaa932, name: d8452c13-a48d-4f77-a480-6e1d88cc619f, enabled: 1, priority: 1
2020-07-23 18:36:42.682086+0300 EmbeddedMultiVideo[1587:1401351] INFO:Twilio:[Core](0x104c69840): Remote participant clinic-212-0 added a video track with sid: MT6839c77744e5540b8fd1c89479345af8, name: 0e7f7dba-d419-4e87-b89a-d9f8b04a4a64, enabled: 1, priority: 1
2020-07-23 18:36:42.682587+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:36:42.683162+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call setObserver
2020-07-23 18:36:42.683582+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteAudioTracks
2020-07-23 18:36:42.684174+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteVideoTracks
2020-07-23 18:36:42.684829+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteDataTracks
2020-07-23 18:36:42.688430+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"body":{"version":2,"type":"update","peer_connections":[{"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2","description":{"type":"offer","sdp":"v=0\r\no=- 4667450949016211130 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS *\r\na=group:BUNDLE audio video\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=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: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=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=mid:audio\r\na=sendrecv\r\na=ice-ufrag:xQjj\r\na=ice-pwd:1I4LWnNyIK9Jk4rh5aZQVDk2\r\na=fingerprint:sha-256 74:10:68:0C:AF:E3:54:26:38:FF:4E:71:FD:24:F8:7A:49:5B:77:F4:B1:F9:77:1E:F4:BF:F9:48:18:D7:46:7B\r\na=ssrc:3159471775 msid:- 41a0e80f-9599-4b4d-a5e0-a498910f1923\r\na=ssrc:3159471775 cname:kPtP7La+h2wuU+au\r\na=ssrc:3159471775 mslabel:-\r\na=ssrc:3159471775 label:41a0e80f-9599-4b4d-a5e0-a498910f1923\r\na=rtcp-mux\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-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=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: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=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\na=setup:actpass\r\na=mid:video\r\na=sendrecv\r\na=ice-ufrag:xQjj\r\na=ice-pwd:1I4LWnNyIK9Jk4rh5aZQVDk2\r\na=fingerprint:sha-256 74:10:68:0C:AF:E3:54:26:38:FF:4E:71:FD:24:F8:7A:49:5B:77:F4:B1:F9:77:1E:F4:BF:F9:48:18:D7:46:7B\r\na=ssrc-group:FID 805607780 2936338424\r\na=ssrc:805607780 msid:- 78923aef-b816-44f9-8291-0f69fc6720c6\r\na=ssrc:805607780 cname:kPtP7La+h2wuU+au\r\na=ssrc:805607780 mslabel:-\r\na=ssrc:805607780 label:78923aef-b816-44f9-8291-0f69fc6720c6\r\na=ssrc:2936338424 msid:- 78923aef-b816-44f9-8291-0f69fc6720c6\r\na=ssrc:2936338424 cname:kPtP7La+h2wuU+au\r\na=ssrc:2936338424 mslabel:-\r\na=ssrc:2936338424 label:78923aef-b816-44f9-8291-0f69fc6720c6\r\na=rtcp-mux\r\n","revision":1}}],"sid":"RMf37bef6752f5165a38bcda7a5a0047cb","name":"RMf37bef6752f5165a38bcda7a5a0047cb","participant":{"sid":"PAa63d388bcd354b3ed22dd908096973f0","identity":"user-183250-0","tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}],"revision":1,"state":"connected"},"participants":[{"sid":"PAa8e67deab1820bb2f91e4035703db504","identity":"clinic-212-0","tracks":[{"kind":"audio","priority":"standard","id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","enabled":true,"sid":"MT5b13021b181027c6902f38008ebaa932","name":"d8452c13-a48d-4f77-a480-6e1d88cc619f","state":"ready"},{"kind":"video","priority":"standard","id":"78923aef-b816-44f9-8291-0f69fc6720c6","enabled":true,"sid":"MT6839c77744e5540b8fd1c89479345af8","name":"0e7f7dba-d419-4e87-b89a-d9f8b04a4a64","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":4,"tracks":[{"id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","sid":"MT5b13021b181027c6902f38008ebaa932"},{"id":"78923aef-b816-44f9-8291-0f69fc6720c6","sid":"MT6839c77744e5540b8fd1c89479345af8"}]},"published":{"revision":1,"tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}]}},"type":"msg"}
2020-07-23 18:36:42.702012+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): API Call getSignalingThread
2020-07-23 18:36:42.702468+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): API Call getSignalingThread
2020-07-23 18:36:42.702836+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): API Call getNetworkingThread
2020-07-23 18:36:42.703134+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Starting the Network Manager
2020-07-23 18:36:42.703845+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): API Call RTCIceServers
2020-07-23 18:36:42.704045+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): API Call RTCIceServer
2020-07-23 18:36:42.704713+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): API Call RTCIceServer
2020-07-23 18:36:42.710066+0300 EmbeddedMultiVideo[1587:1401533] INFO:Twilio:[Core](MediaFactoryImpl::signaling): Creating peer connection ...
2020-07-23 18:36:42.711745+0300 EmbeddedMultiVideo[1587:1401533] INFO:Twilio:[Core](MediaFactoryImpl::signaling): Adding local stream to peer connection ...
2020-07-23 18:36:42.712484+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Open -> Updating. Process an event
2020-07-23 18:36:42.712693+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Process remote offer.
2020-07-23 18:36:42.712838+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Process remote sdp for: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 revision is: 1.
2020-07-23 18:36:42.716533+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Applying remote description to: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 rev: 1
2020-07-23 18:36:42.724096+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): onSetSessionRemoteDescription: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:42.724405+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Create local answer: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:42.724881+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Including track id: C0b88dC9FAaa85bc4f5d6eC7AA6BDCec for simulcast.
2020-07-23 18:36:42.724976+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): onCreateSessionLocalDescription 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:42.725167+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): SDP Utils: New SSRC group 821806621 41496782 --- Original SSRC group 821806621 41496782
2020-07-23 18:36:42.725660+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Applying local description to: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 rev: 1
2020-07-23 18:36:42.756914+0300 EmbeddedMultiVideo[1587:1401535] DEBUG:Twilio:[Core](MediaFactoryImpl::networking): void twilio::media::NetworkMonitor::onNetworksChanged()
2020-07-23 18:36:42.756985+0300 EmbeddedMultiVideo[1587:1401535] DEBUG:Twilio:[Core](MediaFactoryImpl::networking): Network Name: en0, Cost: 10, Type : Wifi, Preference : 127, Active : 1, id: 1, prefix : 172.26.98.0 and key : en0%172.26.98.0/24
2020-07-23 18:36:42.757022+0300 EmbeddedMultiVideo[1587:1401535] DEBUG:Twilio:[Core](MediaFactoryImpl::networking): Network Name: en2, Cost: 10, Type : Wifi, Preference : 126, Active : 1, id: 2, prefix : 169.254.0.0 and key : en2%169.254.0.0/16
2020-07-23 18:36:42.757056+0300 EmbeddedMultiVideo[1587:1401535] DEBUG:Twilio:[Core](MediaFactoryImpl::networking): Network Name: lo0, Cost: 0, Type : Loopback, Preference : 125, Active : 1, id: 4, prefix : ::1 and key : lo0%::1/128
2020-07-23 18:36:42.757088+0300 EmbeddedMultiVideo[1587:1401535] DEBUG:Twilio:[Core](MediaFactoryImpl::networking): Network Name: lo0, Cost: 0, Type : Loopback, Preference : 124, Active : 1, id: 3, prefix : 127.0.0.0 and key : lo0%127.0.0.0/8
2020-07-23 18:36:42.758318+0300 EmbeddedMultiVideo[1587:1401535] DEBUG:Twilio:[Core](MediaFactoryImpl::networking): New preferred network reported by manager: key: en0%172.26.98.0/24, type: Wifi, cost: 10, id: 1
2020-07-23 18:36:42.765970+0300 EmbeddedMultiVideo[1587:1402390] INFO:Twilio:[Core](0x16ba2b000): Local answer is ready for 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.766066+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Queue Description: 1 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.766254+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): onSetSessionLocalDescription: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:42.766302+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Starting ICE Gathering timer...
2020-07-23 18:36:42.766369+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Updating -> Open
2020-07-23 18:36:42.766440+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Done processing onSetSessionLocalDescription: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:42.766479+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (4083 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"description":{"revision":1,"sdp":"v=0\r\no=- 3168127018362843138 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio video\r\na=msid-semantic: WMS 18847C3eDC98B2fFAB455AdD076cDCad\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:WLiU\r\na=ice-pwd:j9rpAPYqL1RfTE/P617hchiC\r\na=ice-options:trickle\r\na=fingerprint:sha-256 51:2B:96:91:40:7F:CE:DB:AC:DB:E4:7B:A4:71:AE:4C:8E:2B:BC:FF:F9:D5:8A:AC:D8:67:A9:EC:4B:63:FF:E1\r\na=setup:active\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=sendrecv\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:1296475868 cname:mVZIjKtkXFgvPIWx\r\na=ssrc:1296475868 msid:18847C3eDC98B2fFAB455AdD076cDCad D422a8Cd9e794Cb08bD6E12b1c60c8e2\r\na=ssrc:1296475868 mslabel:18847C3eDC98B2fFAB455AdD076cDCad\r\na=ssrc:1296475868 label:D422a8Cd9e794Cb08bD6E12b1c60c8e2\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-ufrag:WLiU\r\na=ice-pwd:j9rpAPYqL1RfTE/P617hchiC\r\na=ice-options:trickle\r\na=fingerprint:sha-256 51:2B:96:91:40:7F:CE:DB:AC:DB:E4:7B:A4:71:AE:4C:8E:2B:BC:FF:F9:2020-07-23 18:36:42.766497+0300 EmbeddedMultiVideo[1587:1402390] INFO:Twilio:[Core](0x16ba2b000): Ice Gathering for 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
D5:8A:AC:D8:67:A9:EC:4B:63:FF:E1\r\na=setup:active\r\na=mid:video\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=sendrecv\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: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=ssrc-group:FID 821806621 41496782\r\na=ssrc:821806621 cname:mVZIjKtkXFgvPIWx\r\na=ssrc:821806621 msid:18847C3eDC98B2fFAB455AdD076cDCad C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\na=ssrc:821806621 mslabel:18847C3eDC98B2fFAB455AdD076cDCad\r\na=ssrc:821806621 label:C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\na=ssrc:41496782 cname:mVZIjKtkXFgvPIWx\r\na=ssrc:41496782 msid:18847C3eDC98B2fFAB455AdD076cDCad C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\na=ssrc:41496782 mslabel:18847C3eDC98B2fFAB455AdD076cDCad\r\na=ssrc:41496782 label:C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\n","type":"answer"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda72020-07-23 18:36:42.766586+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Gathered a candidate. candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 ufrag WLiU network-id 1 network-cost 10
a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:42.766667+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Queue ICE candidate revision: 1 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.766880+0300 EmbeddedMultiVideo[1587:1402390] INFO:Twilio:[Core](0x16ba2b000): Publish ICE candidate revision: 1 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.767020+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Gathered a candidate. candidate:1874525586 1 udp 2122194687 169.254.183.216 60675 typ host generation 0 ufrag WLiU network-id 2 network-cost 10
2020-07-23 18:36:42.767317+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Queue ICE candidate revision: 2 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.767398+0300 EmbeddedMultiVideo[1587:1402390] INFO:Twilio:[Core](0x16ba2b000): Publish ICE candidate revision: 2 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.767475+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (762 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"}],"complete":false,"revision":1,"ufrag":"WLiU"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda7a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:42.767788+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (935 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:1874525586 1 udp 2122194687 169.254.183.216 60675 typ host generation 0 ufrag WLiU network-id 2 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"}],"complete":false,"revision":2,"ufrag":"WLiU"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda7a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:42.871157+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Gathered a candidate. candidate:160733099 1 udp 1686052607 194.105.145.87 60271 typ srflx raddr 172.26.98.26 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10
2020-07-23 18:36:42.871256+0300 EmbeddedMultiVideo[1587:1402390] DEBUG:Twilio:[Core](0x16ba2b000): Queue ICE candidate revision: 3 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.871339+0300 EmbeddedMultiVideo[1587:1402390] INFO:Twilio:[Core](0x16ba2b000): Publish ICE candidate revision: 3 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.871981+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (1138 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:1874525586 1 udp 2122194687 169.254.183.216 60675 typ host generation 0 ufrag WLiU network-id 2 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:160733099 1 udp 1686052607 194.105.145.87 60271 typ srflx raddr 172.26.98.26 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"}],"complete":false,"revision":3,"ufrag":"WLiU"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda7a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:42.938412+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:36:42.938563+0300 EmbeddedMultiVideo[1587:1401351] Participant clinic-212-0 connected with 1 audio and 1 video tracks
2020-07-23 18:36:42.942346+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:36:42.942903+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Did move to window with size: {834, 1112}.
Metal content scale factor is now: 2.000
2020-07-23 18:36:42.945516+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call isTrackEnabled
2020-07-23 18:36:42.945581+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackName
2020-07-23 18:36:42.945629+0300 EmbeddedMultiVideo[1587:1401351] INFO:Twilio:[Core](0x104c69840): Subscribed to Participant's clinic-212-0 audio track with sid MT5b13021b181027c6902f38008ebaa932
2020-07-23 18:36:42.945688+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:36:42.946589+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:36:42.946696+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:36:42.946792+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:36:42.946945+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getWebRtcTrack
2020-07-23 18:36:42.947808+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackName
2020-07-23 18:36:42.947948+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:36:42.948002+0300 EmbeddedMultiVideo[1587:1401351] Subscribed to d8452c13-a48d-4f77-a480-6e1d88cc619f audio track for Participant clinic-212-0
2020-07-23 18:36:42.948037+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:36:42.948203+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call setTrackSubscribed
2020-07-23 18:36:42.948312+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call isTrackEnabled
2020-07-23 18:36:42.948387+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackName
2020-07-23 18:36:42.948546+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call setTrack
2020-07-23 18:36:42.948582+0300 EmbeddedMultiVideo[1587:1401351] INFO:Twilio:[Core](0x104c69840): Subscribed to Participant's clinic-212-0 video track with sid MT6839c77744e5540b8fd1c89479345af8
2020-07-23 18:36:42.948615+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:36:42.948659+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:36:42.948807+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:36:42.948847+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:36:42.948895+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getWebRtcTrack
2020-07-23 18:36:42.948999+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackName
2020-07-23 18:36:42.949091+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:36:42.949129+0300 EmbeddedMultiVideo[1587:1401351] Subscribed to 0e7f7dba-d419-4e87-b89a-d9f8b04a4a64 video track for Participant clinic-212-0
2020-07-23 18:36:42.949199+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:36:42.949270+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getWebRtcTrack
2020-07-23 18:36:42.949350+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getWebRtcTrack
2020-07-23 18:36:42.984250+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"body":{"version":2,"type":"update","peer_connections":[{"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2","description":{"type":"create-offer","revision":2}}],"sid":"RMf37bef6752f5165a38bcda7a5a0047cb","name":"RMf37bef6752f5165a38bcda7a5a0047cb","participant":{"sid":"PAa63d388bcd354b3ed22dd908096973f0","identity":"user-183250-0","tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}],"revision":1,"state":"connected"},"participants":[],"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":4,"tracks":[{"id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","sid":"MT5b13021b181027c6902f38008ebaa932"},{"id":"78923aef-b816-44f9-8291-0f69fc6720c6","sid":"MT6839c77744e5540b8fd1c89479345af8"}]}},"type":"msg"}
2020-07-23 18:36:42.984979+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Open -> Updating. Process an event
2020-07-23 18:36:42.985133+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Create local offer: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:42.985569+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Including track id: C0b88dC9FAaa85bc4f5d6eC7AA6BDCec for simulcast.
2020-07-23 18:36:42.985671+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): onCreateSessionLocalDescription 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:42.985941+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): SDP Utils: New SSRC 821806621 replaced with Old SSRC 821806621
2020-07-23 18:36:42.986065+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): SDP Utils: New SSRC 41496782 replaced with Old SSRC 41496782
2020-07-23 18:36:42.986223+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): SDP Utils: New SSRC 1296475868 replaced with Old SSRC 1296475868
2020-07-23 18:36:42.986377+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): SDP Utils: New SSRC group 821806621 41496782 --- Original SSRC group 821806621 41496782
2020-07-23 18:36:42.986897+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Updating -> Open
2020-07-23 18:36:42.986946+0300 EmbeddedMultiVideo[1587:1403988] INFO:Twilio:[Core](0x16c23b000): Local offer is ready for 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.986985+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Queue Description: 3 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:42.987317+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (4470 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"description":{"revision":3,"sdp":"v=0\r\no=- 3168127018362843138 3 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio video\r\na=msid-semantic: WMS 18847C3eDC98B2fFAB455AdD076cDCad\r\nm=audio 60271 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 194.105.145.87\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:1874525586 1 udp 2122194687 169.254.183.216 60675 typ host generation 0 network-id 2 network-cost 10\r\na=candidate:160733099 1 udp 1686052607 194.105.145.87 60271 typ srflx raddr 172.26.98.26 rport 60271 generation 0 network-id 1 network-cost 10\r\na=ice-ufrag:WLiU\r\na=ice-pwd:j9rpAPYqL1RfTE/P617hchiC\r\na=ice-options:trickle\r\na=fingerprint:sha-256 51:2B:96:91:40:7F:CE:DB:AC:DB:E4:7B:A4:71:AE:4C:8E:2B:BC:FF:F9:D5:8A:AC:D8:67:A9:EC:4B:63:FF:E1\r\na=setup:actpass\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=sendrecv\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:1296475868 cname:mVZIjKtkXFgvPIWx\r\na=ssrc:1296475868 msid:18847C3eDC98B2fFAB455AdD076cDCad D422a8Cd9e794Cb08bD6E12b1c60c8e2\r\na=ssrc:1296475868 mslabel:18847C3eDC98B2fFAB455AdD076cDCad\r\na=ssrc:1296475868 label:D422a8Cd9e794Cb08bD6E12b1c60c8e2\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-ufrag:WLiU\r\na=ice-pwd:j9rpAPYqL1RfTE/P617hchiC\r\na=ice-options:trickle\r\na=fingerprint:sha-256 51:2B:96:91:40:7F:CE:DB:AC:DB:E4:7B:A4:71:AE:4C:8E:2B:BC:FF:F9:D5:8A:AC:D8:67:A9:EC:4B:63:FF:E1\r\na=setup:actpass\r\na=mid:video\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=sendrecv\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: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=ssrc-group:FID 821806621 41496782\r\na=ssrc:821806621 cname:mVZIjKtkXFgvPIWx\r\na=ssrc:821806621 msid:18847C3eDC98B2fFAB455AdD076cDCad C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\na=ssrc:821806621 mslabel:18847C3eDC98B2fFAB455AdD076cDCad\r\na=ssrc:821806621 label:C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\na=ssrc:41496782 cname:mVZIjKtkXFgvPIWx\r\na=ssrc:41496782 msid:18847C3eDC98B2fFAB455AdD076cDCad C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\na=ssrc:41496782 mslabel:18847C3eDC98B2fFAB455AdD076cDCad\r\na=ssrc:41496782 label:C0b88dC9FAaa85bc4f5d6eC7AA6BDCec\r\n","type":"offer"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda7a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:43.086931+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Gathered a candidate. candidate:4208617566 1 udp 41885695 18.195.48.239 38483 typ relay raddr 194.105.145.87 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10
2020-07-23 18:36:43.087191+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Queue ICE candidate revision: 4 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:43.087359+0300 EmbeddedMultiVideo[1587:1403988] INFO:Twilio:[Core](0x16c23b000): Publish ICE candidate revision: 4 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:43.088915+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (1341 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:1874525586 1 udp 2122194687 169.254.183.216 60675 typ host generation 0 ufrag WLiU network-id 2 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:160733099 1 udp 1686052607 194.105.145.87 60271 typ srflx raddr 172.26.98.26 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:4208617566 1 udp 41885695 18.195.48.239 38483 typ relay raddr 194.105.145.87 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"}],"complete":false,"revision":4,"ufrag":"WLiU"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda7a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:43.124786+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"body":{"version":2,"type":"update","peer_connections":[{"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2","ice":{"ufrag":"xQjj","revision":1,"candidates":[{"candidate":"candidate:705695061 1 udp 2122260223 192.168.33.254 53793 typ host generation 0 ufrag xQjj network-id 5 network-cost 50","sdpMid":"audio","sdpMLineIndex":0}]}}],"sid":"RMf37bef6752f5165a38bcda7a5a0047cb","name":"RMf37bef6752f5165a38bcda7a5a0047cb","participant":{"sid":"PAa63d388bcd354b3ed22dd908096973f0","identity":"user-183250-0","tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}],"revision":1,"state":"connected"},"participants":[{"sid":"PAa8e67deab1820bb2f91e4035703db504","identity":"clinic-212-0","tracks":[{"kind":"audio","priority":"standard","id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","enabled":true,"sid":"MT5b13021b181027c6902f38008ebaa932","name":"d8452c13-a48d-4f77-a480-6e1d88cc619f","state":"ready"},{"kind":"video","priority":"standard","id":"78923aef-b816-44f9-8291-0f69fc6720c6","enabled":true,"sid":"MT6839c77744e5540b8fd1c89479345af8","name":"0e7f7dba-d419-4e87-b89a-d9f8b04a4a64","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":4,"tracks":[{"id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","sid":"MT5b13021b181027c6902f38008ebaa932"},{"id":"78923aef-b816-44f9-8291-0f69fc6720c6","sid":"MT6839c77744e5540b8fd1c89479345af8"}]},"published":{"revision":1,"tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}]}},"type":"msg"}
2020-07-23 18:36:43.130884+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Adding 1 ICE candidate(s).
2020-07-23 18:36:43.132865+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): ICE connection state transitioned from New -> Checking
2020-07-23 18:36:43.133135+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): PeerConnection 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 Ice connection state transitioned from New -> Checking
2020-07-23 18:36:43.133680+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Room Media state changed to Checking
2020-07-23 18:36:43.133956+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Ice Connection state checking. Stopping Ice gathering timer.
2020-07-23 18:36:43.220176+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Gathered a candidate. candidate:2865289691 1 udp 8331007 18.195.48.239 47875 typ relay raddr 194.105.145.87 rport 51773 generation 0 ufrag WLiU network-id 1 network-cost 10
2020-07-23 18:36:43.220646+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Queue ICE candidate revision: 5 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:43.221264+0300 EmbeddedMultiVideo[1587:1403988] INFO:Twilio:[Core](0x16c23b000): Publish ICE candidate revision: 5 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:43.223776+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (1543 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:1874525586 1 udp 2122194687 169.254.183.216 60675 typ host generation 0 ufrag WLiU network-id 2 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:160733099 1 udp 1686052607 194.105.145.87 60271 typ srflx raddr 172.26.98.26 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:4208617566 1 udp 41885695 18.195.48.239 38483 typ relay raddr 194.105.145.87 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:2865289691 1 udp 8331007 18.195.48.239 47875 typ relay raddr 194.105.145.87 rport 51773 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"}],"complete":false,"revision":5,"ufrag":"WLiU"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda7a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:43.282194+0300 EmbeddedMultiVideo[1587:1403988] INFO:Twilio:[Core](0x16c23b000): Ice Gathering Complete for 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:43.284986+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Queue ICE candidate revision: 6 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:43.286379+0300 EmbeddedMultiVideo[1587:1403988] INFO:Twilio:[Core](0x16c23b000): Publish ICE candidate revision: 6 for PeerConnection: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2.
2020-07-23 18:36:43.291630+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (1542 bytes):
{"body":{"participant":{"revision":1,"tracks":[{"enabled":true,"id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","kind":"video","name":"Camera","priority":"standard"},{"enabled":true,"id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","kind":"audio","name":"Microphone","priority":"standard"}]},"peer_connections":[{"ice":{"candidates":[{"candidate":"candidate:4286901887 1 udp 2122260223 172.26.98.26 60271 typ host generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:1874525586 1 udp 2122194687 169.254.183.216 60675 typ host generation 0 ufrag WLiU network-id 2 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:160733099 1 udp 1686052607 194.105.145.87 60271 typ srflx raddr 172.26.98.26 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:4208617566 1 udp 41885695 18.195.48.239 38483 typ relay raddr 194.105.145.87 rport 60271 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"},{"candidate":"candidate:2865289691 1 udp 8331007 18.195.48.239 47875 typ relay raddr 194.105.145.87 rport 51773 generation 0 ufrag WLiU network-id 1 network-cost 10","sdpMLineIndex":0,"sdpMid":"audio"}],"complete":true,"revision":6,"ufrag":"WLiU"},"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2"}],"session":"4c8fef3f873f245e1b0dff0283ca5522f37bef6752f5165a38bcda7a5a0047cba63d388bcd354b3ed22dd908096973f0967b7ef80961f943d42616a461e57b70","type":"update","version":2},"type":"msg"}
2020-07-23 18:36:43.308459+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): ICE connection state transitioned from Checking -> Connected
2020-07-23 18:36:43.308718+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): PeerConnection 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 Ice connection state transitioned from Checking -> Connected
2020-07-23 18:36:43.308991+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Room Media state changed to Connected
2020-07-23 18:36:43.309482+0300 EmbeddedMultiVideo[1587:1403988] INFO:Twilio:[Core](0x16c23b000): Media is connected
2020-07-23 18:36:43.310066+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Starting the media monitor - call getPeerConnectionStats
2020-07-23 18:36:43.312442+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Media for PeerConnection 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 transitioned from MediaInactive -> MediaActive
2020-07-23 18:36:43.312709+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): PeerConnection 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 Media activity state transitioned from MediaInactive -> MediaActive
2020-07-23 18:36:43.346944+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Preparing renderer for frame size: {640, 480}, view bounds: {{0, 0}, {834, 1092}}
2020-07-23 18:36:43.347216+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Detected a pixel format change. Preparing the rendering pipeline. Old = none, New = f420
2020-07-23 18:36:43.347808+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Texture caching failed with code: -6660, fallback to copy and upload path.
2020-07-23 18:36:43.356997+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"body":{"version":2,"type":"update","peer_connections":[{"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2","description":{"type":"answer","sdp":"v=0\r\no=- 4667450949016211130 3 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS *\r\na=group:BUNDLE audio video\r\nm=audio 52048 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126\r\nc=IN IP4 194.105.145.94\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:705695061 1 udp 2122260223 192.168.33.254 53793 typ host generation 0 network-id 5 network-cost 50\r\na=candidate:1308951304 1 udp 2122194687 172.26.36.13 52048 typ host generation 0 network-id 2\r\na=candidate:1646753361 1 udp 2122129151 169.254.118.136 55694 typ host generation 0 network-id 3\r\na=candidate:3553770417 1 udp 2122063615 172.26.36.14 61775 typ host generation 0 network-id 1 network-cost 10\r\na=candidate:1103076178 1 udp 2121998079 172.27.225.46 54511 typ host generation 0 network-id 4 network-cost 50\r\na=candidate:3088415452 1 udp 1685987071 194.105.145.94 52048 typ srflx raddr 172.26.36.13 rport 52048 generation 0 network-id 2\r\na=candidate:633487973 1 udp 1685855999 194.105.145.94 61775 typ srflx raddr 172.26.36.14 rport 61775 generation 0 network-id 1 network-cost 10\r\na=candidate:1687417253 1 tcp 1518280447 192.168.33.254 9 typ host tcptype active generation 0 network-id 5 network-cost 50\r\na=candidate:8847352 1 tcp 1518214911 172.26.36.13 9 typ host tcptype active generation 0 network-id 2\r\na=candidate:748980897 1 tcp 1518149375 169.254.118.136 9 typ host tcptype active generation 0 network-id 3\r\na=candidate:2639382337 1 tcp 1518083839 172.26.36.14 9 typ host tcptype active generation 0 network-id 1 network-cost 10\r\na=candidate:255616930 1 tcp 1518018303 172.27.225.46 9 typ host tcptype active generation 0 network-id 4 network-cost 50\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:passive\r\na=mid:audio\r\na=sendrecv\r\na=ice-ufrag:xQjj\r\na=ice-pwd:1I4LWnNyIK9Jk4rh5aZQVDk2\r\na=fingerprint:sha-256 74:10:68:0C:AF:E3:54:26:38:FF:4E:71:FD:24:F8:7A:49:5B:77:F4:B1:F9:77:1E:F4:BF:F9:48:18:D7:46:7B\r\na=ssrc:3159471775 msid:- 41a0e80f-9599-4b4d-a5e0-a498910f1923\r\na=ssrc:3159471775 cname:kPtP7La+h2wuU+au\r\na=rtcp-mux\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=fmtp:98 profile-id=0\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:passive\r\na=mid:video\r\na=sendrecv\r\na=ice-ufrag:xQjj\r\na=ice-pwd:1I4LWnNyIK9Jk4rh5aZQVDk2\r\na=fingerprint:sha-256 74:10:68:0C:AF:E3:54:26:38:FF:4E:71:FD:24:F8:7A:49:5B:77:F4:B1:F9:77:1E:F4:BF:F9:48:18:D7:46:7B\r\na=ssrc-group:FID 805607780 2936338424\r\na=ssrc:805607780 msid:- 78923aef-b816-44f9-8291-0f69fc6720c6\r\na=ssrc:805607780 cname:kPtP7La+h2wuU+au\r\na=ssrc:2936338424 msid:- 78923aef-b816-44f9-8291-0f69fc6720c6\r\na=ssrc:2936338424 cname:kPtP7La+h2wuU+au\r\na=rtcp-mux\r\n","revision":3},"ice":{"ufrag":"xQjj","revision":12,"candidates":[{"candidate":"candidate:705695061 1 udp 2122260223 192.168.33.254 53793 typ host generation 0 ufrag xQjj network-id 5 network-cost 50","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:1308951304 1 udp 2122194687 172.26.36.13 52048 typ host generation 0 ufrag xQjj network-id 2","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:1646753361 1 udp 2122129151 169.254.118.136 55694 typ host generation 0 ufrag xQjj network-id 3","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:3553770417 1 udp 2122063615 172.26.36.14 61775 typ host generation 0 ufrag xQjj network-id 1 network-cost 10","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:1103076178 1 udp 2121998079 172.27.225.46 54511 typ host generation 0 ufrag xQjj network-id 4 network-cost 50","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:3088415452 1 udp 1685987071 194.105.145.94 52048 typ srflx raddr 172.26.36.13 rport 52048 generation 0 ufrag xQjj network-id 2","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:633487973 1 udp 1685855999 194.105.145.94 61775 typ srflx raddr 172.26.36.14 rport 61775 generation 0 ufrag xQjj network-id 1 network-cost 10","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:1687417253 1 tcp 1518280447 192.168.33.254 9 typ host tcptype active generation 0 ufrag xQjj network-id 5 network-cost 50","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:8847352 1 tcp 1518214911 172.26.36.13 9 typ host tcptype active generation 0 ufrag xQjj network-id 2","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:748980897 1 tcp 1518149375 169.254.118.136 9 typ host tcptype active generation 0 ufrag xQjj network-id 3","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:2639382337 1 tcp 1518083839 172.26.36.14 9 typ host tcptype active generation 0 ufrag xQjj network-id 1 network-cost 10","sdpMid":"audio","sdpMLineIndex":0},{"candidate":"candidate:255616930 1 tcp 1518018303 172.27.225.46 9 typ host tcptype active generation 0 ufrag xQjj network-id 4 network-cost 50","sdpMid":"audio","sdpMLineIndex":0}]}}],"sid":"RMf37bef6752f5165a38bcda7a5a0047cb","name":"RMf37bef6752f5165a38bcda7a5a0047cb","participant":{"sid":"PAa63d388bcd354b3ed22dd908096973f0","identity":"user-183250-0","tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}],"revision":1,"state":"connected"},"participants":[{"sid":"PAa8e67deab1820bb2f91e4035703db504","identity":"clinic-212-0","tracks":[{"kind":"audio","priority":"standard","id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","enabled":true,"sid":"MT5b13021b181027c6902f38008ebaa932","name":"d8452c13-a48d-4f77-a480-6e1d88cc619f","state":"ready"},{"kind":"video","priority":"standard","id":"78923aef-b816-44f9-8291-0f69fc6720c6","enabled":true,"sid":"MT6839c77744e5540b8fd1c89479345af8","name":"0e7f7dba-d419-4e87-b89a-d9f8b04a4a64","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":4,"tracks":[{"id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","sid":"M
2020-07-23 18:36:43.368151+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): Open -> Updating. Process an event
2020-07-23 18:36:43.368281+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): Process remote answer at revision 3.
2020-07-23 18:36:43.368357+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): Process remote sdp for: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 revision is: 3.
2020-07-23 18:36:43.369878+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Applying local description to: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 rev: 3
2020-07-23 18:36:43.372184+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Adding 11 ICE candidate(s).
2020-07-23 18:36:43.378551+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): onSetSessionLocalDescription: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:43.378692+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Applying remote description to: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 rev: 3
2020-07-23 18:36:43.380490+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): Done processing onSetSessionLocalDescription: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:43.380941+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): onSetSessionRemoteDescription: 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:36:43.381759+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Core](0x16b76f000): Updating -> Open
2020-07-23 18:36:43.427172+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"body":{"version":2,"type":"update","peer_connections":[{"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2","ice":{"ufrag":"xQjj","revision":13,"complete":true}}],"sid":"RMf37bef6752f5165a38bcda7a5a0047cb","name":"RMf37bef6752f5165a38bcda7a5a0047cb","participant":{"sid":"PAa63d388bcd354b3ed22dd908096973f0","identity":"user-183250-0","tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}],"revision":1,"state":"connected"},"participants":[{"sid":"PAa8e67deab1820bb2f91e4035703db504","identity":"clinic-212-0","tracks":[{"kind":"audio","priority":"standard","id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","enabled":true,"sid":"MT5b13021b181027c6902f38008ebaa932","name":"d8452c13-a48d-4f77-a480-6e1d88cc619f","state":"ready"},{"kind":"video","priority":"standard","id":"78923aef-b816-44f9-8291-0f69fc6720c6","enabled":true,"sid":"MT6839c77744e5540b8fd1c89479345af8","name":"0e7f7dba-d419-4e87-b89a-d9f8b04a4a64","state":"ready"}],"revision":3,"state":"connected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":4,"tracks":[{"id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","sid":"MT5b13021b181027c6902f38008ebaa932"},{"id":"78923aef-b816-44f9-8291-0f69fc6720c6","sid":"MT6839c77744e5540b8fd1c89479345af8"}]},"published":{"revision":1,"tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}]}},"type":"msg"}
2020-07-23 18:36:43.428507+0300 EmbeddedMultiVideo[1587:1403988] WARN:Twilio:[Core](0x16c23b000): Won't return Ice update since it contains fewer candidates then the previous revision.
2020-07-23 18:36:45.259089+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:36:48.096159+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:36:49.758979+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}

After that, I put the task to the background

2020-07-23 18:36:52.901110+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:36:54.358184+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:36:57.087178+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Rendering inactive scene at 30 fps.
2020-07-23 18:36:57.087415+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Rendering inactive scene at 30 fps.
2020-07-23 18:36:57.169899+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): AVCaptureSession interrupted with reason: 1
2020-07-23 18:36:57.170155+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): ... Capture pipeline did stop.
2020-07-23 18:36:57.170215+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Platform](0x16b887000): Teardown video output pipeline ...
2020-07-23 18:36:57.170309+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Platform](0x16b887000): Finished video output pipeline teardown.
2020-07-23 18:36:57.170428+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Platform](0x16b887000): Video pipeline did finish running
2020-07-23 18:36:57.170806+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): AVCaptureSession stopped running
2020-07-23 18:36:57.674301+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Scene did enter background. Pausing rendering.
2020-07-23 18:36:57.674391+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Scene did enter background. Pausing rendering.
2020-07-23 18:36:57.727411+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:36:57.789560+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Invoking cancelled closure.
2020-07-23 18:36:59.054426+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}

2020-07-23 18:37:02.164868+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Application will enter foreground.
2020-07-23 18:37:02.164942+0300 EmbeddedMultiVideo[1587:1401351] INFO:Twilio:[Platform](0x104c69840): Manually restarting interrupted session.
2020-07-23 18:37:02.165407+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): AVCaptureSession interrupted with reason: 1
2020-07-23 18:37:02.165452+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): ... Capture pipeline did stop.
2020-07-23 18:37:02.165490+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Platform](0x16b887000): Teardown video output pipeline ...
2020-07-23 18:37:02.165526+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Platform](0x16b887000): Finished video output pipeline teardown.
2020-07-23 18:37:02.165561+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Platform](0x16b887000): Video pipeline did finish running
2020-07-23 18:37:02.167567+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Platform](0x16b887000): No background task to stop.
2020-07-23 18:37:02.170720+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): AVCaptureSession stopped running
2020-07-23 18:37:02.304208+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): AVCaptureSession interruption ended
2020-07-23 18:37:02.305026+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): Best device format was: <AVCaptureDeviceFormat: 0x281cdc600 'vide'/'420f'  640x 480, { 2- 30 fps}, HRSI:3088x2320, fov:54.201, max zoom:145.00 (upscales @4.82), ISO:23.0-2208.0, SS:0.000013-0.500000, supports wide color>
2020-07-23 18:37:02.305300+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Platform](0x16b76f000): Video pipeline will start running.
2020-07-23 18:37:02.305461+0300 EmbeddedMultiVideo[1587:1402389] INFO:Twilio:[Platform](0x16b887000): AVCaptureSession started running
2020-07-23 18:37:02.305549+0300 EmbeddedMultiVideo[1587:1402362] DEBUG:Twilio:[Platform](0x16b76f000): Video device will start running: <AVCaptureFigVideoDevice: 0x109158830 [Front Camera][com.apple.avfoundation.avcapturedevice.built-in_video:1]>.
2020-07-23 18:37:02.533343+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:37:02.700299+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Rendering active scene at 60 fps.
2020-07-23 18:37:02.700431+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Rendering active scene at 60 fps.
2020-07-23 18:37:02.701297+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Default audio device received UIApplicationDidBecomeActiveNotification.
2020-07-23 18:37:02.735923+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Preparing renderer for frame size: {480, 640}, view bounds: {{0, 2}, {179, 239}}
2020-07-23 18:37:02.737203+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Preparing renderer for frame size: {640, 480}, view bounds: {{0, 0}, {834, 1092}}
2020-07-23 18:37:03.556257+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}

Once the task was gotten back to the foreground the audio was working

2020-07-23 18:37:07.341687+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:37:08.065773+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}
2020-07-23 18:37:09.572029+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Core](0x16b887000): ICE connection state transitioned from Connected -> Disconnected
2020-07-23 18:37:09.572218+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Core](0x16b887000): PeerConnection 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 Ice connection state transitioned from Connected -> Disconnected
2020-07-23 18:37:09.572322+0300 EmbeddedMultiVideo[1587:1402389] DEBUG:Twilio:[Core](0x16b887000): Room Media state changed to Disconnected
2020-07-23 18:37:09.572470+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): bool twilio::media::IceConnectionActivityMonitor::isRunning()
2020-07-23 18:37:09.572638+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): void twilio::media::IceConnectionActivityMonitor::getStats()
2020-07-23 18:37:09.705644+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"body":{"version":2,"type":"update","peer_connections":[{"id":"6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2","description":{"type":"close","revision":4}}],"sid":"RMf37bef6752f5165a38bcda7a5a0047cb","name":"RMf37bef6752f5165a38bcda7a5a0047cb","participant":{"sid":"PAa63d388bcd354b3ed22dd908096973f0","identity":"user-183250-0","tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}],"revision":1,"state":"connected"},"participants":[{"sid":"PAa8e67deab1820bb2f91e4035703db504","identity":"clinic-212-0","tracks":[{"kind":"audio","priority":"standard","id":"41a0e80f-9599-4b4d-a5e0-a498910f1923","enabled":true,"sid":"MT5b13021b181027c6902f38008ebaa932","name":"d8452c13-a48d-4f77-a480-6e1d88cc619f","state":"ready"},{"kind":"video","priority":"standard","id":"78923aef-b816-44f9-8291-0f69fc6720c6","enabled":true,"sid":"MT6839c77744e5540b8fd1c89479345af8","name":"0e7f7dba-d419-4e87-b89a-d9f8b04a4a64","state":"ready"}],"revision":4,"state":"disconnected"}],"recording":{"enabled":false,"revision":1},"subscribe":{"revision":1,"rules":[{"type":"include","all":true}]},"subscribed":{"revision":5,"tracks":[]},"published":{"revision":1,"tracks":[{"kind":"video","priority":"standard","id":"C0b88dC9FAaa85bc4f5d6eC7AA6BDCec","enabled":true,"sid":"MT4e0f80924a7fdda12ff57b9e27e1a882","name":"Camera","state":"ready"},{"kind":"audio","priority":"standard","id":"D422a8Cd9e794Cb08bD6E12b1c60c8e2","enabled":true,"sid":"MTca0e57217455c339e65945ebf2110857","name":"Microphone","state":"ready"}]}},"type":"msg"}
2020-07-23 18:37:09.708997+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Open -> Updating. Process an event
2020-07-23 18:37:09.709003+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.709065+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.709099+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Current Ice connection state: Disconnected
2020-07-23 18:37:09.709106+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:37:09.709139+0300 EmbeddedMultiVideo[1587:1403988] INFO:Twilio:[Core](0x16c23b000): Closing 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 due to signaling channel instruction to close
2020-07-23 18:37:09.709147+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:37:09.709220+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackName
2020-07-23 18:37:09.709342+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:37:09.709481+0300 EmbeddedMultiVideo[1587:1401351] Unsubscribed from d8452c13-a48d-4f77-a480-6e1d88cc619f audio track for Participant clinic-212-0
2020-07-23 18:37:09.709549+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:37:09.709680+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): -[TVIAudioTrack dealloc]
2020-07-23 18:37:09.709784+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.709836+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call setTrackSubscribed
2020-07-23 18:37:09.709992+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.710030+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:37:09.710068+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackSid
2020-07-23 18:37:09.710158+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getTrackName
2020-07-23 18:37:09.710229+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:37:09.710304+0300 EmbeddedMultiVideo[1587:1401351] Unsubscribed from 0e7f7dba-d419-4e87-b89a-d9f8b04a4a64 video track for Participant clinic-212-0
2020-07-23 18:37:09.710384+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:37:09.710492+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getWebRtcTrack
2020-07-23 18:37:09.710529+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getWebRtcTrack
2020-07-23 18:37:09.712785+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): -[TVIVideoTrack dealloc]
2020-07-23 18:37:09.712853+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call setTrack
2020-07-23 18:37:09.712921+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): RemoteParticipantSignaling::~RemoteParticipantSignaling(SID = PAa8e67deab1820bb2f91e4035703db504)
2020-07-23 18:37:09.712974+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:37:09.713008+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:37:09.713042+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:37:09.713102+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getName
2020-07-23 18:37:09.713428+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:37:09.713492+0300 EmbeddedMultiVideo[1587:1401351] Room appointment-3709857, Participant clinic-212-0 disconnected
2020-07-23 18:37:09.713530+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getSid
2020-07-23 18:37:09.713937+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): Did move to window with size: {0, 0}.
Metal content scale factor is now: 0.000
2020-07-23 18:37:09.714143+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): -[TVIRemoteParticipant dealloc]
2020-07-23 18:37:09.714192+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:37:09.714229+0300 EmbeddedMultiVideo[1587:1401351] INFO:Twilio:[Core](0x104c69840): Invalidating remote media of clinic-212-0
2020-07-23 18:37:09.714261+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.714294+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.714325+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): RemoteParticipantImpl::~RemoteParticipantImpl
2020-07-23 18:37:09.714354+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getIdentity
2020-07-23 18:37:09.714410+0300 EmbeddedMultiVideo[1587:1401351] INFO:Twilio:[Core](0x104c69840): Invalidating remote media of clinic-212-0
2020-07-23 18:37:09.714458+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.714547+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Core](0x104c69840): API Call getRemoteTrack
2020-07-23 18:37:09.714807+0300 EmbeddedMultiVideo[1587:1401351] DEBUG:Twilio:[Platform](0x104c69840): -[TVIMetalRenderer dealloc]
2020-07-23 18:37:09.813345+0300 EmbeddedMultiVideo[1587:1401533] INFO:Twilio:[Core](MediaFactoryImpl::signaling): Closed Webrtc PeerConnection 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2
2020-07-23 18:37:09.813426+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): void twilio::media::IceConnectionActivityMonitor::stop()
2020-07-23 18:37:09.813470+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): virtual twilio::media::IceConnectionActivityMonitor::~IceConnectionActivityMonitor()
2020-07-23 18:37:09.813512+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Waiting for any in-progress calls in async_invoker to complete.
2020-07-23 18:37:09.813557+0300 EmbeddedMultiVideo[1587:1401533] DEBUG:Twilio:[Core](MediaFactoryImpl::signaling): Stopping the Network Manager
2020-07-23 18:37:09.814228+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): PeerConnection 6a3d2a5c-4a5c-43cd-ac99-8234481ed4d2 closed. Ice connection state set from Disconnected -> Closed
2020-07-23 18:37:09.814356+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Room Media state changed to Closed
2020-07-23 18:37:09.816130+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): All Peer Connections closed without media failure.
2020-07-23 18:37:09.816255+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): ICE connection state transitioned from Disconnected -> Closed
2020-07-23 18:37:09.816297+0300 EmbeddedMultiVideo[1587:1403988] DEBUG:Twilio:[Core](0x16c23b000): Discarding ice connection state update because our state is closed
2020-07-23 18:37:12.147265+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Sending message (21 bytes):
{"type":"heartbeat"}
2020-07-23 18:37:12.575126+0300 EmbeddedMultiVideo[1587:1401553] DEBUG:Twilio:[Core](0x16bd73000): Received message:
{"type":"heartbeat"}

The question is how to keep the audio? Background Models option Audio, AirPlay, and Picture in Picture is turned on. In the iOS 12 I used another fix

            let session = AVAudioSession.sharedInstance()

            do {
                try session.setActive(false)
            } catch let error as NSError {
                print("Unable to activate audio session:  \(error.localizedDescription)")
            }

But in the version 13+ it does not work and provides an exception 

 [avas] AVAudioSession.mm:997:-[AVAudioSession setActive:withOptions:error:]: Deactivating an audio session that has running I/O. All I/O should be stopped or paused prior to deactivating the audio session.
 Unable to activate audio session:  The operation couldn’t be completed. (OSStatus error 560030580.)
ceaglest commented 4 years ago

Hi @mialex,

But in the version 13+ it does not work and provides an exception

The exception is because you should not configure AVAudioSession directly when using the Video SDK. TVIDefaultAudioDevice has an API where you can specify a block to configure the session. We will call your block as many times as needed while you are in a Room. If you need to start/stop audio you can use the TVIDefaultAudioDevice.isEnabled property.

Please take a look at Configuring Audio Video Inputs and Outputs: Selecting Specific Audio Routes and let me know if that helps to clarify.

Have a good day, Chris

mialex commented 4 years ago

Seems like isEnabled property helped me. What I did.

Under my ViewController I've created a new property var audioDevice: DefaultAudioDevice = DefaultAudioDevice(), then at the beginning of viewDidLoad() method I added the next code TwilioVideoSDK.audioDevice = self.audioDevice. Once the participant was disconnected, I disable audio in participantDidDisconnect(...):

if (room.remoteParticipants.count == 0) {
    self.audioDevice.isEnabled = false
}

Once the participant has connected, I enable it under participantDidConnect() with the next source line self.audioDevice.isEnabled = true.

Seems like for now it's enough, as I was able also to solve the issue with the help of block usage where I could get AVAudioSession.sharedInstance() and inactivate it. but as far as audioDevice.isEnabled option works, I don't see any reason to add extra complicated logic