awslabs / amazon-kinesis-video-streams-webrtc-sdk-c

Amazon Kinesis Video Streams Webrtc SDK is for developers to install and customize realtime communication between devices and enable secure streaming of video, audio to Kinesis Video Streams.
https://awslabs.github.io/amazon-kinesis-video-streams-webrtc-sdk-c/group__PublicMemberFunctions.html
Apache License 2.0
1.01k stars 304 forks source link

Sample freepeerconnection blocked for 3 seconds #1045

Closed jerryjwb closed 3 years ago

jerryjwb commented 3 years ago

Logging SDK : amazon-kinesis-video-streams-webrtc-sdk-c-1.4.1 In the sample kvswertcclientmaster, if the function interface (freeamplestreamingsession) is in the common. C file, freepeerconnection will block for 3 seconds Operation steps: in Google Chrome browser, first start viewer, then stop viewer. When it is executed to freepeerconnection, it will block for 3 seconds

test20210115181516

锘?021-01-15 03:38:23 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:28 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:31 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:31 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:31 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 9 2021-01-15 03:38:33 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:33 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJ0eXBlIjoib2ZmZXIiLCJzZHAiOiJ2PTBcclxubz0tIDg0MDA3MDQyMjIyOTAzNDg4NDQgMiBJTiBJUDQgMTI3LjAuMC4xXHJcbnM9LVxyXG50PTAgMFxyXG5hPWdyb3VwOkJVTkRMRSAwIDFcclxuYT1tc2lkLXNlbWFudGljOiBXTVNcclxubT1hdWRpbyA5IFVEUC9UTFMvUlRQL1NBVlBGIDExMSAxMDMgMTA0IDkgMCA4IDEwNiAxMDUgMTMgMTEwIDExMiAxMTMgMTI2XHJcbmM9SU4gSVA0IDAuMC4wLjBcclxuYT1ydGNwOjkgSU4gSVA0IDAuMC4wLjBcclxuYT1pY2UtdWZyYWc6bVFrQlxyXG5hPWljZS1wd2Q6aEhGK2t3VlFKa1Z2QjBuaFJvQzZJcFRLXHJcbmE9aWNlLW9wdGlvbnM6dHJpY2tsZVxyXG5hPWZpbmdlcnByaW50OnNoYS0yNTYgMDQ6ODg6RUE6MTA6Mzg6NkQ6QUI6NUY6NjA6OEE6OTA6ODE6QTY6NEI6OTI6QTA6NkQ6MUM6Rjk6NDU6Rjg6MTM6OEE6QTg6OTQ6QUE6Qzk6QkY6RUU6NTE6MTA6NDlcclxuYT1zZXR1cDphY3RwYXNzXHJcbmE9bWlkOjBcclxuYT1leHRtYXA6MSB1cm46aWV0ZjpwYXJhbXM6cnRwLWhkcmV4dDpzc3JjLWF1ZGlvLWxldmVsXHJcbmE9ZXh0bWFwOjIgaHR0cDovL3d3dy53ZWJydGMub3JnL2V4cGVyaW1lbnRzL3J0cC1oZHJleHQvYWJzLXNlbmQtdGltZVxyXG5hPWV4dG1hcDozIGh0dHA6Ly93d3cuaWV0Zi5vcmcvaWQvZHJhZnQtaG9sbWVyLXJtY2F0LXRyYW5zcG9ydC13aWRlLWNjLWV4dGVuc2lvbnMtMDFcclxuYT1leHRtYXA6NCB1cm46aWV0ZjpwYXJhbXM6cnRwLWhkcmV4dDpzZGVzOm1pZFxyXG5hPWV4dG1hcDo1IHVybjppZXRmOnBhcmFtczpydHAtaGRyZXh0OnNkZXM6cnRwLXN0cmVhbS1pZFxyXG5hPWV4dG1hcDo2IHVybjppZXRmOnBhcmFtczpydHAtaGRyZXh0OnNkZXM6cmVwYWlyZWQtcnRwLXN0cmVhbS1pZFxyXG5hPXJlY3Zvbmx5XHJcbmE9cnRjcC1tdXhcclxuYT1ydHBtYXA6MTExIG9wdXMvNDgwMDAvMlxyXG5hPXJ0Y3AtZmI6MTExIHRyYW5zcG9ydC1jY1xyXG5hPWZtdHA6MTExIG1pbnB0aW1lPTEwO3VzZWluYmFuZGZlYz0xXHJcbmE9cnRwbWFwOjEwMyBJU0FDLzE2MDAwXHJcbmE9cnRwbWFwOjEwNCBJU0FDLzMyMDAwXHJcbmE9cnRwbWFwOjkgRzcyMi84MDAwXHJcbmE9cnRwbWFwOjAgUENNVS84MDAwXHJcbmE9cnRwbWFwOjggUENNQS84MDAwXHJcbmE9cnRwbWFwOjEwNiBDTi8zMjAwMFxyXG5hPXJ0cG1hcDoxMDUgQ04vMTYwMDBcclxuYT1ydHBtYXA6MTMgQ04vODAwMFxyXG5hPXJ0cG1hcDoxMTAgdGVsZXBob25lLWV2ZW50LzQ4MDAwXHJcbmE9cnRwbWFwOjExMiB0ZWxlcGhvbmUtZXZlbnQvMzIwMDBcclxuYT1ydHBtYXA6MTEzIHRlbGVwaG9uZS1ldmVudC8xNjAwMFxyXG5hPXJ0cG1hcDoxMjYgdGVsZXBob25lLWV2ZW50LzgwMDBcclxubT12aWRlbyA5IFVEUC9UTFMvUlRQL1NBVlBGIDk2IDk3IDk4IDk5IDEwMCAxMDEgMTIyIDEwMiAxMjAgMTI3IDExOSAxMjUgMTA3IDEwOCAxMDkgMTIxIDExNCAxMTUgMTI0IDExOCAxMjNcclxuYz1JTiBJUDQgMC4wLjAuMFxyXG5hPXJ0Y3A6OSBJTiBJUDQgMC4wLjAuMFxyXG5hPWljZS11ZnJhZzptUWtCXHJcbmE9aWNlLXB3ZDpoSEYra3dWUUprVnZCMG5oUm9DNklwVEtcclxuYT1pY2Utb3B0aW9uczp0cmlja2xlXHJcbmE9ZmluZ2VycHJpbnQ6c2hhLTI1NiAwNDo4ODpFQToxMDozODo2RDpBQjo1Rjo2MDo4QTo5MDo4MTpBNjo0Qjo5MjpBMDo2RDoxQzpGOTo0NTpGODoxMzo4QTpBODo5NDpBQTpDOTpCRjpFRTo1MToxMDo0OVxyXG5hPXNldHVwOmFjdHBhc3NcclxuYT1taWQ6MVxyXG5hPWV4dG1hcDoxNCB1cm46aWV0ZjpwYXJhbXM6cnRwLWhkcmV4dDp0b2Zmc2V0XHJcbmE9ZXh0bWFwOjIgaHR0cDovL3d3dy53ZWJydGMub3JnL2V4cGVyaW1lbnRzL3J0cC1oZHJleHQvYWJzLXNlbmQtdGltZVxyXG5hPWV4dG1hcDoxMyB1cm46M2dwcDp2aWRlby1vcmllbnRhdGlvblxyXG5hPWV4dG1hcDozIGh0dHA6Ly93d3cuaWV0Zi5vcmcvaWQvZHJhZnQtaG9sbWVyLXJtY2F0LXRyYW5zcG9ydC13aWRlLWNjLWV4dGVuc2lvbnMtMDFcclxuYT1leHRtYXA6MTIgaHR0cDovL3d3dy53ZWJydGMub3JnL2V4cGVyaW1lbnRzL3J0cC1oZHJleHQvcGxheW91dC1kZWxheVxyXG5hPWV4dG1hcDoxMSBodHRwOi8vd3d3LndlYnJ0Yy5vcmcvZXhwZXJpbWVudHMvcnRwLWhkcmV4dC92aWRlby1jb250ZW50LXR5cGVcclxuYT1leHRtYXA6NyBodHRwOi8vd3d3LndlYnJ0Yy5vcmcvZXhwZXJpbWVudHMvcnRwLWhkcmV4dC92aWRlby10aW1pbmdcclxuYT1leHRtYXA6OCBodHRwOi8vd3d3LndlYnJ0Yy5vcmcvZXhwZXJpbWVudHMvcnRwLWhkcmV4dC9jb2xvci1zcGFjZVxyXG5hPWV4dG1hcDo0IHVybjppZXRmOnBhcmFtczpydHAtaGRyZXh0OnNkZXM6bWlkXHJcbmE9ZXh0bWFwOjUgdXJuOmlldGY6cGFyYW1zOnJ0cC1oZHJleHQ6c2RlczpydHAtc3RyZWFtLWlkXHJcbmE9ZXh0bWFwOjYgdXJuOmlldGY6cGFyYW1zOnJ0cC1oZHJleHQ6c2RlczpyZXBhaXJlZC1ydHAtc3RyZWFtLWlkXHJcbmE9cmVjdm9ubHlcclxuYT1ydGNwLW11eFxyXG5hPXJ0Y3AtcnNpemVcclxuYT1ydHBtYXA6OTYgVlA4LzkwMDAwXHJcbmE9cnRjcC1mYjo5NiBnb29nLXJlbWJcclxuYT1ydGNwLWZiOjk2IHRyYW5zcG9ydC1jY1xyXG5hPXJ0Y3AtZmI6OTYgY2NtIGZpclxyXG5hPXJ0Y3AtZmI6OTYgbmFja1xyXG5hPXJ0Y3AtZmI6OTYgbmFjayBwbGlcclxuYT1ydHBtYXA6OTcgcnR4LzkwMDAwXHJcbmE9Zm10cDo5NyBhcHQ9OTZcclxuYT1ydHBtYXA6OTggVlA5LzkwMDAwXHJcbmE9cnRjcC1mYjo5OCBnb29nLXJlbWJcclxuYT1ydGNwLWZiOjk4IHRyYW5zcG9ydC1jY1xyXG5hPXJ0Y3AtZmI6OTggY2NtIGZpclxyXG5hPXJ0Y3AtZmI6OTggbmFja1xyXG5hPXJ0Y3AtZmI6OTggbmFjayBwbGlcclxuYT1mbXRwOjk4IHByb2ZpbGUtaWQ9MFxyXG5hPXJ0cG1hcDo5OSBydHgvOTAwMDBcclxuYT1mbXRwOjk5IGFwdD05OFxyXG5hPXJ0cG1hcDoxMDAgVlA5LzkwMDAwXHJcbmE9cnRjcC1mYjoxMDAgZ29vZy1yZW1iXHJcbmE9cnRjcC1mYjoxMDAgdHJhbnNwb3J0LWNjXHJcbmE9cnRjcC1mYjoxMDAgY2NtIGZpclxyX 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive G5hPXJ0Y3AtZmI6MTAwIG5hY2tcclxuYT1ydGNwLWZiOjEwMCBuYWNrIHBsaVxyXG5hPWZtdHA6MTAwIHByb2ZpbGUtaWQ9MlxyXG5hPXJ0cG1hcDoxMDEgcnR4LzkwMDAwXHJcbmE9Zm10cDoxMDEgYXB0PTEwMFxyXG5hPXJ0cG1hcDoxMjIgVlA5LzkwMDAwXHJcbmE9cnRjcC1mYjoxMjIgZ29vZy1yZW1iXHJcbmE9cnRjcC1mYjoxMjIgdHJhbnNwb3J0LWNjXHJcbmE9cnRjcC1mYjoxMjIgY2NtIGZpclxyXG5hPXJ0Y3AtZmI6MTIyIG5hY2tcclxuYT1ydGNwLWZiOjEyMiBuYWNrIHBsaVxyXG5hPWZtdHA6MTIyIHByb2ZpbGUtaWQ9MVxyXG5hPXJ0cG1hcDoxMDIgSDI2NC85MDAwMFxyXG5hPXJ0Y3AtZmI6MTAyIGdvb2ctcmVtYlxyXG5hPXJ0Y3AtZmI6MTAyIHRyYW5zcG9ydC1jY1xyXG5hPXJ0Y3AtZmI6MTAyIGNjbSBmaXJcclxuYT1ydGNwLWZiOjEwMiBuYWNrXHJcbmE9cnRjcC1mYjoxMDIgbmFjayBwbGlcclxuYT1mbXRwOjEwMiBsZXZlbC1hc3ltbWV0cnktYWxsb3dlZD0xO3BhY2tldGl6YXRpb24tbW9kZT0xO3Byb2ZpbGUtbGV2ZWwtaWQ9NDIwMDFmXHJcbmE9cnRwbWFwOjEyMCBydHgvOTAwMDBcclxuYT1mbXRwOjEyMCBhcHQ9MTAyXHJcbmE9cnRwbWFwOjEyNyBIMjY0LzkwMDAwXHJcbmE9cnRjcC1mYjoxMjcgZ29vZy1yZW1iXHJcbmE9cnRjcC1mYjoxMjcgdHJhbnNwb3J0LWNjXHJcbmE9cnRjcC1mYjoxMjcgY2NtIGZpclxyXG5hPXJ0Y3AtZmI6MTI3IG5hY2tcclxuYT1ydGNwLWZiOjEyNyBuYWNrIHBsaVxyXG5hPWZtdHA6MTI3IGxldmVsLWFzeW1tZXRyeS1hbGxvd2VkPTE7cGFja2V0aXphdGlvbi1tb2RlPTA7cHJvZmlsZS1sZXZlbC1pZD00MjAwMWZcclxuYT1ydHBtYXA6MTE5IHJ0eC85MDAwMFxyXG5hPWZtdHA6MTE5IGFwdD0xMjdcclxuYT1ydHBtYXA6MTI1IEgyNjQvOTAwMDBcclxuYT1ydGNwLWZiOjEyNSBnb29nLXJlbWJcclxuYT1ydGNwLWZiOjEyNSB0cmFuc3BvcnQtY2NcclxuYT1ydGNwLWZiOjEyNSBjY20gZmlyXHJcbmE9cnRjcC1mYjoxMjUgbmFja1xyXG5hPXJ0Y3AtZmI6MTI1IG5hY2sgcGxpXHJcbmE9Zm10cDoxMjUgbGV2ZWwtYXN5bW1ldHJ5LWFsbG93ZWQ9MTtwYWNrZXRpemF0aW9uLW1vZGU9MTtwcm9maWxlLWxldmVsLWlkPTQyZTAxZlxyXG5hPXJ0cG1hcDoxMDcgcnR4LzkwMDAwXHJcbmE9Zm10cDoxMDcgYXB0PTEyNVxyXG5hPXJ0cG1hcDoxMDggSDI2NC85MDAwMFxyXG5hPXJ0Y3AtZmI6MTA4IGdvb2ctcmVtYlxyXG5hPXJ0Y3AtZmI6MTA4IHRyYW5zcG9ydC1jY1xyXG5hPXJ0Y3AtZmI6MTA4IGNjbSBmaXJcclxuYT1ydGNwLWZiOjEwOCBuYWNrXHJcbmE9cnRjcC1mYjoxMDggbmFjayBwbGlcclxuYT1mbXRwOjEwOCBsZXZlbC1hc3ltbWV0cnktYWxsb3dlZD0xO3BhY2tldGl6YXRpb24tbW9kZT0wO3Byb2ZpbGUtbGV2ZWwtaWQ9NDJlMDFmXHJcbmE9cnRwbWFwOjEwOSBydHgvOTAwMDBcclxuYT1mbXRwOjEwOSBhcHQ9MTA4XHJcbmE9cnRwbWFwOjEyMSBIMjY0LzkwMDAwXHJcbmE9cnRjcC1mYjoxMjEgZ29vZy1yZW1iXHJcbmE9cnRjcC1mYjoxMjEgdHJhbnNwb3J0LWNjXHJcbmE9cnRjcC1mYjoxMjEgY2NtIGZpclxyXG5hPXJ0Y3AtZmI6MTIxIG5hY2tcclxuYT1ydGNwLWZiOjEyMSBuYWNrIHBsaVxyXG5hPWZtdHA6MTIxIGxldmVsLWFzeW1tZXRyeS1hbGxvd2VkPTE7cGFja2V0aXphdGlvbi1tb2RlPTE7cHJvZmlsZS1sZXZlbC1pZD00ZDAwMTVcclxuYT1ydHBtYXA6MTE0IEgyNjQvOTAwMDBcclxuYT1ydGNwLWZiOjExNCBnb29nLXJlbWJcclxuYT1ydGNwLWZiOjExNCB0cmFuc3BvcnQtY2NcclxuYT1ydGNwLWZiOjExNCBjY20gZmlyXHJcbmE9cnRjcC1mYjoxMTQgbmFja1xyXG5hPXJ0Y3AtZmI6MTE0IG5hY2sgcGxpXHJcbmE9Zm10cDoxMTQgbGV2ZWwtYXN5bW1ldHJ5LWFsbG93ZWQ9MTtwYWNrZXRpemF0aW9uLW1vZGU9MTtwcm9maWxlLWxldmVsLWlkPTY0MDAxNVxyXG5hPXJ0cG1hcDoxMTUgcnR4LzkwMDAwXHJcbmE9Zm10cDoxMTUgYXB0PTExNFxyXG5hPXJ0cG1hcDoxMjQgcmVkLzkwMDAwXHJcbmE9cnRwbWFwOjExOCBydHgvOTAwMDBcclxuYT1mbXRwOjExOCBhcHQ9MTI0XHJcbmE9cnRwbWFwOjEyMyB1bHBmZWMvOTAwMDBcclxuIn0=","messageType":"SDP_OFFER","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MzAxMzk1MzYyNCAxIHVkcCAyMTIyMjYwMjIzIDE5Mi4xNjguMS4xMDAgNTk5NDcgdHlwIGhvc3QgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMCIsInNkcE1MaW5lSW5kZXgiOjB9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MzAxMzk1MzYyNCAxIHVkcCAyMTIyMjYwMjIzIDE5Mi4xNjguMS4xMDAgNTk5NDggdHlwIGhvc3QgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMSIsInNkcE1MaW5lSW5kZXgiOjF9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6ODU0NDEzMDM2IDEgdWRwIDE2ODYwNTI2MDcgMTgzLjE0LjEzNS4xODcgNjUxOTYgdHlwIHNyZmx4IHJhZGRyIDE5Mi4xNjguMS4xMDAgcnBvcnQgNTk5NDcgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMCIsInNkcE1MaW5lSW5kZXgiOjB9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6ODU0NDEzMDM2IDEgdWRwIDE2ODYwNTI2MDcgMTgzLjE0LjEzNS4xODcgNjUxOTcgdHlwIHNyZmx4IHJhZGRyIDE5Mi4xNjguMS4xMDAgcnBvcnQgNTk5NDggZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMSIsInNkcE1MaW5lSW5kZXgiOjF9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6NDI0NzE3MjI2NCAxIHRjcCAxNTE4MjgwNDQ3IDE5Mi4xNjguMS4xMDAgOSB0eXAgaG9zdCB0Y3B0eXBlIGFjdGl2ZSBnZW5lcmF0aW9uIDAgdWZyYWcgbVFrQiBuZXR3b3JrLWlkIDEiLCJzZHBNaWQiOiIwIiwic2RwTUxpbmVJbmRleCI6MH0=","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 INFO signalingClientGetIceConfigInfoCount(): Signaling Client Get ICE Config Info Count 2021-01-15 03:38:34 INFO signalingClientGetIceConfigInfo(): Signaling Client Get ICE Config Info 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6NDI0NzE3MjI2NCAxIHRjcCAxNTE4MjgwNDQ3IDE5Mi4xNjguMS4xMDAgOSB0eXAgaG9zdCB0Y3B0eXBlIGFjdGl2ZSBnZW5lcmF0aW9uIDAgdWZyYWcgbVFrQiBuZXR3b3JrLWlkIDEiLCJzZHBNaWQiOiIxIiwic2RwTUxpbmVJbmRleCI6MX0=","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MzQ0NzA4Mjc0NiAxIHVkcCA0MTg4NTk1MSA1Mi44MS44Mi4yMDkgNjI1NzAgdHlwIHJlbGF5IHJhZGRyIDE4My4xNC4xMzUuMTg3IHJwb3J0IDY1MTk3IGdlbmVyYXRpb24gMCB1ZnJhZyBtUWtCIG5ldHdvcmstaWQgMSIsInNkcE1pZCI6IjEiLCJzZHBNTGluZUluZGV4IjoxfQ==","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MzE0MDc5MTIxMiAxIHVkcCA0MTg4NjcxOSA1Mi44MS4xOTQuMyA1MzQ3MCB0eXAgcmVsYXkgcmFkZHIgMTgzLjE0LjEzNS4xODcgcnBvcnQgNjUxOTYgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMCIsInNkcE1MaW5lSW5kZXgiOjB9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MzE0MDc5MTIxMiAxIHVkcCA0MTg4NjcxOSA1Mi44MS4xOTQuMyA1MDc5MiB0eXAgcmVsYXkgcmFkZHIgMTgzLjE0LjEzNS4xODcgcnBvcnQgNjUxOTcgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMSIsInNkcE1MaW5lSW5kZXgiOjF9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MzQ0NzA4Mjc0NiAxIHVkcCA0MTg4NTk1MSA1Mi44MS44Mi4yMDkgNTMxNTIgdHlwIHJlbGF5IHJhZGRyIDE4My4xNC4xMzUuMTg3IHJwb3J0IDY1MTk2IGdlbmVyYXRpb24gMCB1ZnJhZyBtUWtCIG5ldHdvcmstaWQgMSIsInNkcE1pZCI6IjAiLCJzZHBNTGluZUluZGV4IjowfQ==","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6Mzk0NTE0MjgyNSAxIHVkcCA4MzMyMDMxIDUyLjgxLjE5NC4zIDY0MzE2IHR5cCByZWxheSByYWRkciAxODMuMTQuMTM1LjE4NyBycG9ydCA2NTUxOSBnZW5lcmF0aW9uIDAgdWZyYWcgbVFrQiBuZXR3b3JrLWlkIDEiLCJzZHBNaWQiOiIxIiwic2RwTUxpbmVJbmRleCI6MX0=","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6Mzk0NTE0MjgyNSAxIHVkcCA4MzMxNzc1IDUyLjgxLjE5NC4zIDU5Mzc5IHR5cCByZWxheSByYWRkciAxODMuMTQuMTM1LjE4NyBycG9ydCA2NTUxNyBnZW5lcmF0aW9uIDAgdWZyYWcgbVFrQiBuZXR3b3JrLWlkIDEiLCJzZHBNaWQiOiIwIiwic2RwTUxpbmVJbmRleCI6MH0=","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MjY0MDY0MTkxOSAxIHVkcCA4MzMxMjYzIDUyLjgxLjgyLjIwOSA1MTI0MCB0eXAgcmVsYXkgcmFkZHIgMTgzLjE0LjEzNS4xODcgcnBvcnQgNjU1MTYgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMCIsInNkcE1MaW5lSW5kZXgiOjB9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:34 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:34 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:35 DEBUG iceAgentValidateKvsRtcConfig(): iceLocalCandidateGatheringTimeout: 10000 ms iceConnectionCheckTimeout: 0 ms iceCandidateNominationTimeout: 10000 ms iceConnectionCheckPollingInterval: 0 ms 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MjY0MDY0MTkxOSAxIHVkcCA4MzMxMDA3IDUyLjgxLjgyLjIwOSA2MzgyOCB0eXAgcmVsYXkgcmFkZHIgMTgzLjE0LjEzNS4xODcgcnBvcnQgNjU1MTggZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMCIsInNkcE1MaW5lSW5kZXgiOjB9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MjY0MDY0MTkxOSAxIHVkcCA4MzMxMjYzIDUyLjgxLjgyLjIwOSA1OTU3OCB0eXAgcmVsYXkgcmFkZHIgMTgzLjE0LjEzNS4xODcgcnBvcnQgNjU1MjEgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMSIsInNkcE1MaW5lSW5kZXgiOjF9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6Mzk0NTE0MjgyNSAxIHVkcCA4MzMxNzc1IDUyLjgxLjE5NC4zIDUyMTQ1IHR5cCByZWxheSByYWRkciAxODMuMTQuMTM1LjE4NyBycG9ydCA2NTUyMCBnZW5lcmF0aW9uIDAgdWZyYWcgbVFrQiBuZXR3b3JrLWlkIDEiLCJzZHBNaWQiOiIxIiwic2RwTUxpbmVJbmRleCI6MX0=","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MjY0MDY0MTkxOSAxIHVkcCA4MzMxMDA3IDUyLjgxLjgyLjIwOSA1MTkyNyB0eXAgcmVsYXkgcmFkZHIgMTgzLjE0LjEzNS4xODcgcnBvcnQgNjU1MjQgZ2VuZXJhdGlvbiAwIHVmcmFnIG1Ra0IgbmV0d29yay1pZCAxIiwic2RwTWlkIjoiMSIsInNkcE1MaW5lSW5kZXgiOjF9","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client receive {"messagePayload":"eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6Mzk0NTE0MjgyNSAxIHVkcCA4MzMyMDMxIDUyLjgxLjE5NC4zIDYyOTY3IHR5cCByZWxheSByYWRkciAxODMuMTQuMTM1LjE4NyBycG9ydCA2NTUxNCBnZW5lcmF0aW9uIDAgdWZyYWcgbVFrQiBuZXR3b3JrLWlkIDEiLCJzZHBNaWQiOiIwIiwic2RwTUxpbmVJbmRleCI6MH0=","messageType":"ICE_CANDIDATE","senderClientId":"4CJ2IGNPXIO"} 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:35 VERBOSE setPayloadTypesFromOffer(): Found H264 payload type 102 with score 2: (null) 2021-01-15 03:38:35 VERBOSE setPayloadTypesFromOffer(): Found H264 payload type 125 with score 3: (null) 2021-01-15 03:38:35 DEBUG createSocket(): setsockopt() failed with errno Protocol not available 2021-01-15 03:38:35 DEBUG createSocket(): setsockopt() failed with errno Protocol not available 2021-01-15 03:38:35 DEBUG createSocket(): setsockopt() failed with errno Protocol not available 2021-01-15 03:38:35 DEBUG stepStateMachine(): State Machine - Current state: 0x0000000000000001, Next state: 0x0000000000000002 2021-01-15 03:38:35 DEBUG iceAgentCheckConnectionStateSetup(): ice candidate pair count 0 2021-01-15 03:38:35 DEBUG stepIceAgentStateMachine(): Ice agent state changed from ICE_AGENT_STATE_NONE to ICE_AGENT_STATE_CHECK_CONNECTION. 2021-01-15 03:38:35 INFO onConnectionStateChange(): New connection state 2

