Closed maisasb closed 9 months ago
sevgit
commented
9 months ago I'm getting the same issue on Chrome 117.0.5938.149 (Windows 11). If it adds any meaningful info, this started for me on v3.18.0 of the SDK.
For me the Audio Video controller seems to try to get started a few times and then just fails.
dinmin-amzn
commented
9 months ago Hello, I'm checking in from the Chime SDK team. If possible, could you provide debug level logs and could you see if you can reproduce the issue using our demo app? Meanwhile, I will try to reproduce this issue on my end and try to track down the error.
ltrung
commented
9 months ago This seems to be where it throws the error https://github.com/aws/amazon-chime-sdk-js/blob/12418f35fe9abb6c5e997343f62126cdcaf31671/src/transceivercontroller/DefaultTransceiverController.ts#L499. If you could put a breakpoint in that line, could you let us know what would be the return for redWorkerCode ?
aguasch1
commented
9 months ago We have tried to upgrade AWS Chime to version 18.1 and we have the same issue. For us the issue only appears after the build, when we run the project locally in development mode everything is fine, but when we do a production build the meetings cannot establish connection.
We have checked the version 18.0 and problem does not appear.
ConsoleLogger.ts:88 2023-10-04T11:18:24.438Z [INFO] Logs - transitioning from Disconnecting to NotConnected with FinishDisconnecting
ConsoleLogger.ts:88 2023-10-04T11:18:24.438Z [INFO] Logs - handling status: TaskFailed
ConsoleLogger.ts:82 2023-10-04T11:18:24.438Z [WARN] Logs - The audio video controller will reconnect due to status code TaskFailed and error: serial group task AudioVideoStart/40c897bc-d2f5-4337-8048-0c7c52c70706/e3aa1693-df8c-ddb8-0abc-1e630b32113e was canceled due to subtask AudioVideoStart/40c897bc-d2f5-4337-8048-0c7c52c70706/e3aa1693-df8c-ddb8-0abc-1e630b32113e/Timeout15000ms error: serial group task AudioVideoStart/40c897bc-d2f5-4337-8048-0c7c52c70706/e3aa1693-df8c-ddb8-0abc-1e630b32113e/Timeout15000ms/Peer was canceled due to subtask AudioVideoStart/40c897bc-d2f5-4337-8048-0c7c52c70706/e3aa1693-df8c-ddb8-0abc-1e630b32113e/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once) error: Cannot read properties of null (reading '1')
maisasb
commented
9 months ago This seems to be where it throws the error
. If you could put a breakpoint in that line, could you let us know what would be the return for
redWorkerCode?
@ltrung yes, this is the point where I get the error with message: "Cannot read properties of null (reading '1')"
The result of redWorkerCode.match(/class\s*(\w+)\s*\{/) is null
The redWorkerCode is
"function RedundantAudioEncoder() {
_classCallCheck(this, RedundantAudioEncoder);
// Each payload must be less than 1024 bytes to fit the 10 bit block length
this.maxRedPacketSizeBytes = 1 << 10;
// Limit payload to 1000 bytes to handle small MTU. 1000 is chosen because in Chromium-based browsers, writing audio
// frames larger than 1000 bytes will cause an error to be thrown. See https://crbug.com/1248479.
this.maxAudioRtpPacketSizeBytes = 1000;
// Each payload can encode a timestamp delta of 14 bits
this.maxRedTimestampOffset = 1 << 14;
// 4 byte RED header
this.redHeaderSizeBytes = 4;
// reduced size for last RED header
this.redLastHeaderSizeBytes = 1;
// P-Time for Opus 20 msec packets
// We do not support other p-times or clock rates
this.redPacketizationTime = 960;
// distance between redundant payloads, Opus FEC handles a distance of 1
// TODO(https://issues.amazon.com/issues/ChimeSDKAudio-55):
// Consider making this dynamic
this.redPacketDistance = 2;
// maximum number of redundant payloads per RTP packet
this.maxRedEncodings = 2;
// Maximum number of encodings that can be recovered with a single RED packet, assuming the primary and redundant
// payloads have FEC.
this.redMaxRecoveryDistance = this.redPacketDistance * this.maxRedEncodings + 1;
// maximum history of prior payloads to keep
// generally we will expire old entries based on timestamp
// this limit is in place just to make sure the history does not
// grow too large in the case of erroneous timestamp inputs
this.maxEncodingHistorySize = 10;
// Current number of encodings we want to send
// to the remote end. This will be dynamically
// updated through the setNumEncodingsFromPacketloss API
this.numRedundantEncodings = 0;
// Used to enable or disable redundancy
// in response to very high packet loss events
this.redundancyEnabled = true;
// Loss stats are reported to the main thread every 5 seconds.
// Since timestamp differences between 2 consecutive packets
// give us the number of samples in each channel, 1 second
// is equivalent to 48000 samples:
// P-time * (1000ms/1s)
// = (960 samples/20ms) * (1000ms/1s)
// = 48000 samples/s
this.lossReportInterval = 48000 * 5;
// Maximum distance of a packet from the most recent packet timestamp
// that we will consider for recovery.
this.maxOutOfOrderPacketDistance = 16;
/**
* Below are Opus helper methods and constants.
*/
this.OPUS_BAD_ARG = -1;
this.OPUS_INVALID_PACKET = -4;
// Max number of Opus frames in an Opus packet is 48 (https://www.rfc-editor.org/rfc/rfc6716#section-3.2.5).
this.OPUS_MAX_OPUS_FRAMES = 48;
// Max number of bytes that any individual Opus frame can have.
this.OPUS_MAX_FRAME_SIZE_BYTES = 1275;
this.encodingHistory = new Array();
this.opusPayloadType = 0;
this.redPayloadType = 0;
this.initializePacketLogs();
}"@maisasb We have a potential fix for this issue in #2772 . Can you pull that branch and verify that it fixes your issue?
maisasb
commented
9 months ago @ltrung I did a link with the branch that you ask in my project, but I got the following error
RedundantAudioEncoder.initializeWorker is not a function
dinmin-amzn
commented
9 months ago @maisasb Is it possible for you to send a link to the repo/branch that you are currently using to test this issue? The discrepancy seems to lie in the different build systems used for your project and our project and I'd like to be able to test the build in your project more reliably.
dinmin-amzn
commented
9 months ago We are still working on a fix for this issue as it seems that the current fix attempt is still not working.
However, for anybody that is currently blocked by this issue, here is a quick fix. The issue is caused by logic in our new Audio Redundancy feature that was released in 3.18.0, but this feature can be disabled anytime before meetingSession.audioVideo.start() is called to prevent encountering this failure. The steps to disable Audio Redundancy can be found here under the Use case 35. Redundant Audio section. To disable Audio Redundancy, just call
meetingSession.audioVideo.setAudioProfile(new AudioProfile(null, false));anytime before meetingSession.audioVideo.start().
dinmin-amzn
commented
9 months ago @maisasb @aguasch1 @sevgit We have updated the potential fix in https://github.com/aws/amazon-chime-sdk-js/pull/2772. Can you again try pulling that branch and verify if it fixes your issue? When testing please do not use this line meetingSession.audioVideo.setAudioProfile(new AudioProfile(null, false)); in your code since we would like to see if the redundant audio worker can actually be created successfully. If possible, could you also provide debug level logs so we can verify if the redundant audio worker was created?
maisasb
commented
9 months ago @dinmin-amzn It worked! 🎉
dinmin-amzn
commented
9 months ago @maisasb Thank you for verifying the fix. Were you able to get any debug logs from your latest test? You can get debug level logs by using the ChimeSDK.LogLevel.DEBUG level when constructing the ChimeSDK.ConsoleLogger. We would just like to see if the redundant audio worker was also successfully created since with the latest fix, it is possible for the meeting to start but for the redundant audio worker to fail creation.
dinmin-amzn
commented
9 months ago @maisasb Did you remove the meetingSession.audioVideo.setAudioProfile(new AudioProfile(null, false)); line from your code? Looking at the logs you posted, it seems like there is no indication that the redundant audio worker is getting started.
maisasb
commented
9 months ago @dinmin-amzn the log
[INFO] ChimeLogger - DefaultDeviceController video dimension 960 x 540
[INFO] ChimeLogger - Supported Constraints in this browser {"aspectRatio":true,"autoGainControl":true,"brightness":true,"channelCount":true,"colorTemperature":true,"contrast":true,"deviceId":true,"displaySurface":true,"echoCancellation":true,"exposureCompensation":true,"exposureMode":true,"exposureTime":true,"facingMode":true,"focusDistance":true,"focusMode":true,"frameRate":true,"groupId":true,"height":true,"iso":true,"latency":true,"noiseSuppression":true,"pan":true,"pointsOfInterest":true,"resizeMode":true,"sampleRate":true,"sampleSize":true,"saturation":true,"sharpness":true,"suppressLocalAudioPlayback":true,"tilt":true,"torch":true,"whiteBalanceMode":true,"width":true,"zoom":true}
[INFO] ChimeLogger - browser is chrome 117 (117.0.0)
[INFO] ChimeLogger - Event ingestion URL is present in the configuration
[INFO] ChimeLogger - Event reporting started
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - API/DefaultDeviceController/listVideoInputDevices false -> [{"deviceId":"8f3cdf28d0b0e1cb07768d7b6cb6b343cf6ccc599e1618b528e479d2e83083a8","kind":"videoinput","label":"Câmera FaceTime HD (3A71:F4B5)","groupId":"84b85b51b16888502892b9860496bbf5c236309911392a5d8bbd8a35d00e28ae"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/listVideoInputDevices false -> [{"deviceId":"8f3cdf28d0b0e1cb07768d7b6cb6b343cf6ccc599e1618b528e479d2e83083a8","kind":"videoinput","label":"Câmera FaceTime HD (3A71:F4B5)","groupId":"84b85b51b16888502892b9860496bbf5c236309911392a5d8bbd8a35d00e28ae"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/startVideoInput "8f3cdf28d0b0e1cb07768d7b6cb6b343cf6ccc599e1618b528e479d2e83083a8"
[INFO] ChimeLogger - requesting new video device with constraint {"video":{"deviceId":{"exact":"8f3cdf28d0b0e1cb07768d7b6cb6b343cf6ccc599e1618b528e479d2e83083a8"},"width":{"ideal":960},"height":{"ideal":540},"frameRate":{"ideal":15}}}
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - got video device for constraints {"video":{"deviceId":{"exact":"8f3cdf28d0b0e1cb07768d7b6cb6b343cf6ccc599e1618b528e479d2e83083a8"},"width":{"ideal":960},"height":{"ideal":540},"frameRate":{"ideal":15}}}
[INFO] ChimeLogger - Starting devicechange listener.
[INFO] ChimeLogger - API/DefaultDeviceController/startVideoInputDevice "8f3cdf28d0b0e1cb07768d7b6cb6b343cf6ccc599e1618b528e479d2e83083a8"
[INFO] ChimeLogger - API/DefaultDeviceController/listAudioInputDevices false -> [{"deviceId":"default","kind":"audioinput","label":"Padrão - Microfone (MacBook Pro) (Built-in)","groupId":"e0d0167983a87db957dac8018603f95967a0d315e9c2b08eac04f21cf224927b"},{"deviceId":"c070360759729a0c0cf05871f2559fb97180782882171b16ae41dac20eb21dfe","kind":"audioinput","label":"Microfone (MacBook Pro) (Built-in)","groupId":"e0d0167983a87db957dac8018603f95967a0d315e9c2b08eac04f21cf224927b"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/listAudioInputDevices false -> [{"deviceId":"default","kind":"audioinput","label":"Padrão - Microfone (MacBook Pro) (Built-in)","groupId":"e0d0167983a87db957dac8018603f95967a0d315e9c2b08eac04f21cf224927b"},{"deviceId":"c070360759729a0c0cf05871f2559fb97180782882171b16ae41dac20eb21dfe","kind":"audioinput","label":"Microfone (MacBook Pro) (Built-in)","groupId":"e0d0167983a87db957dac8018603f95967a0d315e9c2b08eac04f21cf224927b"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/startAudioInput "default"
[INFO] ChimeLogger - Choosing intrinsic audio input device default
[INFO] ChimeLogger - requesting new audio device with constraint {"audio":{"echoCancellation":true,"googEchoCancellation":true,"googEchoCancellation2":true,"googAutoGainControl":true,"googAutoGainControl2":true,"googNoiseSuppression":true,"googNoiseSuppression2":true,"googHighpassFilter":true,"deviceId":{"exact":"default"}}}
[INFO] ChimeLogger - got audio device for constraints {"audio":{"echoCancellation":true,"googEchoCancellation":true,"googEchoCancellation2":true,"googAutoGainControl":true,"googAutoGainControl2":true,"googNoiseSuppression":true,"googNoiseSuppression2":true,"googHighpassFilter":true,"deviceId":{"exact":"default"}}}
[INFO] ChimeLogger - API/DefaultDeviceController/startAudioInputDevice "default" -> "success"
[INFO] ChimeLogger - API/DefaultDeviceController/listAudioOutputDevices false -> [{"deviceId":"default","kind":"audiooutput","label":"Padrão - Fones de Ouvido Externos (Built-in)","groupId":"99b43feff9c30ee9bb61a3d0857bc803550b4442837c828118d9aad1543d6780"},{"deviceId":"1c28d56c52dce9a20ecaa56e1c45b757463943b86cecb6968726d6f868a3f622","kind":"audiooutput","label":"LG ULTRAWIDE (HDMI)","groupId":"72aa39f84fc2ff8185ea8f3a6b282bf11feaf5973a239703dc3c8fa26319d6bc"},{"deviceId":"711fae76ffbbb03a74f3a115ce058d715d3a4174c57419ca31a79c43738ef3c0","kind":"audiooutput","label":"Fones de Ouvido Externos (Built-in)","groupId":"99b43feff9c30ee9bb61a3d0857bc803550b4442837c828118d9aad1543d6780"},{"deviceId":"1d2807c6c97b7008fbd316bd185b6508f64e2e38b7c3a0f040b4c3e423fb8ee4","kind":"audiooutput","label":"Alto-falantes (MacBook Pro) (Built-in)","groupId":"e0d0167983a87db957dac8018603f95967a0d315e9c2b08eac04f21cf224927b"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/listAudioOutputDevices false -> [{"deviceId":"default","kind":"audiooutput","label":"Padrão - Fones de Ouvido Externos (Built-in)","groupId":"99b43feff9c30ee9bb61a3d0857bc803550b4442837c828118d9aad1543d6780"},{"deviceId":"1c28d56c52dce9a20ecaa56e1c45b757463943b86cecb6968726d6f868a3f622","kind":"audiooutput","label":"LG ULTRAWIDE (HDMI)","groupId":"72aa39f84fc2ff8185ea8f3a6b282bf11feaf5973a239703dc3c8fa26319d6bc"},{"deviceId":"711fae76ffbbb03a74f3a115ce058d715d3a4174c57419ca31a79c43738ef3c0","kind":"audiooutput","label":"Fones de Ouvido Externos (Built-in)","groupId":"99b43feff9c30ee9bb61a3d0857bc803550b4442837c828118d9aad1543d6780"},{"deviceId":"1d2807c6c97b7008fbd316bd185b6508f64e2e38b7c3a0f040b4c3e423fb8ee4","kind":"audiooutput","label":"Alto-falantes (MacBook Pro) (Built-in)","groupId":"e0d0167983a87db957dac8018603f95967a0d315e9c2b08eac04f21cf224927b"}]
[INFO] ChimeLogger - API/DefaultDeviceController/chooseAudioOutput "default" -> null
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/chooseAudioOutput "default"
[INFO] ChimeLogger - transitioning from NotConnected to Connecting with Connect
[INFO] ChimeLogger - Using default transceiver controller
[INFO] ChimeLogger - Needs to wait for attendee presence? false
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask (once)
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask waiting for dependencies:
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/start
[INFO] ChimeLogger - Update request requires resubscribe
[INFO] ChimeLogger - deferring transition from Connecting with Update
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/startLocalVideoTile null -> 1
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/realtimeSubscribeToAttendeeIdPresence
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/addObserver
[INFO] ChimeLogger - adding device change observer
[INFO] ChimeLogger - API/DefaultDeviceController/addDeviceChangeObserver
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/addDeviceChangeObserver
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - adding a ping-pong observer
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - Starting StatsCollector
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask took 1 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask (once)
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once)
[INFO] ChimeLogger - Signaling waiting for dependencies:
[INFO] ChimeLogger - CreatePeerConnectionTask waiting for dependencies: Signaling (once)
[INFO] ChimeLogger - ReceiveAudioInputTask waiting for dependencies:
[INFO] ChimeLogger - AttachMediaInputTask waiting for dependencies: CreatePeerConnectionTask (once), ReceiveAudioInputTask (once)
[INFO] ChimeLogger - CreateSDPTask waiting for dependencies: AttachMediaInputTask (once)
[INFO] ChimeLogger - SetLocalDescriptionTask waiting for dependencies: CreateSDPTask (once)
[INFO] ChimeLogger - FinishGatheringICECandidatesTask waiting for dependencies: SetLocalDescriptionTask (once)
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask waiting for dependencies: FinishGatheringICECandidatesTask (once)
[INFO] ChimeLogger - running task Signaling
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/Timeout15000ms (once)
[INFO] ChimeLogger - Signaling/Timeout15000ms waiting for dependencies:
[INFO] ChimeLogger - running task ReceiveAudioInputTask
[INFO] ChimeLogger - running task Signaling/Timeout15000ms
[INFO] ChimeLogger - running task Signaling/Timeout15000ms/OpenSignalingConnectionTask
[INFO] ChimeLogger - adding connection request to queue: wss://signal.m2.ue1.app.chime.aws/control/ec13bbcc-2854-4d6f-903c-54c7c3ba2713?X-Chime-Control-Protocol-Version=3&X-Amzn-Chime-Send-Close-On-Error=1
[INFO] ChimeLogger - no existing signaling client connection needs closing
[INFO] ChimeLogger - opening connection to wss://signal.m2.ue1.app.chime.aws/control/ec13bbcc-2854-4d6f-903c-54c7c3ba2713?X-Chime-Control-Protocol-Version=3&X-Amzn-Chime-Send-Close-On-Error=1
[INFO] ChimeLogger - notifying event: WebSocketConnecting
[INFO] ChimeLogger - ReceiveAudioInputTask took 1 ms
[INFO] ChimeLogger - Receive an audio output change event
[INFO] ChimeLogger - notifying event: WebSocketOpen
[INFO] ChimeLogger - Opening signaling connection took 535 ms
[INFO] ChimeLogger - Signaling/Timeout15000ms/OpenSignalingConnectionTask took 536 ms
[INFO] ChimeLogger - timeout task Signaling/Timeout15000ms completed
[INFO] ChimeLogger - Signaling/Timeout15000ms took 536 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/Timeout15000ms (once)
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/ListenForVolumeIndicatorsTask
[INFO] ChimeLogger - running task Signaling/ListenForVolumeIndicatorsTask
[INFO] ChimeLogger - Signaling/ListenForVolumeIndicatorsTask took 1 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/ListenForVolumeIndicatorsTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/SendAndReceiveDataMessagesTask
[INFO] ChimeLogger - running task Signaling/SendAndReceiveDataMessagesTask
[INFO] ChimeLogger - Signaling/SendAndReceiveDataMessagesTask took 0 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/SendAndReceiveDataMessagesTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/JoinAndReceiveIndexTask
[INFO] ChimeLogger - running task Signaling/JoinAndReceiveIndexTask
[INFO] ChimeLogger - sending join
[INFO] ChimeLogger - local clock skew estimate=-287ms from ping-pong time=152ms
[INFO] ChimeLogger - received first index {"atCapacity":false,"numParticipants":1,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - Signaling/JoinAndReceiveIndexTask took 153 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/JoinAndReceiveIndexTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/ReceiveTURNCredentialsTask
[INFO] ChimeLogger - running task Signaling/ReceiveTURNCredentialsTask
[INFO] ChimeLogger - TURN credentials available, skipping credentials fetch
[INFO] ChimeLogger - Signaling/ReceiveTURNCredentialsTask took 1 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/ReceiveTURNCredentialsTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/ReceiveVideoStreamIndexTask
[INFO] ChimeLogger - running task Signaling/ReceiveVideoStreamIndexTask
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - Signaling/ReceiveVideoStreamIndexTask took 0 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/ReceiveVideoStreamIndexTask
[INFO] ChimeLogger - serial group task Signaling completed
[INFO] ChimeLogger - Signaling took 692 ms
[INFO] ChimeLogger - running task CreatePeerConnectionTask
[INFO] ChimeLogger - creating new peer connection
[INFO] ChimeLogger - CreatePeerConnectionTask took 1 ms
[INFO] ChimeLogger - running task AttachMediaInputTask
[INFO] ChimeLogger - attaching audio track to peer connection
[INFO] ChimeLogger - peer connection negotiation is needed
[INFO] ChimeLogger - no video track
[INFO] ChimeLogger - AttachMediaInputTask took 2 ms
[INFO] ChimeLogger - running task CreateSDPTask
[INFO] ChimeLogger - uses video: false (enabled: true, sending: false, receiving: false)
[INFO] ChimeLogger - peer connection offerOptions: {"offerToReceiveAudio":true,"offerToReceiveVideo":false}
[INFO] ChimeLogger - peer connection created offer
[INFO] ChimeLogger - CreateSDPTask took 1 ms
[INFO] ChimeLogger - running task SetLocalDescriptionTask
[INFO] ChimeLogger - set local description
[INFO] ChimeLogger - SetLocalDescriptionTask took 2 ms
[INFO] ChimeLogger - running task FinishGatheringICECandidatesTask
[INFO] ChimeLogger - peer connection ice gathering state changed: gathering
[INFO] ChimeLogger - Resubscribe check is paused, setting incoming client metric report as pending
[INFO] ChimeLogger - peer connection ice candidate: candidate:2623063158 1 udp 41820415 10.2.30.230 51448 typ relay raddr 0.0.0.0 rport 0 generation 0 ufrag s9sw network-id 1 network-cost 10
[INFO] ChimeLogger - ice candidate: candidate:2623063158 1 udp 41820415 10.2.30.230 51448 typ relay raddr 0.0.0.0 rport 0 generation 0 ufrag s9sw network-id 1 network-cost 10 state: gathering
[INFO] ChimeLogger - gathered at least one relay candidate
[INFO] ChimeLogger - FinishGatheringICECandidatesTask took 375 ms
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask
[INFO] ChimeLogger - Fixed up [0] to [0] (may be same))}
[INFO] ChimeLogger - Compressed the SDP message from 5328 to 1486 bytes.
[INFO] ChimeLogger - sending subscribe: {"attendeeId":"7ea2be98-9a94-f05c-dc47-1ee56f64f3de","sdpOffer":"","audioHost":"229f796bd6e1415232dc00346342e602.k.m2.ue1.app.chime.aws:3478","audioMuted":false,"audioCheckin":false,"receiveStreamIds":[0],"localVideoEnabled":false,"videoStreamDescriptions":[{"groupId":2,"streamId":2,"ssrc":0,"trackLabel":"AmazonChimeExpressVideo","maxBitrateKbps":0,"maxFrameRate":0,"timeEnabled":0,"disabledByWebRTC":false,"disabledByUplinkPolicy":false}],"connectionTypeHasVideo":true,"compressedSdpOffer":{"0":221,"1":88,"2":219,"3":114,"4":219,"5":54,"6":16,"7":125,"8":247,"9":140,"10":255,"11":1,"12":63,"13":0,"14":19,"15":0,"16":193,"17":11,"18":144,"19":209,"20":131,"21":34,"22":39,"23":173,"24":59,"25":73,"26":198,"27":105,"28":18,"29":39,"30":125,"31":132,"32":72,"33":80,"34":102,"35":45,"36":94,"37":10,"38":66,"39":150,"40":157,"41":175,"42":207,"43":130,"44":146,"45":98,"46":74,"47":2,"48":221,"49":56,"50":113,"51":95,"52":58,"53":22,"54":53,"55":226,"56":217,"57":197,"58":114,"59":113,"60":176,"61":55,"62":250,"63":118,"64":66,"65":78,"66":79,"67":154,"68":73,"69":213,"70":124,"71":45,"72":151,"73":75,"74":133,"75":179,"76":107,"77":211,"78":84,"79":26,"80":113,"81":202,"82":69,"83":66,"84":88,"85":76,"86":19,"87":206,"88":82,"89":150,"90":134,"91":52,"92":98,"93":136,"94":161,"95":139,"96":119,"97":232,"98":226,"99":146,"100":35,"101":202,"102":146,"103":51,"104":2,"105":127,"106":244,"107":244,"108":164,"109":155,"110":224,"111":211,"112":19,"113":59,"114":33,"115":8,"116":140,"117":168,"118":201,"119":194,"120":52,"121":171,"122":86,"123":190,"124":252,"125":244,"126":238,"127":252,"128":205,"129":43,"130":68,"131":16,"132":117,"133":152,"134":190,"135":179,"136":149,"137":106,"138":177,"139":90,"140":46,"141":155,"142":53,"143":174,"144":202,"145":59,"146":157,"147":59,"148":180,"149":234,"150":202,"151":28,"152":119,"153":186,"154":82,"155":181,"156":45,"157":51,"158":137,"159":62,"160":191,"161":253,"162":128,"163":68,"164":206,"165":51,"166":161,"167":9,"168":193,"169":169,"170":208,"171":28,"172":243,"173":180,"174":200,"175":176,"176":74,"177":180,"178":194,"179":113,"180":168,"181":194,"182":152,"183":196,"184":69,"185":30,"186":49,"187":125,"188":122,"189":82,"190":77,"191":212,"192":42,"193":47,"194":27,"195":20,"196":81,"197":206,"198":83,"199":244,"200":233,"201":252,"202":50,"203":248,"204":248,"205":230,"206":67,"207":240,"208":231,"209":199,"210":203,"211":224,"212":195,"213":244,"214":234,"215":242,"216":53,"217":162,"218":148,"219":162,"220":56,"221":68,"222":2,"223":30,"224":159,"225":34,"226":26,"227":194,"228":61,"229":248,"230":193,"231":226,"232":211,"233":147,"234":108,"235":178,"236":243,"237":158,"238":156,"239":177,"240":179,"241":16,"242":190,"243":194,"244":222,"245":103,"246":99,"247":179,"248":86,"249":138,"250":221,"251":214,"252":72,"253":191,"254":177,"255":94,"256":144,"257":169,"258":58,"259":47,"260":115,"261":101,"262":181,"263":100,"264":49,"265":232,"266":198,"267":192,"268":1,"269":88,"270":68,"271":171,"272":188,"273":5,"274":118,"275":82,"276":70,"277":56,"278":141,"279":134,"280":182,"281":182,"282":30,"283":217,"284":251,"285":22,"286":25,"287":189,"288":84,"289":247,"290":200,"291":168,"292":60,"293":55,"294":59,"295":131,"296":200,"297":180,"298":141,"299":177,"300":224,"301":212,"302":66,"303":215,"304":218,"305":40,"306":91,"307":54,"308":53,"309":220,"310":212,"311":218,"312":174,"313":27,"314":115,"315":131,"316":203,"317":28,"318":12,"319":239,"320":110,"321":178,"322":166,"323":179,"324":96,"325":216,"326":185,"327":80,"328":102,"329":26,"330":175,"331":10,"332":163,"333":22,"334":178,"335":19,"336":221,"337":122,"338":135,"339":180,"340":235,"341":92,"342":54,"343":55,"344":95,"345":200,"346":151,"347":247,"348":231,"349":23,"350":127,"351":4,"352":252,"353":159,"354":100,"355":22,"356":252,"357":181,"358":126,"359":47,"360":106,"361":165,"362":62,"363":95,"364":236,"365":116,"366":154,"367":214,"368":61,"369":162,"370":147,"371":214,"372":148,"373":217,"374":205,"375":82,"376":59,"377":184,"378":40,"379":235,"380":133,"381":54,"382":173,"383":41,"384":107,"385":43,"386":187,"387":107,"388":133,"389":89,"390":20,"391":163,"392":151,"393":137,"394":156,"395":198,"396":146,"397":17,"398":153,"399":68,"400":50,"401":102,"402":82,"403":196,"404":242,"405":60,"406":145,"407":179,"408":115,"409":41,"410":102,"411":114,"412":250,"413":90,"414":242,"415":169,"416":140,"417":66,"418":25,"419":50,"420":73,"421":185,"422":156,"423":133,"424":50,"425":77,"426":221,"427":135,"428":193,"429":55,"430":147,"431":211,"432":68,"433":190,"434":156,"435":73,"436":50,"437":147,"438":169,"439":144,"440":132,"441":184,"442":15,"443":40,"444":240,"445":72,"446":78,"447":95,"448":73,"449":250,"450":74,"451":78,"452":97,"453":33,"454":145,"455":231,"456":83,"457":247,"458":216,"459":78,"460":91,"461":136,"462":9,"463":149,"464":217,"465":86,"466":117,"467":93,"468":127,"469":244,"470":101,"471":46,"472":201,"473":67,"474":1
[INFO] ChimeLogger - received new index {"atCapacity":false,"numParticipants":1,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - received new index {"atCapacity":false,"numParticipants":1,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - got subscribe ack: {"duplex":"RX","allocations":[{"trackLabel":"","streamId":1,"groupId":1}],"compressedSdpAnswer":"5FXLbts4FN0b8D9wPYCkS0oipVto0SQ2WiBTGE0fa4qkHKLWAyQdO/36Ae04cRMXM8AAs5mNQZ/7Orw+h35oYD4bm4QAAfLxE/m4KghlIoUUUjqf+SaZz0IDBOYz2azduJ3w6uunm9sFAUIjZvahl1MiN5txl/R2b3REe2914k0vh2AVku9/3pE/5rO+kVttR0IFlIx8vVllX27vss9fVtnd+2+rJaGUzmeqeaJRFinlLGUFTykXsasLasJj8W9zlBy01TIYvJPAeMcqQslWT4RWnDEBtShfVT3RCY8T8a7b7ImTWjtCIWUppTQtgLhpdOEpb20G42Sw43BcyoWB7D8a+OHVDXldFHVV0fKXXi/T7kcf/kk79i/aWWWSbefkGhkAVLwTTFPFWsOhojRvyzIvcy2ZNqfsaaeRFp3silazTou6ynXR5iUYQ0EprU3OYm5nh7Vxk7NDQH8vE1ZyUjBc5FgVmF/jssJaYL1AKpCWEb8WWBYRLCtcXOH7KxRXyG6Q5rhkKJbxvKiwoCgWWFMsl7iocQFYLLCo8LqOY70J2wkn6b19OHDurUZ4kT4ych/ChFm22+3SnWldUOno1pnZT8bZ3gzBZy5Myb12Zh8y2frEm0EnwfbmOGDQzqiHk2/waJLD50n1Sb/dP5+dtz/N8dsUCVBKyThtfVZUAJCx58SuPcSCk4OPgkqUOiyyD8ei3g5TZNFQeLf1xg6tHHRnVHNwtvdOYS4Y8BJy4EQNsjcYrr6v+PKWtcOys/pzG139YLUZSX3B0TUntSAUOKEgzrwNhwcGnk1dk7eB/4eSDqu2g1ThBEYJnAByHvlbIdScfFtVWQ0AcC6Cmr/RwFlIqZ501r1CB6l+vILW47hOnOnbC6lk2thzJoK4sH9hcpBcLYicQlPzc+0CJx8YL96SjpHfsY6xC7Qj/Jp3xC4SPyWfmB9dAZxszIPZJNI/9r0J7vH412Z0Q99NUv0wwf48PHdJP2oTQTd2dmOSY5nVTcEM0O6XO17aRkTjOijwZ7cxARR4JcTJbS8//18BAAD//w=="}
[INFO] ChimeLogger - Decompressed the SDP message from 787 to 1961 bytes.
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask took 170 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once)
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume (once)
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume waiting for dependencies: AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once)
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume took 0 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume (once)
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SetRemoteDescriptionTask
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SetRemoteDescriptionTask
[INFO] ChimeLogger - processed remote description is >>>v=0
o=- 0 0 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=extmap-allow-mixed
a=msid-semantic: WMS *
m=audio 17052 UDP/TLS/RTP/SAVPF 111
c=IN IP4 54.162.246.167
a=rtcp:17052 IN IP4 54.162.246.167
a=candidate:Ha026f28 1 udp 1694498815 10.2.111.40 17052 typ host generation 0
a=candidate:Ha026f28 2 udp 1694498815 10.2.111.40 17052 typ host generation 0
a=ice-ufrag:200086f72d1c2be608113b55353da2de
a=ice-pwd:14faf4bd2fd7983d4b350ee10ccdde32
a=fingerprint:sha-256 42:E3:84:3C:F8:97:9E:17:15:E3:C7:54:97:58:EB:AB:7B:2D:13:F2:7F:2D:E8:41:7E:91:5F:E9:E0:4E:48:C9
a=setup:passive
a=mid:0
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=sendrecv
a=msid:audio audio
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=ssrc:3720650306 cname:tBWP6FL2bnFfidRb
m=video 9 UDP/TLS/RTP/SAVPF 96 97 106 107
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:200086f72d1c2be608113b55353da2de
a=ice-pwd:14faf4bd2fd7983d4b350ee10ccdde32
a=fingerprint:sha-256 42:E3:84:3C:F8:97:9E:17:15:E3:C7:54:97:58:EB:AB:7B:2D:13:F2:7F:2D:E8:41:7E:91:5F:E9:E0:4E:48:C9
a=setup:passive
a=mid:1
a=inactive
a=msid:inactive inactive
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:96 VP8/90000
a=rtcp-fb:96 transport-cc
a=rtcp-fb:96 ccm fir
a=rtcp-fb:96 nack
a=rtcp-fb:96 goog-remb
a=rtcp-fb:96 nack pli
a=rtpmap:97 rtx/90000
a=fmtp:97 apt=96
a=rtpmap:106 H264/90000
a=rtcp-fb:106 transport-cc
a=rtcp-fb:106 ccm fir
a=rtcp-fb:106 nack
a=rtcp-fb:106 goog-remb
a=rtcp-fb:106 nack pli
a=fmtp:106 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=106
a=ssrc:2701068776 cname:inactive
<<<
[INFO] ChimeLogger - received new index {"atCapacity":false,"numParticipants":1,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - peer connection ice connection state changed: checking
[INFO] ChimeLogger - received track event: kind=audio id=ee6701c7-407a-45e6-80d3-97d3a87fed84 label=ee6701c7-407a-45e6-80d3-97d3a87fed84
[INFO] ChimeLogger - set remote description, waiting for ICE connection
[INFO] ChimeLogger - peer connection state changed: connecting
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/realtimeSubscribeToVolumeIndicator "7ea2be98-9a94-f05c-dc47-1ee56f64f3de"
[INFO] ChimeLogger - attendeePresenceReceived: 7ea2be98-9a94-f05c-dc47-1ee56f64f3de
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/bindAudioElement "" -> {}
[INFO] ChimeLogger - peer connection ice connection state changed: connected
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SetRemoteDescriptionTask took 158 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SetRemoteDescriptionTask
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer completed
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer took 1402 ms
[INFO] ChimeLogger - timeout task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms completed
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms took 1402 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de completed
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de took 1405 ms
[INFO] ChimeLogger - transitioning from Connecting to Connected with FinishConnecting
[INFO] ChimeLogger - Resuming index ingestion with pending index
[INFO] ChimeLogger - should resubscribe: false (downlink: false uplink: false)
[INFO] ChimeLogger - Resuming resubscribe check with pending metrics report
[INFO] ChimeLogger - trigger resubscribe for up=true; videosToReceive=[]
[INFO] ChimeLogger - Request to update remote videos with added: , updated: , removed:
[INFO] ChimeLogger - Updated local video from policy without renegotiation
[INFO] ChimeLogger - Update request does not require resubscribe
[INFO] ChimeLogger - updated audio-video session
[INFO] ChimeLogger - performing deferred action Update
[INFO] ChimeLogger - transitioning from Connected to Updating with Update
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/ReceiveVideoInputTask
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/ReceiveVideoInputTask
[INFO] ChimeLogger - using video device label=Câmera FaceTime HD (3A71:F4B5) id=b966c62c-c52d-4cf2-9518-387a86621689
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/ReceiveVideoInputTask took 0 ms
[INFO] ChimeLogger - start completed
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/ReceiveVideoInputTask
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/AttachMediaInputTask
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/AttachMediaInputTask
[INFO] ChimeLogger - attaching audio track to peer connection
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[WARN] ChimeLogger - Ignoring video element unbinding for unknown tile id undefined
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/unbindVideoElement {"cleanUpVideoElement":false}
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/bindAudioElement "" -> {}
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/bindVideoElement {"tileId":1,"videoElementId":""}
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - attaching video track to peer connection
[INFO] ChimeLogger - peer connection ice gathering state changed: complete
[INFO] ChimeLogger - peer connection ice candidate: (null)
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - peer connection negotiation is needed
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/AttachMediaInputTask took 10 ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/AttachMediaInputTask
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/CreateSDPTask
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/CreateSDPTask
[INFO] ChimeLogger - uses video: true (enabled: true, sending: true, receiving: false)
[INFO] ChimeLogger - peer connection offerOptions: {"offerToReceiveAudio":true,"offerToReceiveVideo":true}
[INFO] ChimeLogger - peer connection created offer
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/CreateSDPTask took 1 ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/CreateSDPTask
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetLocalDescriptionTask
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetLocalDescriptionTask
[INFO] ChimeLogger - set local description
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetLocalDescriptionTask took 1 ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetLocalDescriptionTask
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/FinishGatheringICECandidatesTask
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/FinishGatheringICECandidatesTask
[INFO] ChimeLogger - ice gathering state is complete and candidates are in SDP; bypass gathering
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/FinishGatheringICECandidatesTask took 1 ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/FinishGatheringICECandidatesTask
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SubscribeAndReceiveSubscribeAckTask
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SubscribeAndReceiveSubscribeAckTask
[INFO] ChimeLogger - Fixed up [0] to [0] (may be same))}
[INFO] ChimeLogger - Compressed the SDP message from 5813 to 178 bytes.
[INFO] ChimeLogger - sending subscribe: {"attendeeId":"7ea2be98-9a94-f05c-dc47-1ee56f64f3de","sdpOffer":"","audioHost":"229f796bd6e1415232dc00346342e602.k.m2.ue1.app.chime.aws:3478","audioMuted":false,"audioCheckin":false,"receiveStreamIds":[0],"localVideoEnabled":true,"videoStreamDescriptions":[{"groupId":2,"streamId":2,"ssrc":0,"trackLabel":"AmazonChimeExpressVideo","maxBitrateKbps":1500,"maxFrameRate":15,"timeEnabled":0,"disabledByWebRTC":false,"disabledByUplinkPolicy":false}],"connectionTypeHasVideo":true,"compressedSdpOffer":{"0":237,"1":216,"2":81,"3":10,"4":131,"5":48,"6":12,"7":6,"8":224,"9":171,"10":236,"11":2,"12":1,"13":107,"14":98,"15":52,"16":239,"17":67,"18":216,"19":49,"20":218,"21":206,"22":30,"23":96,"24":131,"25":157,"26":127,"27":82,"28":69,"29":11,"30":62,"31":40,"32":226,"33":139,"34":240,"35":63,"36":55,"37":180,"38":161,"39":45,"40":77,"41":250,"42":253,"43":14,"44":123,"45":38,"46":195,"47":51,"48":225,"49":153,"50":240,"51":76,"52":120,"53":38,"54":60,"55":19,"56":158,"57":9,"58":207,"59":132,"60":103,"61":194,"62":51,"63":239,"64":224,"65":153,"66":243,"67":28,"68":118,"69":102,"70":231,"71":198,"72":131,"73":28,"74":62,"75":223,"76":220,"77":188,"78":197,"79":124,"80":231,"81":230,"82":39,"83":242,"84":84,"85":209,"86":82,"87":11,"88":110,"89":208,"90":70,"91":41,"92":118,"93":156,"94":72,"95":184,"96":49,"97":178,"98":84,"99":57,"100":122,"101":123,"102":31,"103":67,"104":18,"105":14,"106":222,"107":100,"108":91,"109":180,"110":128,"111":174,"112":64,"113":87,"114":160,"115":43,"116":208,"117":21,"118":232,"119":10,"120":116,"121":93,"122":99,"123":129,"124":174,"125":64,"126":87,"127":160,"128":43,"129":208,"130":245,"131":166,"132":232,"133":58,"134":217,"135":1,"136":77,"137":94,"138":218,"139":191,"140":158,"141":143,"142":90,"143":107,"144":103,"145":149,"146":116,"147":54,"148":254,"149":225,"150":133,"151":91,"152":177,"153":86,"154":213,"155":45,"156":198,"157":80,"158":140,"159":238,"160":104,"161":68,"162":17,"163":121,"164":125,"165":99,"166":158,"167":87,"168":88,"169":211,"170":219,"171":203,"172":165,"173":136,"174":188,"175":58,"176":151,"177":63},"videoSubscriptionConfiguration":[]}
[INFO] ChimeLogger - received new index {"atCapacity":false,"numParticipants":1,"supportedReceiveCodecIntersection":["VP8","H264_CONSTRAINED_BASELINE_PROFILE"]}
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - got subscribe ack: {"duplex":"DUPLEX","allocations":[{"trackLabel":"AmazonChimeExpressVideo","streamId":1,"groupId":1}],"compressedSdpAnswer":"7JZLasMwEIavkgsMlaNHLZ8g0E2hi64n0ogKIjtICn2cvkQxxKXYTlsvuvDCC4NmbInR/30rqFdQr6BeQX0zqGv18LxjJxV2W/Px9NhOg7pHdHku0NYrr0d53Q9UJTan2DaesmuOGDGk5jo/Te6cS5QXHMC+zeRXk6XUBG+Hv8lmC87vKUfCAF9Lq/lSOqKPZGGsBx/u+typ7NnbOxvRZXjpDoEixGAww/UGvHpLYAzQW6Y2+a5NwIZnr392lmXy4YDvFFNhoClReOHxt4utrTCaGINakwBROwN4TwiKI1dMOSu3tFF6X5GSCkzNHQguNWjHKrCIZu8E36MWt8XCpAONystCcvSPHOgXayeUd0xXl1Dhvyjv/MIS6JWWnEnFpR4N9M8AAAD//w=="}
[INFO] ChimeLogger - Decompressed the SDP message from 298 to 2580 bytes.
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SubscribeAndReceiveSubscribeAckTask took 159 ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SubscribeAndReceiveSubscribeAckTask
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession running subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetRemoteDescriptionTask
[INFO] ChimeLogger - running task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetRemoteDescriptionTask
[INFO] ChimeLogger - processed remote description is >>>v=0
o=- 0 0 IN IP4 127.0.0.1
s=-
t=0 0
a=group:BUNDLE 0 1
a=extmap-allow-mixed
a=msid-semantic: WMS *
m=audio 17052 UDP/TLS/RTP/SAVPF 111
c=IN IP4 54.162.246.167
a=rtcp:17052 IN IP4 54.162.246.167
a=candidate:Ha026f28 1 udp 1694498815 10.2.111.40 17052 typ host generation 0
a=candidate:Ha026f28 2 udp 1694498815 10.2.111.40 17052 typ host generation 0
a=ice-ufrag:200086f72d1c2be608113b55353da2de
a=ice-pwd:14faf4bd2fd7983d4b350ee10ccdde32
a=fingerprint:sha-256 42:E3:84:3C:F8:97:9E:17:15:E3:C7:54:97:58:EB:AB:7B:2D:13:F2:7F:2D:E8:41:7E:91:5F:E9:E0:4E:48:C9
a=setup:passive
a=mid:0
a=extmap:2 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time
a=sendrecv
a=msid:audio audio
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:111 opus/48000/2
a=rtcp-fb:111 transport-cc
a=fmtp:111 minptime=10;useinbandfec=1
a=ssrc:3720650306 cname:86KWH0u6mH2czSPn
m=video 9 UDP/TLS/RTP/SAVPF 106 107 107 96 97 97
c=IN IP4 0.0.0.0
a=rtcp:9 IN IP4 0.0.0.0
a=ice-ufrag:200086f72d1c2be608113b55353da2de
a=ice-pwd:14faf4bd2fd7983d4b350ee10ccdde32
a=fingerprint:sha-256 42:E3:84:3C:F8:97:9E:17:15:E3:C7:54:97:58:EB:AB:7B:2D:13:F2:7F:2D:E8:41:7E:91:5F:E9:E0:4E:48:C9
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:4 urn:ietf:params:rtp-hdrext:sdes:mid
a=extmap:10 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id
a=extmap:11 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id
a=extmap:3 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
a=extmap:9 http://www.webrtc.org/experiments/rtp-hdrext/video-layers-allocation00
a=sendrecv
a=msid:9d4c9e00-89e4-48fc-a7ea-63a3606fd52e 69b1e656-c83f-4359-9f01-daacbf43ba94
a=rtcp-mux
a=rtcp-rsize
a=rtpmap:106 H264/90000
a=rtcp-fb:106 goog-remb
a=rtcp-fb:106 transport-cc
a=rtcp-fb:106 ccm fir
a=rtcp-fb:106 nack
a=rtcp-fb:106 nack pli
a=fmtp:106 level-asymmetry-allowed=1;packetization-mode=1;profile-level-id=42e01f
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=106
a=rtpmap:107 rtx/90000
a=fmtp:107 apt=106
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:97 rtx/90000
a=fmtp:97 apt=96
a=ssrc:1953056359 cname:86KWH0u6mH2czSPn
<<<
[INFO] ChimeLogger - received track event: kind=video id=d261812a-f8a5-4a12-a9ea-d82dd8980a1d label=d261812a-f8a5-4a12-a9ea-d82dd8980a1d
[INFO] ChimeLogger - set remote description, waiting for ICE connection
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetRemoteDescriptionTask took 2 ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession/SetRemoteDescriptionTask
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession completed
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/UpdateSession took 176 ms
[INFO] ChimeLogger - timeout task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms completed
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms took 176 ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de completed subtask AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - serial group task AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de completed
[INFO] ChimeLogger - AudioVideoUpdate/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de took 177 ms
[INFO] ChimeLogger - transitioning from Updating to Connected with FinishUpdating
[INFO] ChimeLogger - Resuming index ingestion with pending index
[INFO] ChimeLogger - should resubscribe: false (downlink: false uplink: false)
[INFO] ChimeLogger - updated audio-video session
[INFO] ChimeLogger - received new index {"atCapacity":false,"numParticipants":1,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - should resubscribe: false (downlink: false uplink: false)
[INFO] ChimeLogger - peer connection state changed: connected @dinmin-amzn yes, I removed that line
dinmin-amzn
commented
9 months ago @maisasb Are you available for a Chime call? I'd like to see why the redundant audio worker is not starting.
maisasb
commented
9 months ago @dinmin-amzn yes
dinmin-amzn
commented
9 months ago @maisasb Please try joining at this link https://chime.aws/2333969980 and let me know if the link is not working.
dinmin-amzn
commented
9 months ago Confirmed with @maisasb that the latest fix is working and that the redundant audio worker is able to be setup. We will move forward with this fix.
dinmin-amzn
commented
9 months ago @maisasb Could you send some of the debug logs from the project that had the AudioRed log lines for reference?
maisasb
commented
9 months ago [INFO] ChimeLogger - DefaultDeviceController video dimension 960 x 540
[INFO] ChimeLogger - Supported Constraints in this browser {"aspectRatio":true,"autoGainControl":true,"brightness":true,"channelCount":true,"colorTemperature":true,"contrast":true,"deviceId":true,"displaySurface":true,"echoCancellation":true,"exposureCompensation":true,"exposureMode":true,"exposureTime":true,"facingMode":true,"focusDistance":true,"focusMode":true,"frameRate":true,"groupId":true,"height":true,"iso":true,"latency":true,"noiseSuppression":true,"pan":true,"pointsOfInterest":true,"resizeMode":true,"sampleRate":true,"sampleSize":true,"saturation":true,"sharpness":true,"suppressLocalAudioPlayback":true,"tilt":true,"torch":true,"whiteBalanceMode":true,"width":true,"zoom":true}
[INFO] ChimeLogger - browser is chrome 117 (117.0.0)
[INFO] ChimeLogger - Event ingestion URL is present in the configuration
[INFO] ChimeLogger - Event reporting started
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - API/DefaultDeviceController/listVideoInputDevices false -> [{"deviceId":"7071635ee93a260cc9074a7e9bcf3157cbf629639de15b3c90853b4c6bc2649b","kind":"videoinput","label":"Câmera FaceTime HD (3A71:F4B5)","groupId":"924aa361961f6d674ddfdff82d2c9e2dc797ecb5e4e1d028aef2d74cc859b6ea"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/listVideoInputDevices false -> [{"deviceId":"7071635ee93a260cc9074a7e9bcf3157cbf629639de15b3c90853b4c6bc2649b","kind":"videoinput","label":"Câmera FaceTime HD (3A71:F4B5)","groupId":"924aa361961f6d674ddfdff82d2c9e2dc797ecb5e4e1d028aef2d74cc859b6ea"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/startVideoInput "7071635ee93a260cc9074a7e9bcf3157cbf629639de15b3c90853b4c6bc2649b"
[INFO] ChimeLogger - requesting new video device with constraint {"video":{"deviceId":{"exact":"7071635ee93a260cc9074a7e9bcf3157cbf629639de15b3c90853b4c6bc2649b"},"width":{"ideal":960},"height":{"ideal":540},"frameRate":{"ideal":15}}}
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - got video device for constraints {"video":{"deviceId":{"exact":"7071635ee93a260cc9074a7e9bcf3157cbf629639de15b3c90853b4c6bc2649b"},"width":{"ideal":960},"height":{"ideal":540},"frameRate":{"ideal":15}}}
[INFO] ChimeLogger - Starting devicechange listener.
[INFO] ChimeLogger - API/DefaultDeviceController/startVideoInputDevice "7071635ee93a260cc9074a7e9bcf3157cbf629639de15b3c90853b4c6bc2649b"
[INFO] ChimeLogger - API/DefaultDeviceController/listAudioInputDevices false -> [{"deviceId":"default","kind":"audioinput","label":"Padrão - Microfone (MacBook Pro) (Built-in)","groupId":"d1f87cb5b11316f139514124a15cf585aa236dbc80ff1b0597da75d492a82bd0"},{"deviceId":"7a6780de6c9307ef9ae57cba9295baf2ac40b1a702f07f567ba8788e1704f9b5","kind":"audioinput","label":"Microfone (MacBook Pro) (Built-in)","groupId":"d1f87cb5b11316f139514124a15cf585aa236dbc80ff1b0597da75d492a82bd0"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/listAudioInputDevices false -> [{"deviceId":"default","kind":"audioinput","label":"Padrão - Microfone (MacBook Pro) (Built-in)","groupId":"d1f87cb5b11316f139514124a15cf585aa236dbc80ff1b0597da75d492a82bd0"},{"deviceId":"7a6780de6c9307ef9ae57cba9295baf2ac40b1a702f07f567ba8788e1704f9b5","kind":"audioinput","label":"Microfone (MacBook Pro) (Built-in)","groupId":"d1f87cb5b11316f139514124a15cf585aa236dbc80ff1b0597da75d492a82bd0"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/startAudioInput "default"
[INFO] ChimeLogger - Choosing intrinsic audio input device default
[INFO] ChimeLogger - requesting new audio device with constraint {"audio":{"echoCancellation":true,"googEchoCancellation":true,"googEchoCancellation2":true,"googAutoGainControl":true,"googAutoGainControl2":true,"googNoiseSuppression":true,"googNoiseSuppression2":true,"googHighpassFilter":true,"deviceId":{"exact":"default"}}}
[INFO] ChimeLogger - got audio device for constraints {"audio":{"echoCancellation":true,"googEchoCancellation":true,"googEchoCancellation2":true,"googAutoGainControl":true,"googAutoGainControl2":true,"googNoiseSuppression":true,"googNoiseSuppression2":true,"googHighpassFilter":true,"deviceId":{"exact":"default"}}}
[INFO] ChimeLogger - API/DefaultDeviceController/startAudioInputDevice "default" -> "success"
[INFO] ChimeLogger - API/DefaultDeviceController/listAudioOutputDevices false -> [{"deviceId":"default","kind":"audiooutput","label":"Padrão - Fones de Ouvido Externos (Built-in)","groupId":"552fd6566074bddcb72606a36abee1b86c79f3b5495e86b93b68f03f8ca49903"},{"deviceId":"bdc35bfb4a6ed14e83123783a5f5c9beb49fccbba6e42d5a2885b0b38b8f11b5","kind":"audiooutput","label":"LG ULTRAWIDE (HDMI)","groupId":"3882c660211e7874d15ece57bae4385cda6660d27d698849078d40c701186019"},{"deviceId":"80c70baf3ffde308fd4afb156702866bb22a39de2c8ded4f15ffc99a2104e25a","kind":"audiooutput","label":"Fones de Ouvido Externos (Built-in)","groupId":"552fd6566074bddcb72606a36abee1b86c79f3b5495e86b93b68f03f8ca49903"},{"deviceId":"8dd074d46ae1c8e3c89c975e2221a895987af69e662535e014e90252566dcee6","kind":"audiooutput","label":"Alto-falantes (MacBook Pro) (Built-in)","groupId":"d1f87cb5b11316f139514124a15cf585aa236dbc80ff1b0597da75d492a82bd0"}]
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/listAudioOutputDevices false -> [{"deviceId":"default","kind":"audiooutput","label":"Padrão - Fones de Ouvido Externos (Built-in)","groupId":"552fd6566074bddcb72606a36abee1b86c79f3b5495e86b93b68f03f8ca49903"},{"deviceId":"bdc35bfb4a6ed14e83123783a5f5c9beb49fccbba6e42d5a2885b0b38b8f11b5","kind":"audiooutput","label":"LG ULTRAWIDE (HDMI)","groupId":"3882c660211e7874d15ece57bae4385cda6660d27d698849078d40c701186019"},{"deviceId":"80c70baf3ffde308fd4afb156702866bb22a39de2c8ded4f15ffc99a2104e25a","kind":"audiooutput","label":"Fones de Ouvido Externos (Built-in)","groupId":"552fd6566074bddcb72606a36abee1b86c79f3b5495e86b93b68f03f8ca49903"},{"deviceId":"8dd074d46ae1c8e3c89c975e2221a895987af69e662535e014e90252566dcee6","kind":"audiooutput","label":"Alto-falantes (MacBook Pro) (Built-in)","groupId":"d1f87cb5b11316f139514124a15cf585aa236dbc80ff1b0597da75d492a82bd0"}]
[INFO] ChimeLogger - API/DefaultDeviceController/chooseAudioOutput "default" -> null
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/chooseAudioOutput "default"
[INFO] ChimeLogger - transitioning from NotConnected to Connecting with Connect
[INFO] ChimeLogger - Using default transceiver controller
[INFO] ChimeLogger - Needs to wait for attendee presence? false
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask (once)
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask waiting for dependencies:
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/start
[INFO] ChimeLogger - Update request requires resubscribe
[INFO] ChimeLogger - deferring transition from Connecting with Update
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/startLocalVideoTile null -> 1
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/realtimeSubscribeToAttendeeIdPresence
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/addObserver
[INFO] ChimeLogger - adding device change observer
[INFO] ChimeLogger - API/DefaultDeviceController/addDeviceChangeObserver
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/addDeviceChangeObserver
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - adding a ping-pong observer
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - Starting StatsCollector
[INFO] ChimeLogger - API/DefaultAudioVideoFacade/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/getAllVideoTiles
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask took 1 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/MonitorTask (once)
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once)
[INFO] ChimeLogger - Signaling waiting for dependencies:
[INFO] ChimeLogger - CreatePeerConnectionTask waiting for dependencies: Signaling (once)
[INFO] ChimeLogger - ReceiveAudioInputTask waiting for dependencies:
[INFO] ChimeLogger - AttachMediaInputTask waiting for dependencies: CreatePeerConnectionTask (once), ReceiveAudioInputTask (once)
[INFO] ChimeLogger - CreateSDPTask waiting for dependencies: AttachMediaInputTask (once)
[INFO] ChimeLogger - SetLocalDescriptionTask waiting for dependencies: CreateSDPTask (once)
[INFO] ChimeLogger - FinishGatheringICECandidatesTask waiting for dependencies: SetLocalDescriptionTask (once)
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask waiting for dependencies: FinishGatheringICECandidatesTask (once)
[INFO] ChimeLogger - running task Signaling
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/Timeout15000ms (once)
[INFO] ChimeLogger - Signaling/Timeout15000ms waiting for dependencies:
[INFO] ChimeLogger - running task ReceiveAudioInputTask
[INFO] ChimeLogger - running task Signaling/Timeout15000ms
[INFO] ChimeLogger - running task Signaling/Timeout15000ms/OpenSignalingConnectionTask
[INFO] ChimeLogger - adding connection request to queue: wss://signal.m2.ue1.app.chime.aws/control/ec13bbcc-2854-4d6f-903c-54c7c3ba2713?X-Chime-Control-Protocol-Version=3&X-Amzn-Chime-Send-Close-On-Error=1
[INFO] ChimeLogger - no existing signaling client connection needs closing
[INFO] ChimeLogger - opening connection to wss://signal.m2.ue1.app.chime.aws/control/ec13bbcc-2854-4d6f-903c-54c7c3ba2713?X-Chime-Control-Protocol-Version=3&X-Amzn-Chime-Send-Close-On-Error=1
[INFO] ChimeLogger - notifying event: WebSocketConnecting
[INFO] ChimeLogger - ReceiveAudioInputTask took 1 ms
[INFO] ChimeLogger - Receive an audio output change event
[INFO] ChimeLogger - notifying event: WebSocketOpen
[INFO] ChimeLogger - Opening signaling connection took 535 ms
[INFO] ChimeLogger - Signaling/Timeout15000ms/OpenSignalingConnectionTask took 536 ms
[INFO] ChimeLogger - timeout task Signaling/Timeout15000ms completed
[INFO] ChimeLogger - Signaling/Timeout15000ms took 536 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/Timeout15000ms (once)
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/ListenForVolumeIndicatorsTask
[INFO] ChimeLogger - running task Signaling/ListenForVolumeIndicatorsTask
[INFO] ChimeLogger - Signaling/ListenForVolumeIndicatorsTask took 0 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/ListenForVolumeIndicatorsTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/SendAndReceiveDataMessagesTask
[INFO] ChimeLogger - running task Signaling/SendAndReceiveDataMessagesTask
[INFO] ChimeLogger - Signaling/SendAndReceiveDataMessagesTask took 0 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/SendAndReceiveDataMessagesTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/JoinAndReceiveIndexTask
[INFO] ChimeLogger - running task Signaling/JoinAndReceiveIndexTask
[INFO] ChimeLogger - sending join
[INFO] ChimeLogger - local clock skew estimate=-293ms from ping-pong time=154ms
[INFO] ChimeLogger - received first index {"atCapacity":false,"sources":[{"streamId":1,"framerate":15,"maxBitrateKbps":1500,"trackLabel":"AmazonChimeExpressVideo","groupId":1,"avgBitrateBps":1517445,"attendeeId":"7ea2be98-9a94-f05c-dc47-1ee56f64f3de","mediaType":"VIDEO","externalUserId":"2aef395e-b8df-4f83-b076-cb38d5043c00"}],"numParticipants":2,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - Signaling/JoinAndReceiveIndexTask took 153 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/JoinAndReceiveIndexTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/ReceiveTURNCredentialsTask
[INFO] ChimeLogger - running task Signaling/ReceiveTURNCredentialsTask
[INFO] ChimeLogger - TURN credentials available, skipping credentials fetch
[INFO] ChimeLogger - Signaling/ReceiveTURNCredentialsTask took 1 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/ReceiveTURNCredentialsTask
[INFO] ChimeLogger - serial group task Signaling running subtask Signaling/ReceiveVideoStreamIndexTask
[INFO] ChimeLogger - running task Signaling/ReceiveVideoStreamIndexTask
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - Signaling/ReceiveVideoStreamIndexTask took 0 ms
[INFO] ChimeLogger - serial group task Signaling completed subtask Signaling/ReceiveVideoStreamIndexTask
[INFO] ChimeLogger - serial group task Signaling completed
[INFO] ChimeLogger - Signaling took 691 ms
[INFO] ChimeLogger - running task CreatePeerConnectionTask
[INFO] ChimeLogger - creating new peer connection
[INFO] ChimeLogger - CreatePeerConnectionTask took 2 ms
[INFO] ChimeLogger - running task AttachMediaInputTask
[INFO] ChimeLogger - [AudioRed] Supports encoded insertable streams using TransformStream
[INFO] ChimeLogger - [AudioRed] Redundant audio worker URL blob:http://localhost:8080/a54f3cc6-597e-44ea-bcef-f9479f422479
[INFO] ChimeLogger - adding meeting observer
[INFO] ChimeLogger - attaching audio track to peer connection
[INFO] ChimeLogger - peer connection negotiation is needed
[INFO] ChimeLogger - audio/red set as preferred codec
[INFO] ChimeLogger - no video track
[INFO] ChimeLogger - AttachMediaInputTask took 4 ms
[INFO] ChimeLogger - running task CreateSDPTask
[INFO] ChimeLogger - uses video: false (enabled: true, sending: false, receiving: false)
[INFO] ChimeLogger - peer connection offerOptions: {"offerToReceiveAudio":true,"offerToReceiveVideo":false}
[INFO] ChimeLogger - peer connection created offer
[INFO] ChimeLogger - CreateSDPTask took 1 ms
[INFO] ChimeLogger - running task SetLocalDescriptionTask
[INFO] ChimeLogger - set local description
[INFO] ChimeLogger - SetLocalDescriptionTask took 3 ms
[INFO] ChimeLogger - running task FinishGatheringICECandidatesTask
[INFO] ChimeLogger - peer connection ice gathering state changed: gathering
[INFO] ChimeLogger - Resubscribe check is paused, setting incoming client metric report as pending
[INFO] ChimeLogger - peer connection ice candidate: candidate:2587448403 1 udp 41820415 10.2.22.63 62838 typ relay raddr 0.0.0.0 rport 0 generation 0 ufrag RX63 network-id 1 network-cost 10
[INFO] ChimeLogger - ice candidate: candidate:2587448403 1 udp 41820415 10.2.22.63 62838 typ relay raddr 0.0.0.0 rport 0 generation 0 ufrag RX63 network-id 1 network-cost 10 state: gathering
[INFO] ChimeLogger - gathered at least one relay candidate
[INFO] ChimeLogger - FinishGatheringICECandidatesTask took 378 ms
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask
[INFO] ChimeLogger - Fixed up [0] to [0] (may be same))}
[INFO] ChimeLogger - Compressed the SDP message from 5322 to 1490 bytes.
[INFO] ChimeLogger - sending subscribe: {"attendeeId":"7ea2be98-9a94-f05c-dc47-1ee56f64f3de","sdpOffer":"","audioHost":"229f796bd6e1415232dc00346342e602.k.m2.ue1.app.chime.aws:3478","audioMuted":false,"audioCheckin":false,"receiveStreamIds":[0],"localVideoEnabled":false,"videoStreamDescriptions":[{"groupId":2,"streamId":2,"ssrc":0,"trackLabel":"AmazonChimeExpressVideo","maxBitrateKbps":0,"maxFrameRate":0,"timeEnabled":0,"disabledByWebRTC":false,"disabledByUplinkPolicy":false}],"connectionTypeHasVideo":true,"compressedSdpOffer":{"0":221,"1":88,"2":77,"3":115,"4":219,"5":54,"6":16,"7":189,"8":123,"9":198,"10":255,"11":1,"12":247,"13":14,"14":44,"15":0,"16":4,"17":41,"18":0,"19":25,"20":29,"21":108,"22":57,"23":110,"24":51,"25":77,"26":82,"27":79,"28":28,"29":167,"30":237,"31":17,"32":34,"33":65,"34":153,"35":181,"36":248,"37":49,"38":32,"39":100,"40":217,"41":254,"42":245,"43":89,"44":80,"45":82,"46":76,"47":73,"48":160,"49":27,"50":39,"51":238,"52":165,"53":227,"54":225,"55":140,"56":248,"57":22,"58":92,"59":46,"60":30,"61":118,"62":223,"63":46,"64":125,"65":55,"66":33,"67":199,"68":71,"69":245,"70":164,"71":172,"72":31,"73":139,"74":197,"75":66,"76":227,"77":244,"78":198,"79":214,"80":165,"81":65,"82":84,"83":140,"84":199,"85":146,"86":196,"87":145,"88":160,"89":132,"90":9,"91":26,"92":145,"93":152,"94":142,"95":17,"96":67,"97":239,"98":62,"99":162,"100":119,"101":151,"102":28,"103":81,"104":54,"105":62,"106":33,"107":240,"108":71,"109":143,"110":143,"111":218,"112":9,"113":62,"114":62,"115":114,"116":19,"117":130,"118":192,"119":137,"120":158,"121":204,"122":109,"123":189,"124":108,"125":212,"126":217,"127":245,"128":199,"129":243,"130":247,"131":111,"132":17,"133":65,"134":212,"135":99,"136":230,"137":222,"138":149,"139":186,"140":193,"141":122,"142":177,"143":168,"144":87,"145":184,"146":44,"147":238,"148":77,"149":230,"150":209,"151":178,"152":45,"153":50,"154":220,"155":154,"156":82,"157":87,"158":174,"159":72,"160":21,"161":250,"162":243,"163":195,"164":21,"165":98,"166":9,"167":165,"168":209,"169":152,"170":198,"171":88,"172":204,"173":34,"174":134,"175":121,"176":158,"177":11,"178":172,"179":9,"180":23,"181":120,"182":150,"183":69,"184":169,"185":136,"186":152,"187":153,"188":17,"189":45,"190":143,"191":143,"192":202,"193":137,"194":94,"195":102,"196":69,"197":141,"198":18,"199":38,"200":34,"201":129,"202":174,"203":207,"204":47,"205":71,"206":159,"207":223,"208":95,"209":141,"210":62,"211":125,"212":190,"213":28,"214":93,"215":157,"216":126,"217":185,"218":188,"219":64,"220":73,"221":132,"222":40,"223":165,"224":72,"225":194,"226":235,"227":5,"228":162,"229":254,"230":6,"231":226,"232":96,"233":201,"234":241,"235":81,"236":58,"237":217,"238":70,"239":79,"240":78,"241":216,"242":9,"243":99,"244":39,"245":73,"246":228,"247":3,"248":177,"249":46,"250":109,"251":148,"252":220,"253":238,"254":140,"255":116,"256":251,"257":234,"258":246,"259":146,"260":234,"261":42,"262":43,"263":50,"264":237,"265":140,"266":98,"267":177,"268":24,"269":115,"270":46,"271":56,"272":1,"273":111,"274":104,"275":153,"276":53,"277":136,"278":83,"279":193,"280":8,"281":167,"282":113,"283":207,"284":213,"285":38,"286":30,"287":247,"288":208,"289":32,"290":107,"291":22,"292":250,"293":1,"294":89,"295":157,"296":101,"297":118,"298":235,"299":15,"300":217,"301":166,"302":182,"303":14,"304":66,"305":154,"306":155,"307":202,"308":88,"309":237,"310":138,"311":186,"312":130,"313":155,"314":202,"315":184,"316":85,"317":109,"318":111,"319":113,"320":145,"321":129,"322":223,"323":237,"324":77,"325":90,"326":183,"327":14,"328":252,"329":250,"330":8,"331":138,"332":212,"333":224,"334":101,"335":110,"336":245,"337":92,"338":125,"339":250,"340":107,"341":29,"342":172,"343":71,"344":154,"345":85,"346":166,"347":174,"348":205,"349":63,"350":146,"351":127,"352":120,"353":120,"354":39,"355":243,"356":187,"357":95,"358":234,"359":179,"360":213,"361":229,"362":237,"363":66,"364":62,"365":254,"366":33,"367":174,"368":150,"369":219,"370":53,"371":117,"372":227,"373":95,"374":209,"375":42,"376":103,"377":139,"378":244,"379":118,"380":97,"381":60,"382":156,"383":23,"384":213,"385":220,"386":216,"387":198,"388":22,"389":149,"390":83,"391":237,"392":141,"393":198,"394":44,"395":78,"396":16,"397":99,"398":138,"399":39,"400":74,"401":92,"402":168,"403":139,"404":68,"405":209,"406":83,"407":21,"408":51,"409":69,"410":35,"411":37,"412":164,"413":18,"414":83,"415":149,"416":16,"417":69,"418":133,"419":154,"420":158,"421":170,"422":179,"423":51,"424":69,"425":207,"426":84,"427":124,"428":174,"429":104,"430":162,"431":166,"432":145,"433":226,"434":92,"435":197,"436":83,"437":117,"438":46,"439":84,"440":114,"441":161,"442":120,"443":172,"444":72,"445":172,"446":206,"447":169,"448":146,"449":240,"450":120,"451":172,"452":196,"453":169,"454":98,"455":68,"456":157,"457":189,"458":85,"459":98,"460":172,"461":166,"462":224,"463":225,"464":204,"465":191,"466":182,"467":53,"468":14,"469":50,"470":66,"471":167,"472":174,"473":209,"474":109,"475"
[INFO] ChimeLogger - received new index {"atCapacity":false,"sources":[{"streamId":1,"framerate":15,"maxBitrateKbps":1500,"trackLabel":"AmazonChimeExpressVideo","groupId":1,"avgBitrateBps":1525269,"attendeeId":"7ea2be98-9a94-f05c-dc47-1ee56f64f3de","mediaType":"VIDEO","externalUserId":"2aef395e-b8df-4f83-b076-cb38d5043c00"}],"numParticipants":2,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - received new index {"atCapacity":false,"sources":[{"streamId":1,"framerate":15,"maxBitrateKbps":1500,"trackLabel":"AmazonChimeExpressVideo","groupId":1,"avgBitrateBps":1525269,"attendeeId":"7ea2be98-9a94-f05c-dc47-1ee56f64f3de","mediaType":"VIDEO","externalUserId":"2aef395e-b8df-4f83-b076-cb38d5043c00"}],"numParticipants":2,"supportedReceiveCodecIntersection":["H264_CONSTRAINED_BASELINE_PROFILE","VP8"]}
[INFO] ChimeLogger - Index ingestion is paused, setting most recent index as pending
[INFO] ChimeLogger - got subscribe ack: {"duplex":"RX","allocations":[{"trackLabel":"","streamId":11,"groupId":2}],"compressedSdpAnswer":"5FVNj9s2EL0b8H/guYAkkqJIcQIdsrs2EmAbGHWS9kqTlJeI9QGK/tj8+oLyeu14HbRAgV5yMeQ3jzNPwzejXYWnk65KEEYYffyEPi4YIlSkOMUpmU6GKplOQoURnk5Utfbdtoe7L58eHmcIIxIxewiN6hO12XT7pHEHayLaDM4kg21UG5wG9OfvS/TbdNJUamtchwgXjKEvD4vs8+My++PzIlu+/7qYI54jQsh0oqsXJQVLCacpZTwlXMTEPugejud/ytGqNc6oYGGpMOU1LRFBW9MjUnJKBZaiuDr1oig892jw9eaAvDLGI4JTmhJCUoaR7zsfXnhr21qvguvaY19uFKT/U8EPV2/IJWOyLEnxQ65ztaduCP8mHf0P6Zy2ybb2ag2ktLlYSVMwbnSdsyJnUpVlkVMjc6X1id3vDQglMdOG5uWqKJUoaMEwk0znJcE110Xk1q5dW9971wYYnlRCC44YhVkOJYP8HuYlSAFyBkQAKSJ+L6BgESxKmN3B+zsQd0AfgOQwpyDm8XlWAiMgZiAJFHOYSZhhYDNgJdzLWHawYdtDr4bB7exob2cAn90PFD2F0EOW7ff7dG9XPui08+vMHnrrXWPbMGQ+9MmT8fYQMrUaksG2JgmusccCrfFW706jA8c5GX9Prk+a7eH12Q/uuz3+66MAniNvTcZKjHFGx1Y1YYQJIdk4VK9cQgjq+u1wyR6T1qsxFrxqh2i+5HhBY6YYaFzbR8UVwe+2g3XtSrWmtroasw+D15AzjnFBsMiRblVjISwLujDPfy0fjbM838UlsHPGdkjeWACSIykQwRwRLC72AB73EX5dABK9Dfwarhtb7VqlwwmMdjkB6DLyj6aRHH1dlJnEGONLE0j+xgMXIa0bVDt/hbZKf7uC1l23TrxtVjeoqN+4SyUC+XA4KxktJwVSfagkv/Qu5ugD5eyt6Bj5meoYuyE7wte6I3ZT+Il8Un6cCszRxu7sJlHDc9PY4J+PX0JrKvKuV/qbDe77uBqTpjM2gr6r3cYmx2POVIxaTOof3vFWNyIa20EwP08bxaQUkp+G7Xz7fwcAAP//"}
[INFO] ChimeLogger - Decompressed the SDP message from 807 to 2007 bytes.
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask took 167 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once)
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume (once)
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume waiting for dependencies: AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once)
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume
[INFO] ChimeLogger - AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume took 0 ms
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer completed subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/ReceiveRemoteVideoPauseResume (once)
[INFO] ChimeLogger - serial group task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer running subtask AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SetRemoteDescriptionTask
[INFO] ChimeLogger - running task AudioVideoStart/ec13bbcc-2854-4d6f-903c-54c7c3ba2713/7ea2be98-9a94-f05c-dc47-1ee56f64f3de/Timeout15000ms/Peer/SetRemoteDescriptionTask@maisasb One last thing I forgot to verify, are you able to see the [AudioRed] Initializing RedundantAudioEncoder line in the debug logs?
maisasb
commented
9 months ago @dinmin-amzn Yes
[DEBUG] ChimeLogger - [AudioRed] Initializing RedundantAudioEncoder
[DEBUG] ChimeLogger - [AudioRed] Setting up sender RED transform
[DEBUG] ChimeLogger - [AudioRed] Setting up receiver RED transform
[DEBUG] ChimeLogger - [AudioRed] red payload type set to 63
[DEBUG] ChimeLogger - [AudioRed] opus payload type set to 111This logs are from the other project that was not working I manage to fix the issue, it was a dependecy conflict :)
dinmin-amzn
commented
9 months ago @maisasb Thank you again for sending those logs. Glad you were able to fix the dependency issue in the main project!
liydaco
commented
9 months ago Is the fix to rollback? My audio has stopped working with:
meetingManager.audioVideo.setAudioProfile(new AudioProfile(null, false));
dinmin-amzn
commented
9 months ago @maisasb @liydaco We have released a new version 3.18.2 to NPM that includes the fix for this issue. Version 3.18.1 is now deprecated, so please update to 3.18.2 and let us know if the issue has been resolved.
What happened and what did you expect to happen?
After updated to 3.18.1 I started to get an error to initialize new meeting. I didn't change the code and start to get this error:
The audio video controller will reconnect due to status code TaskFailed and error: serial group task AudioVideoStart/b03cc98d-2348-4055-8ff6-a93ed3e82713/d525b3b3-734f-f00d-4613-c96e4a92c3ce was canceled due to subtask AudioVideoStart/b03cc98d-2348-4055-8ff6-a93ed3e82713/d525b3b3-734f-f00d-4613-c96e4a92c3ce/Timeout15000ms error: serial group task AudioVideoStart/b03cc98d-2348-4055-8ff6-a93ed3e82713/d525b3b3-734f-f00d-4613-c96e4a92c3ce/Timeout15000ms/Peer was canceled due to subtask AudioVideoStart/b03cc98d-2348-4055-8ff6-a93ed3e82713/d525b3b3-734f-f00d-4613-c96e4a92c3ce/Timeout15000ms/Peer/SubscribeAndReceiveSubscribeAckTask (once) error: Cannot read properties of null (reading '1')This error occur after audioVideo.start()
I followed this documentation to create the configuration: https://github.com/aws/amazon-chime-sdk-js/blob/main/guides/03_API_Overview.md
Have you reviewed our existing documentation?
Reproduction steps
This is the configuration that I use to start the meeting, without the subscribers and observers In version 3.17.0 works, but in 3.18.0 start to get error.
Amazon Chime SDK for JavaScript version
3.18.1
What browsers are you seeing the problem on?
chrome
Browser version
117.0.5938.132
Meeting and Attendee ID Information.
No response
Browser console logs