---newState=2, time=1610681915--- 2021-01-15 03:38:35 DEBUG createSocket(): setsockopt() failed with errno Protocol not available 2021-01-15 03:38:35 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync 2021-01-15 03:38:35 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_NEW to TURN_STATE_CHECK_SOCKET_CONNECTION 2021-01-15 03:38:35 DEBUG writeLwsData(): Sending data over web socket: { "action": "SDP_ANSWER", "RecipientClientId": "4CJ2IGNPXIO", "MessagePayload": "eyJ0eXBlIjogImFuc3dlciIsICJzZHAiOiAidj0wXHJcbm89LSAxODkzMTI3OTYzIDIgSU4gSVA0IDEyNy4wLjAuMVxyXG5zPS1cclxudD0wIDBcclxuYT1ncm91cDpCVU5ETEUgMCAxXHJcbmE9bXNpZC1zZW1hbnRpYzogV01TIG15S3ZzVmlkZW9TdHJlYW1cclxubT1hdWRpbyA5IFVEUC9UTFMvUlRQL1NBVlBGIDExMVxyXG5jPUlOIElQNCAxMjcuMC4wLjFcclxuYT1jYW5kaWRhdGU6MyAxIHVkcCAxNjc3NzIxNSAwMDAwOjAwMDA6MDAwMDowMDAwOjAwMDA6MDAwMDowMDAwOjAwMDAgMCB0eXAgcmVsYXkgcmFkZHIgOjovMCBycG9ydCAwIGdlbmVyYXRpb24gMCBuZXR3b3JrLWNvc3QgOTk5XHJcbmE9Y2FuZGlkYXRlOjIgMSB1ZHAgMTY3NzcyMTUgMDAwMDowMDAwOjAwMDA6MDAwMDowMDAwOjAwMDA6MDAwMDowMDAwIDAgdHlwIHJlbGF5IHJhZGRyIDo6LzAgcnBvcnQgMCBnZW5lcmF0aW9uIDAgbmV0d29yay1jb3N0IDk5OVxyXG5hPWNhbmRpZGF0ZToxIDEgdWRwIDE2OTQ0OTg4MTUgMTkyLjE2OC4xLjEwMyAzNjM3MyB0eXAgc3JmbHggcmFkZHIgMC4wLjAuMCBycG9ydCAwIGdlbmVyYXRpb24gMCBuZXR3b3JrLWNvc3QgOTk5XHJcbmE9Y2FuZGlkYXRlOjAgMSB1ZHAgMjEzMDcwNjQzMSAxOTIuMTY4LjEuMTAzIDQ1OTA4IHR5cCBob3N0IHJhZGRyIDAuMC4wLjAgcnBvcnQgMCBnZW5lcmF0aW9uIDAgbmV0d29yay1jb3N0IDk5OVxyXG5hPW1zaWQ6bXlLdnNWaWRlb1N0cmVhbSBteUF1ZGlvVHJhY2tcclxuYT1zc3JjOjk1Nzk1NTcwNiBjbmFtZTpZZjdJQk85Q0lTVGdaVmsyXHJcbmE9c3NyYzo5NTc5NTU3MDYgbXNpZDpteUt2c1ZpZGVvU3RyZWFtIG15QXVkaW9UcmFja1xyXG5hPXNzcmM6OTU3OTU1NzA2IG1zbGFiZWw6bXlLdnNWaWRlb1N0cmVhbVxyXG5hPXNzcmM6OTU3OTU1NzA2IGxhYmVsOm15QXVkaW9UcmFja1xyXG5hPXJ0Y3A6OSBJTiBJUDQgMC4wLjAuMFxyXG5hPWljZS11ZnJhZzpsWUh0XHJcbmE9aWNlLXB3ZDoxdkpHTzcyNFU2b3pab0MxYzBzaGlOdFBcclxuYT1pY2Utb3B0aW9uczp0cmlja2xlXHJcbmE9ZmluZ2VycHJpbnQ6c2hhLTI1NiA2OTpGNTo1MDpBNzpENjo2ODpDRjo5Qzo4NTpFNDo3QTpGQTo1Rjo2MTo5Mjo3NzoyMjoxRDo0NTozNToxMDozMzpFODpEMzo5NzowMTo5RTowNjo1Qzo5NDpFNzpDQVxyXG5hPXNldHVwOmFjdGl2ZVxyXG5hPW1pZDowXHJcbmE9c2VuZG9ubHlcclxuYT1ydGNwLW11eFxyXG5hPXJ0Y3AtcnNpemVcclxuYT1ydHBtYXA6MTExIG9wdXMvNDgwMDAvMlxyXG5hPWZtdHA6MTExIG1pbnB0aW1lPTEwO3VzZWluYmFuZGZlYz0xXHJcbmE9cnRjcC1mYjoxMTEgbmFja1xyXG5tPXZpZGVvIDkgVURQL1RMUy9SVFAvU0FWUEYgMTI1XHJcbmM9SU4gSVA0IDEyNy4wLjAuMVxyXG5hPWNhbmRpZGF0ZTozIDEgdWRwIDE2Nzc3MjE1IDAwMDA6MDAwMDowMDAwOjAwMDA6MDAwMDowMDAwOjAwMDA6MDAwMCAwIHR5cCByZWxheSByYWRkciA6Oi8wIHJwb3J0IDAgZ2VuZXJhdGlvbiAwIG5ldHdvcmstY29zdCA5OTlcclxuYT1jYW5kaWRhdGU6MiAxIHVkcCAxNjc3NzIxNSAwMDAwOjAwMDA6MDAwMDowMDAwOjAwMDA6MDAwMDowMDAwOjAwMDAgMCB0eXAgcmVsYXkgcmFkZHIgOjovMCBycG9ydCAwIGdlbmVyYXRpb24gMCBuZXR3b3JrLWNvc3QgOTk5XHJcbmE9Y2FuZGlkYXRlOjEgMSB1ZHAgMTY5NDQ5ODgxNSAxOTIuMTY4LjEuMTAzIDM2MzczIHR5cCBzcmZseCByYWRkciAwLjAuMC4wIHJwb3J0IDAgZ2VuZXJhdGlvbiAwIG5ldHdvcmstY29zdCA5OTlcclxuYT1jYW5kaWRhdGU6MCAxIHVkcCAyMTMwNzA2NDMxIDE5Mi4xNjguMS4xMDMgNDU5MDggdHlwIGhvc3QgcmFkZHIgMC4wLjAuMCBycG9ydCAwIGdlbmVyYXRpb24gMCBuZXR3b3JrLWNvc3QgOTk5XHJcbmE9bXNpZDpteUt2c1ZpZGVvU3RyZWFtIG15VmlkZW9UcmFja1xyXG5hPXNzcmM6NDQ1ODE1NzcyIGNuYW1lOllmN0lCTzlDSVNUZ1pWazJcclxuYT1zc3JjOjQ0NTgxNTc3MiBtc2lkOm15S3ZzVmlkZW9TdHJlYW0gbXlWaWRlb1RyYWNrXHJcbmE9c3NyYzo0NDU4MTU3NzIgbXNsYWJlbDpteUt2c1ZpZGVvU3RyZWFtXHJcbmE9c3NyYzo0NDU4MTU3NzIgbGFiZWw6bXlWaWRlb1RyYWNrXHJcbmE9cnRjcDo5IElOIElQNCAwLjAuMC4wXHJcbmE9aWNlLXVmcmFnOmxZSHRcclxuYT1pY2UtcHdkOjF2SkdPNzI0VTZvelpvQzFjMHNoaU50UFxyXG5hPWljZS1vcHRpb25zOnRyaWNrbGVcclxuYT1maW5nZXJwcmludDpzaGEtMjU2IDY5OkY1OjUwOkE3OkQ2OjY4OkNGOjlDOjg1OkU0OjdBOkZBOjVGOjYxOjkyOjc3OjIyOjFEOjQ1OjM1OjEwOjMzOkU4OkQzOjk3OjAxOjlFOjA2OjVDOjk0OkU3OkNBXHJcbmE9c2V0dXA6YWN0aXZlXHJcbmE9bWlkOjFcclxuYT1zZW5kb25seVxyXG5hPXJ0Y3AtbXV4XHJcbmE9cnRjcC1yc2l6ZVxyXG5hPXJ0cG1hcDoxMjUgSDI2NC85MDAwMFxyXG5hPWZtdHA6MTI1IGxldmVsLWFzeW1tZXRyeS1hbGxvd2VkPTE7cGFja2V0aXphdGlvbi1tb2RlPTE7cHJvZmlsZS1sZXZlbC1pZD00MmUwMWZcclxuYT1ydGNwLWZiOjEyNSBuYWNrXHJcbiJ9" } 2021-01-15 03:38:35 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_NEW to TURN_STATE_CHECK_SOCKET_CONNECTION 2021-01-15 03:38:35 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_CHECK_SOCKET_CONNECTION to TURN_STATE_GET_CREDENTIALS 2021-01-15 03:38:35 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_CHECK_SOCKET_CONNECTION to TURN_STATE_GET_CREDENTIALS 2021-01-15 03:38:35 VERBOSE lwsHttpCallbackRoutine(): HTTPS callback with reason 71 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 71 2021-01-15 03:38:35 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:35 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:35 DEBUG handleOffer(): time taken to send answer 684 ms 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: e6+yIyNkS. Ip: 192.168.1.100:59947. Type: host. Protocol: UDP. 2021-01-15 03:38:35 ERROR iceAgentAddRemoteCandidate(): operation returned status code: 0x5a000005 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: 5Lh7iVqGB. Ip: 183.14.135.187:65197. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: 758CU6QtA. Ip: 183.14.135.187:65196. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: XB1up7slP. Ip: 192.168.1.100:59948. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: o78FCTwJA. Ip: 52.81.82.209:62570. Type: host. Protocol: UDP. 2021-01-15 03:38:35 ERROR iceAgentAddRemoteCandidate(): operation returned status code: 0x5a000005 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: P7QbEVjRz. Ip: 52.81.194.3:64316. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: prq+M//Fp. Ip: 52.81.194.3:59379. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: 75YWufe7s. Ip: 52.81.82.209:53152. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: 619GQHg/V. Ip: 52.81.194.3:50792. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: 9iw7WlxU6. Ip: 52.81.82.209:51240. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: xTLkaQG5I. Ip: 52.81.194.3:53470. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: wjPmpQv3r. Ip: 52.81.82.209:59578. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: AjqgtXa+1. Ip: 52.81.82.209:63828. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: KvVQqylYX. Ip: 52.81.194.3:52145. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: 9pcSjJrT7. Ip: 52.81.82.209:51927. Type: host. Protocol: UDP. 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New remote ice candidate discovered. Id: GMA5XuvBl. Ip: 52.81.194.3:62967. Type: host. Protocol: UDP. 2021-01-15 03:38:35 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:35 DEBUG iceAgentLogNewCandidate(): New local ice candidate discovered. Id: PCHnLT3ow. Ip: 192.168.1.103:45908. Type: host. Protocol: UDP. 2021-01-15 03:38:35 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync 2021-01-15 03:38:35 DEBUG writeLwsData(): Sending data over web socket: { "action": "ICE_CANDIDATE", "RecipientClientId": "4CJ2IGNPXIO", "MessagePayload": "eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MCAxIHVkcCAyMTMwNzA2NDMxIDE5Mi4xNjguMS4xMDMgNDU5MDggdHlwIGhvc3QgcmFkZHIgMC4wLjAuMCBycG9ydCAwIGdlbmVyYXRpb24gMCBuZXR3b3JrLWNvc3QgOTk5Iiwic2RwTWlkIjoiMCIsInNkcE1MaW5lSW5kZXgiOjB9" } 2021-01-15 03:38:36 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:36 DEBUG lwsWssCallbackRoutine(): Client receive 2021-01-15 03:38:36 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:36 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:36 DEBUG handleStunPacket(): received candidate with USE_CANDIDATE flag, local candidate type host. 2021-01-15 03:38:36 DEBUG iceAgentLogNewCandidate(): New local ice candidate discovered. Id: jLPSN/zN8. Ip: 183.14.135.187:65203. Type: srflx. Protocol: UDP. 2021-01-15 03:38:36 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync 2021-01-15 03:38:36 DEBUG writeLwsData(): Sending data over web socket: { "action": "ICE_CANDIDATE", "RecipientClientId": "4CJ2IGNPXIO", "MessagePayload": "eyJjYW5kaWRhdGUiOiJjYW5kaWRhdGU6MSAxIHVkcCAxNjk0NDk4ODE1IDE4My4xNC4xMzUuMTg3IDY1MjAzIHR5cCBzcmZseCByYWRkciAwLjAuMC4wIHJwb3J0IDAgZ2VuZXJhdGlvbiAwIG5ldHdvcmstY29zdCA5OTkiLCJzZHBNaWQiOiIwIiwic2RwTUxpbmVJbmRleCI6MH0=" } 2021-01-15 03:38:36 VERBOSE lwsHttpCallbackRoutine(): HTTPS callback with reason 71 2021-01-15 03:38:36 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 71 2021-01-15 03:38:36 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:36 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:36 DEBUG handleStunPacket(): Ice candidate pair PCHnLT3ow_e6+yIyNkS is connected. Round trip time: 2ms 2021-01-15 03:38:36 DEBUG stepStateMachine(): State Machine - Current state: 0x0000000000000002, Next state: 0x0000000000000004 2021-01-15 03:38:36 DEBUG stepIceAgentStateMachine(): Ice agent state changed from ICE_AGENT_STATE_CHECK_CONNECTION to ICE_AGENT_STATE_CONNECTED. 2021-01-15 03:38:36 INFO onConnectionStateChange(): New connection state 1

---newState=1, time=1610681916--- 2021-01-15 03:38:36 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:36 VERBOSE turnConnectionStepState(): Updated turn allocation request credential after receiving 401 2021-01-15 03:38:36 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_GET_CREDENTIALS to TURN_STATE_ALLOCATION 2021-01-15 03:38:36 DEBUG socketConnectionTlsSessionOnStateChange(): TLS handshake done. Time taken 399 ms 2021-01-15 03:38:36 VERBOSE iceAgentSendKeepAliveTimerCallback(): send keep alive 2021-01-15 03:38:36 DEBUG stepStateMachine(): State Machine - Current state: 0x0000000000000004, Next state: 0x0000000000000008 2021-01-15 03:38:36 DEBUG stepIceAgentStateMachine(): Ice agent state changed from ICE_AGENT_STATE_CONNECTED to ICE_AGENT_STATE_NOMINATING. 2021-01-15 03:38:36 VERBOSE turnConnectionStepState(): Updated turn allocation request credential after receiving 401 2021-01-15 03:38:36 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_GET_CREDENTIALS to TURN_STATE_ALLOCATION 2021-01-15 03:38:36 DEBUG turnConnectionHandleStun(): TURN Allocation succeeded. Life time: 600 seconds. Allocation expiration epoch 161068251641887 2021-01-15 03:38:36 DEBUG stepStateMachine(): State Machine - Current state: 0x0000000000000008, Next state: 0x0000000000000010 2021-01-15 03:38:36 DEBUG iceAgentReadyStateSetup(): Selected pair PCHnLT3ow_e6+yIyNkS, local candidate type: host. Round trip time 0 ms 2021-01-15 03:38:36 DEBUG iceAgentReadyStateSetup(): Freeing Turn allocations that are not selected. Total turn allocation count 2 2021-01-15 03:38:36 DEBUG stepIceAgentStateMachine(): Ice agent state changed from ICE_AGENT_STATE_NOMINATING to ICE_AGENT_STATE_READY. 2021-01-15 03:38:36 DEBUG turnConnectionStepState(): Relay address received: 52.81.21.134, port: 59402 2021-01-15 03:38:36 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_ALLOCATION to TURN_STATE_CLEAN_UP 2021-01-15 03:38:36 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_ALLOCATION to TURN_STATE_CLEAN_UP 2021-01-15 03:38:36 DEBUG iceAgentGatherCandidateTimerCallback(): Candidate gathering completed. 2021-01-15 03:38:36 DEBUG onIceCandidateHandler(): ice candidate gathering finished 2021-01-15 03:38:36 DEBUG socketConnectionClosed(): Close socket 12 2021-01-15 03:38:36 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_CLEAN_UP to TURN_STATE_NEW 2021-01-15 03:38:36 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:36 DEBUG lwsWssCallbackRoutine(): Client receive 2021-01-15 03:38:36 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 8 2021-01-15 03:38:36 DEBUG lwsWssCallbackRoutine(): Client receive 2021-01-15 03:38:36 INFO onConnectionStateChange(): New connection state 3

---newState=3, time=1610681916--- 2021-01-15 03:38:36 DEBUG rtcPeerConnectionGetMetrics(): ICE local candidate Stats requested at 16106819165770378 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Local Candidate IP Address: 192.168.1.103 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Local Candidate type: host 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Local Candidate port: 45908 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Local Candidate priority: 2130706431 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Local Candidate transport protocol: transport=udp 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Local Candidate relay protocol: N/A 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Local Candidate Ice server source: N/A 2021-01-15 03:38:36 DEBUG rtcPeerConnectionGetMetrics(): ICE remote candidate Stats requested at 16106819165778225 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Remote Candidate IP Address: 192.168.1.100 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Remote Candidate type: host 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Remote Candidate port: 59947 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Remote Candidate priority: 0 2021-01-15 03:38:36 DEBUG logSelectedIceCandidatesInformation(): Remote Candidate transport protocol: transport=udp 2021-01-15 03:38:36 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:36 DEBUG turnConnectionHandleStun(): TURN Allocation freed. 2021-01-15 03:38:36 DEBUG socketConnectionClosed(): Close socket 11 2021-01-15 03:38:36 DEBUG turnConnectionStepState(): TurnConnection state changed from TURN_STATE_CLEAN_UP to TURN_STATE_NEW 2021-01-15 03:38:36 DEBUG dtlsSessionChangeState(): DTLS init completed. Time taken 300 ms 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 445815772 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 957955706 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 957955706 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 445815772 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 957955706 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 445815772 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 445815772 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report no frames sent 957955706 2021-01-15 03:38:38 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360819580277965 120590 : 124 packets 21080 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360819802948397 230700 : 327 packets 81156 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360820415351463 129922 : 133 packets 22610 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360820448978050 244237 : 343 packets 84973 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360821091466503 137478 : 141 packets 23970 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360821369813455 263533 : 375 packets 92785 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360822115521568 148923 : 153 packets 26010 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360822256392201 282111 : 401 packets 98646 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360822869152178 157346 : 161 packets 27370 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360823394979870 163222 : 167 packets 28390 bytes 2021-01-15 03:38:39 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360823435050627 306809 : 439 packets 107554 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360824218509785 323227 : 460 packets 112203 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360824307417755 173420 : 178 packets 30260 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360824738793104 178241 : 183 packets 31110 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360825427263906 348556 : 498 packets 120722 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360825878763323 190981 : 196 packets 33320 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360826154979112 363805 : 531 packets 133062 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360827005919938 203578 : 209 packets 35530 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360827011484927 381753 : 558 packets 138970 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360827769136485 397629 : 579 packets 144185 bytes 2021-01-15 03:38:40 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360827810897741 212574 : 218 packets 37060 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360828419538415 411258 : 599 packets 149033 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360828564270223 220994 : 226 packets 38420 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360829133019241 227350 : 233 packets 39610 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360829666908587 437396 : 634 packets 157400 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360830141145991 447334 : 649 packets 161138 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360830337452625 240810 : 247 packets 41990 bytes 2021-01-15 03:38:41 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 232920.000000 2021-01-15 03:38:41 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360831049211599 466362 : 674 packets 166765 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360831090854743 249230 : 255 packets 43350 bytes 2021-01-15 03:38:41 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 236533.000000 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360831822740792 257410 : 264 packets 44880 bytes 2021-01-15 03:38:41 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360831892813184 484040 : 699 packets 172890 bytes 2021-01-15 03:38:41 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 240220.000000 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360832533174339 265350 : 272 packets 46240 bytes 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360832800846580 503067 : 724 packets 179130 bytes 2021-01-15 03:38:42 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 243965.000000 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360833524241049 518226 : 744 packets 183989 bytes 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360833668861618 278042 : 285 packets 48450 bytes 2021-01-15 03:38:42 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 247749.000000 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360834435139188 286606 : 293 packets 49810 bytes 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360834681403164 542474 : 780 packets 193283 bytes 2021-01-15 03:38:42 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 251611.000000 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360834892203595 291714 : 299 packets 50830 bytes 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360835228755944 553944 : 795 packets 196631 bytes 2021-01-15 03:38:42 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 255514.000000 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360835774450866 301574 : 309 packets 52530 bytes 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360836420486103 308794 : 316 packets 53720 bytes 2021-01-15 03:38:42 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360836437420729 579271 : 830 packets 205063 bytes 2021-01-15 03:38:42 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 259477.000000 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360836932919510 589654 : 845 packets 208600 bytes 2021-01-15 03:38:43 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 263502.000000 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360837581952204 321774 : 329 packets 55930 bytes 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360838150215462 615162 : 880 packets 217426 bytes 2021-01-15 03:38:43 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 267588.000000 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360838537309697 332451 : 340 packets 57800 bytes 2021-01-15 03:38:43 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 271740.000000 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360839148450472 636080 : 911 packets 224991 bytes 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360839191946465 339767 : 348 packets 59160 bytes 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360839760628482 346123 : 354 packets 60180 bytes 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360839940437288 652676 : 936 packets 231102 bytes 2021-01-15 03:38:43 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 275976.000000 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360840788951027 357615 : 366 packets 62220 bytes 2021-01-15 03:38:43 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360840852802158 671794 : 961 packets 237529 bytes 2021-01-15 03:38:43 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 280278.000000 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360841348492494 682181 : 976 packets 241814 bytes 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360841370618448 364116 : 373 packets 63410 bytes 2021-01-15 03:38:44 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 284646.000000 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360842093865170 372199 : 381 packets 64770 bytes 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360842217974263 700401 : 1001 packets 248584 bytes 2021-01-15 03:38:44 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 289062.000000 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360842928429715 715288 : 1022 packets 254246 bytes 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360843156587826 384075 : 393 packets 66810 bytes 2021-01-15 03:38:44 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 293568.000000 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360843686662382 389999 : 399 packets 67830 bytes 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360844128556535 740437 : 1061 packets 265788 bytes 2021-01-15 03:38:44 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 298122.000000 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360844564353981 749569 : 1077 packets 269316 bytes 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360844809441566 402547 : 412 packets 70040 bytes 2021-01-15 03:38:44 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360845167441263 762206 : 1093 packets 273359 bytes 2021-01-15 03:38:44 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 302746.000000 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360845554284673 410872 : 420 packets 71400 bytes 2021-01-15 03:38:45 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 307442.000000 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360846243388553 418573 : 428 packets 72760 bytes 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360846311749400 786185 : 1134 packets 284843 bytes 2021-01-15 03:38:45 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 312210.000000 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360847507900798 432705 : 443 packets 75310 bytes 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360847584906555 812864 : 1175 packets 295827 bytes 2021-01-15 03:38:45 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 317078.000000 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360848020858621 438438 : 449 packets 76330 bytes 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360848106386452 823791 : 1191 packets 299606 bytes 2021-01-15 03:38:45 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 321996.000000 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360848684066855 445850 : 456 packets 77520 bytes 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360849190512648 846509 : 1231 packets 309598 bytes 2021-01-15 03:38:45 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360849420332912 454078 : 465 packets 79050 bytes 2021-01-15 03:38:45 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 327040.000000 2021-01-15 03:38:46 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 332138.000000 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360850377767022 871387 : 1269 packets 318299 bytes 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360850568956632 466915 : 478 packets 81260 bytes 2021-01-15 03:38:46 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:46 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:46 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 9 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360851045240588 885374 : 1285 packets 322167 bytes 2021-01-15 03:38:46 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 337290.000000 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360851421117657 476439 : 487 packets 82790 bytes 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360851699920305 899093 : 1306 packets 327489 bytes 2021-01-15 03:38:46 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 342548.000000 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360852105900800 484092 : 495 packets 84150 bytes 2021-01-15 03:38:46 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360852691751519 490639 : 502 packets 85340 bytes 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360852706780898 920191 : 1336 packets 334701 bytes 2021-01-15 03:38:46 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 347862.000000 2021-01-15 03:38:46 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360853335475634 933366 : 1356 packets 339425 bytes 2021-01-15 03:38:46 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 353284.000000 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360853977768666 505012 : 517 packets 87890 bytes 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360854071780346 948795 : 1376 packets 344270 bytes 2021-01-15 03:38:47 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 358792.000000 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360855122115887 517801 : 530 packets 90100 bytes 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360855143079622 971243 : 1406 packets 351548 bytes 2021-01-15 03:38:47 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360855853399353 986128 : 1427 packets 356966 bytes 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360856150629128 529295 : 542 packets 92140 bytes 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360856345002598 996429 : 1442 packets 360147 bytes 2021-01-15 03:38:47 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360856886863832 537523 : 550 packets 93500 bytes 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360856948131970 1009068 : 1462 packets 365080 bytes 2021-01-15 03:38:47 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360857482530699 1020266 : 1477 packets 368684 bytes 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360857863683706 548440 : 561 packets 95370 bytes 2021-01-15 03:38:47 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360858055566524 1032274 : 1492 packets 372493 bytes 2021-01-15 03:38:47 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360858947953354 560558 : 573 packets 97410 bytes 2021-01-15 03:38:48 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360859302971915 1058413 : 1527 packets 380572 bytes 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360859778766244 569843 : 583 packets 99110 bytes 2021-01-15 03:38:48 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360860361423655 1080593 : 1563 packets 389301 bytes 2021-01-15 03:38:48 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360860807143765 581336 : 595 packets 101150 bytes 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360861273743858 1099710 : 1588 packets 395043 bytes 2021-01-15 03:38:48 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360861960055580 594221 : 608 packets 103360 bytes 2021-01-15 03:38:48 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360862327802706 1121798 : 1618 packets 402556 bytes 2021-01-15 03:38:48 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360862666233244 602113 : 616 packets 104720 bytes 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360862926685644 1134347 : 1638 packets 407547 bytes 2021-01-15 03:38:49 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 364386.000000 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360863617271288 612741 : 627 packets 106590 bytes 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360864148260242 1159945 : 1674 packets 416819 bytes 2021-01-15 03:38:49 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 366028.000000 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360864246136964 619769 : 634 packets 107780 bytes 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Current duration: 14 seconds 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Selected local candidate ID: PCHnLT3ow 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Selected remote candidate ID: e6+yIyNkS 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Ice Candidate Pair state: 3 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Nomination state: nominated 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Packet send rate: 172.857143 pkts/sec 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Packet receive rate: 5.000000 pkts/sec 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Outgoing bit rate: 318953.142857 bps 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Incoming bit rate: 2817.142857 bps 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Packet discard rate: 0.000000 pkts/sec 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Current STUN request round trip time: 0.001117 sec 2021-01-15 03:38:49 DEBUG getIceCandidatePairStatsCallback(): Number of STUN responses received: 2 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360864661185423 1170693 : 1689 packets 420180 bytes 2021-01-15 03:38:49 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 368548.000000 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360865484958862 633614 : 648 packets 110160 bytes 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360865547686859 1189269 : 1714 packets 426141 bytes 2021-01-15 03:38:49 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 368548.000000 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360866082058101 1200467 : 1729 packets 429436 bytes 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360866573475803 645780 : 661 packets 112370 bytes 2021-01-15 03:38:49 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 368548.000000 2021-01-15 03:38:49 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360866758178295 1214635 : 1749 packets 434474 bytes 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360867597418340 1232221 : 1774 packets 440544 bytes 2021-01-15 03:38:50 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 368548.000000 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360867859450000 660151 : 675 packets 114750 bytes 2021-01-15 03:38:50 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360868720242192 669771 : 685 packets 116450 bytes 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360868780400510 1257010 : 1814 packets 450866 bytes 2021-01-15 03:38:50 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360869383617070 1269650 : 1829 packets 453630 bytes 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360869954774277 683568 : 699 packets 118830 bytes 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360870098382092 1284628 : 1849 packets 456070 bytes 2021-01-15 03:38:50 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360870429101875 688869 : 705 packets 119850 bytes 2021-01-15 03:38:50 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360870671453136 1296637 : 1869 packets 458543 bytes 2021-01-15 03:38:50 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360871249052363 1308740 : 1884 packets 460505 bytes 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360871693605101 703001 : 719 packets 122230 bytes 2021-01-15 03:38:51 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360871963833276 1323718 : 1904 packets 463515 bytes 2021-01-15 03:38:51 VERBOSE iceAgentSendKeepAliveTimerCallback(): send keep alive 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360872751941736 714829 : 731 packets 124270 bytes 2021-01-15 03:38:51 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360873155334514 1348686 : 1939 packets 468825 bytes 2021-01-15 03:38:51 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:51 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360873866167063 727282 : 744 packets 126480 bytes 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360873986073530 1366094 : 1964 packets 472364 bytes 2021-01-15 03:38:51 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360874520464099 1377292 : 1979 packets 474810 bytes 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360874589504839 735366 : 752 packets 127840 bytes 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360875076648620 740810 : 758 packets 128860 bytes 2021-01-15 03:38:51 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360875213655929 1391818 : 1999 packets 478334 bytes 2021-01-15 03:38:51 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360875679764248 747550 : 765 packets 130050 bytes 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360875868339941 1405536 : 2019 packets 481733 bytes 2021-01-15 03:38:52 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360876686572873 758802 : 776 packets 131920 bytes 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360877055731754 1430418 : 2054 packets 488741 bytes 2021-01-15 03:38:52 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360877336995418 766071 : 784 packets 133280 bytes 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360877938010808 1448906 : 2079 packets 493421 bytes 2021-01-15 03:38:52 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360878472695582 778764 : 797 packets 135490 bytes 2021-01-15 03:38:52 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360879043623007 1472074 : 2109 packets 498835 bytes 2021-01-15 03:38:52 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360879153086691 786368 : 804 packets 136680 bytes 2021-01-15 03:38:52 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360880282418277 1498032 : 2149 packets 507480 bytes 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360880297363045 799156 : 817 packets 138890 bytes 2021-01-15 03:38:53 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360880752435017 1507881 : 2159 packets 509606 bytes 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360880883141609 805702 : 824 packets 140080 bytes 2021-01-15 03:38:53 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360881786873595 815802 : 834 packets 141780 bytes 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360881789318290 1529609 : 2190 packets 516617 bytes 2021-01-15 03:38:53 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360882316977786 821727 : 840 packets 142800 bytes 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360882791638244 1550612 : 2220 packets 523144 bytes 2021-01-15 03:38:53 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360883504206819 834995 : 854 packets 145180 bytes 2021-01-15 03:38:53 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:53 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360884017532720 1576301 : 2257 packets 531451 bytes 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360884648504218 847784 : 867 packets 147390 bytes 2021-01-15 03:38:54 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360884972849411 1596319 : 2288 packets 538409 bytes 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360885161404918 853516 : 873 packets 148410 bytes 2021-01-15 03:38:54 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360885889027783 861648 : 881 packets 149770 bytes 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360886194608264 1621921 : 2326 packets 548001 bytes 2021-01-15 03:38:54 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360886955990001 873572 : 893 packets 151810 bytes 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360887244480807 1643921 : 2358 packets 555330 bytes 2021-01-15 03:38:54 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 374292.000000 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360887744468687 1654398 : 2375 packets 559492 bytes 2021-01-15 03:38:54 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360888083083909 886168 : 906 packets 154020 bytes 2021-01-15 03:38:54 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 380044.000000 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360888897401118 1678557 : 2414 packets 569361 bytes 2021-01-15 03:38:55 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 385968.000000 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360889266066079 899389 : 920 packets 156400 bytes 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360889453238606 1690205 : 2431 packets 573226 bytes 2021-01-15 03:38:55 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 391956.000000 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360890384548577 911889 : 933 packets 158610 bytes 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360890653331066 1715352 : 2468 packets 582185 bytes 2021-01-15 03:38:55 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 391956.000000 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360891309944807 922231 : 943 packets 160310 bytes 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360891673068830 1736721 : 2498 packets 589794 bytes 2021-01-15 03:38:55 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 391956.000000 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360891870133956 928492 : 950 packets 161500 bytes 2021-01-15 03:38:55 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360892190453905 1747562 : 2513 packets 593801 bytes 2021-01-15 03:38:55 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 391956.000000 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360892735229275 938160 : 959 packets 163030 bytes 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360893218833144 1769112 : 2544 packets 601796 bytes 2021-01-15 03:38:56 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 391956.000000 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360893501618085 946725 : 968 packets 164560 bytes 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360893830548156 1781930 : 2559 packets 605456 bytes 2021-01-15 03:38:56 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:38:56 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:38:56 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 9 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360894177694041 954281 : 976 packets 165920 bytes 2021-01-15 03:38:56 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 391956.000000 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360894433675381 1794568 : 2580 packets 611009 bytes 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360894943988363 962845 : 985 packets 167450 bytes 2021-01-15 03:38:56 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:38:56 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 391956.000000 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360895509249008 1817107 : 2612 packets 619952 bytes 2021-01-15 03:38:56 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 398066.000000 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360896019578311 974865 : 997 packets 169490 bytes 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360896168282964 1830917 : 2632 packets 624953 bytes 2021-01-15 03:38:56 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360896811615378 983717 : 1006 packets 171020 bytes 2021-01-15 03:38:56 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360896934649870 1846976 : 2657 packets 631465 bytes 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360897374524529 1856193 : 2667 packets 633924 bytes 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360897406175002 990362 : 1013 packets 172210 bytes 2021-01-15 03:38:57 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360897810133856 1865321 : 2682 packets 637481 bytes 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360897876025956 995613 : 1018 packets 173060 bytes 2021-01-15 03:38:57 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360898607991892 1003793 : 1027 packets 174590 bytes 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360899044605382 1891189 : 2718 packets 646394 bytes 2021-01-15 03:38:57 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360899717862551 1016197 : 1039 packets 176630 bytes 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360899832402027 1907697 : 2738 packets 650845 bytes 2021-01-15 03:38:57 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360900804897299 1928076 : 2768 packets 658556 bytes 2021-01-15 03:38:57 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360900939456477 1029849 : 1053 packets 179010 bytes 2021-01-15 03:38:57 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360901249103432 1937384 : 2783 packets 662205 bytes 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360901847543989 1039998 : 1064 packets 180880 bytes 2021-01-15 03:38:58 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360902350376856 1960461 : 2813 packets 669774 bytes 2021-01-15 03:38:58 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360902962183351 1973281 : 2834 packets 675735 bytes 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360902978925992 1052642 : 1076 packets 182920 bytes 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360903698519845 1988711 : 2854 packets 680755 bytes 2021-01-15 03:38:58 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360903839635291 1062261 : 1086 packets 184620 bytes 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360904237195932 1999999 : 2869 packets 684146 bytes 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360904459925634 1069194 : 1093 packets 185810 bytes 2021-01-15 03:38:58 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:58 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360905329956730 2022897 : 2899 packets 691362 bytes 2021-01-15 03:38:59 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360905595651997 1081886 : 1106 packets 188020 bytes 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360906285269126 2042916 : 2929 packets 698897 bytes 2021-01-15 03:38:59 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360906811076202 2053934 : 2944 packets 702630 bytes 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360906868841365 1096115 : 1121 packets 190570 bytes 2021-01-15 03:38:59 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360907617938637 1104487 : 1129 packets 191930 bytes 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360907938279632 2077554 : 2981 packets 711431 bytes 2021-01-15 03:38:59 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360908581848698 1115260 : 1140 packets 193800 bytes 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360908644437968 2092351 : 3002 packets 715967 bytes 2021-01-15 03:38:59 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360909110112496 2102110 : 3013 packets 718538 bytes 2021-01-15 03:38:59 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360909558639796 1126176 : 1152 packets 195840 bytes 2021-01-15 03:39:00 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360910155537171 2124016 : 3045 packets 725999 bytes 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360910492489381 1136613 : 1162 packets 197540 bytes 2021-01-15 03:39:00 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 404272.000000 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360911286995195 2147726 : 3083 packets 734730 bytes 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360911336114588 1146041 : 1172 packets 199240 bytes 2021-01-15 03:39:00 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 410574.000000 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360912205481253 1155757 : 1182 packets 200940 bytes 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360912259541147 2168105 : 3121 packets 748151 bytes 2021-01-15 03:39:00 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 416974.000000 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360912845517279 2180384 : 3137 packets 751684 bytes 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360913143674768 1166242 : 1193 packets 202810 bytes 2021-01-15 03:39:00 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 423442.000000 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360913806940125 1173655 : 1200 packets 204000 bytes 2021-01-15 03:39:00 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360914071424640 2206073 : 3175 packets 760490 bytes 2021-01-15 03:39:01 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 430044.000000 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360914732217814 1183995 : 1211 packets 205870 bytes 2021-01-15 03:39:01 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 430684.000000 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360915310235801 2232032 : 3211 packets 768613 bytes 2021-01-15 03:39:01 VERBOSE sampleBandwidthEstimationHandler(): received bitrate suggestion: 430684.000000 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360915840377788 2243141 : 3231 packets 774074 bytes 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360915872165700 1196735 : 1224 packets 208080 bytes 2021-01-15 03:39:01 DEBUG dtlsSessionProcessPacket(): Detected DTLS close_notify alert 2021-01-15 03:39:01 INFO onConnectionStateChange(): New connection state 6

---newState=6, time=1610681941--- 2021-01-15 03:39:01 DEBUG freeSampleStreamingSession(): Freeing streaming session with peer id: 4CJ2IGNPXIO

---freeSampleStreamingSession 01 time=1610681941--- 2021-01-15 03:39:01 DEBUG socketConnectionClosed(): Close socket 10 2021-01-15 03:39:01 DEBUG socketConnectionClosed(): Close socket 9 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360916333423713 1201890 : 1224 packets 208080 bytes 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360916714051016 2261448 : 3231 packets 774074 bytes 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360916961997760 1208915 : 1224 packets 208080 bytes 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360917162213674 2270839 : 3231 packets 774074 bytes 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360917530382995 1215267 : 1224 packets 208080 bytes 2021-01-15 03:39:01 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360917979673940 2287969 : 3231 packets 774074 bytes 2021-01-15 03:39:02 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360918433786416 1225364 : 1224 packets 208080 bytes 2021-01-15 03:39:02 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360918920618811 1230804 : 1224 packets 208080 bytes 2021-01-15 03:39:02 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360919050577650 2310410 : 3231 packets 774074 bytes 2021-01-15 03:39:02 VERBOSE rtcpReportsCallback(): sender report 957955706 16405360919806846658 1240709 : 1224 packets 208080 bytes 2021-01-15 03:39:02 VERBOSE rtcpReportsCallback(): sender report 445815772 16405360920009749503 2330509 : 3231 packets 774074 bytes

---freeSampleStreamingSession 02 time=1610681942---

---freeSampleStreamingSession 03 time=1610681945--- 2021-01-15 03:39:05 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:39:06 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:39:06 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:39:06 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 9 2021-01-15 03:39:10 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:39:15 VERBOSE signalingClientGetCurrentState(): Signaling Client Get Current State 2021-01-15 03:39:16 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 10 2021-01-15 03:39:16 DEBUG lwsWssCallbackRoutine(): Client is writable 2021-01-15 03:39:16 VERBOSE lwsWssCallbackRoutine(): WSS callback with reason 9


Describe the bug SDK : amazon-kinesis-video-streams-webrtc-sdk-c-1.4.1 In the sample kvswertcclientmaster, if the function interface (freeamplestreamingsession) is in the common. C file, freepeerconnection will block for 3 seconds Operation steps: in Google Chrome browser, first start viewer, then stop viewer. When it is executed to freepeerconnection, it will block for 3 seconds

SDK version number amazon-kinesis-video-streams-webrtc-sdk-c-1.4.1

Screenshots test20210115181516

Desktop (please complete the following information):

jerryjwb commented 3 years ago

SDK : amazon-kinesis-video-streams-webrtc-sdk-c-1.4.1 In the sample kvswertcclientmaster, if the function interface (freeSampleStreamingSession) is in the common. C file, freePeerConnection will block for 3 seconds Operation steps: in Google Chrome browser, first start viewer, then stop viewer. When it is executed to freePeerConnection , it will block for 3 seconds

disa6302 commented 3 years ago

@jerryjwb ,

I am unable to reproduce this. The applications exits pretty much immediately. Have you made any modifications to the sample itself?

jerryjwb commented 3 years ago

@jerryjwb ,

I am unable to reproduce this. The applications exits pretty much immediately. Have you made any modifications to the sample itself?

Sample has not been modified, but added three printfs to print information. The device runs on MSTAR arm platform.

MushMal commented 3 years ago

@jerryjwb we will not be able to reproduce this on your platform. Please try to run this on a commodity os and hardware first and make sure there is no delay. Then, try to port to your platform. Make sure you use stock sdk and samples without any modification. Please report back with the details of your repro on commodity os.

codingspirit commented 3 years ago

In current code(cd002e5d1b09dc4175aa3ca5339fd16cec4b85fa), freeSampleStreamingSession might cost more than 3 secs due to https://github.com/awslabs/amazon-kinesis-video-streams-webrtc-sdk-c/blob/cd002e5d1b09dc4175aa3ca5339fd16cec4b85fa/src/source/Ice/IceAgent.h#L20 and https://github.com/awslabs/amazon-kinesis-video-streams-webrtc-sdk-c/blob/cd002e5d1b09dc4175aa3ca5339fd16cec4b85fa/src/source/Sctp/Sctp.h#L26

@MushMal Does it make sense?

MushMal commented 3 years ago

@codingspirit what I meant is that it's not clear what's holding the shutdown. Under normal condition, the termination sequence should not time out and should close properly. I am not sure whether it's the socket or DTLS that's holding the shutdown sequence. Hence, someone will need to debug this on their platform to understand what's causing it.

Please provide further detail or resolve - unless it's possible to reproduce this on stock sample software on a commodity platform we will not be able to debug.

MushMal commented 3 years ago

Any further info on this? There doesn't seem to be any actionable items unless we get more details or at least verbose logs

jerryjwb commented 3 years ago

I can only get the above log information.

MushMal commented 3 years ago

Are you able to reproduce this on a commodity platform?

jerryjwb commented 3 years ago

Are you able to reproduce this on a commodity platform?

On MSTAR arm platform, stop viewer appears every time.

MushMal commented 3 years ago

@jerryjwb if it's platform specific there is very little I can go on with. You might need to debug into the socket APIs on the given platform.

jerryjwb commented 3 years ago

@jerryjwb if it's platform specific there is very little I can go on with. You might need to debug into the socket APIs on the given platform.

Socket APIs is in which SDK C source file, please explain, so that I can quickly debug.

MushMal commented 3 years ago

You should start with freePeerConnection and add some instrumentation to see what's causing the delay. The socket APIs are in Network.c so you can instrument that too.

MushMal commented 3 years ago

Resolving as there is no actionable item on KVS side. Please cut a new issue with specifics.