amazon-kinesis-video-streams-webrtc-sdk-c [BUG] Calling freePeerConnection doesn't return

trafficstars

When peer connection state is either CLOSED or DISCONNECTED, we call closePeerConnection(pPeerConnection_); freePeerConnection(&pPeerConnection_); Normally they return quickly. But in certain scenario, calling freePeerConnection() never returns. Before the failure, there are multiple peerConnection streamings running in parallel. When one peer connection is dead, our app gets RTC_PEER_CONNECTION_STATE_DISCONNECTED event. But after our app calls freePeerConnection(), it prints out WARNING message 2021-05-11 23:27:05 WARN freeConnectionListener(): Connection listener handler thread shutdown timed out 2021-05-11 23:27:06 WARN freeSocketConnection(): Shutting down socket connection timedout after 1 seconds And stuck there. Since then, our app loses the ability to free up peerconnection. When new peerconnection requests come in, it will be rejected

SDK version number 1.5.0

May 14 '21 05:05 zwei333

@zwei333 ,

Is the viewer disconnecting and do you see dtls_close_notify_alert in the logs? The sample should be taking care of it.

Can you attach complete logs?

May 19 '21 18:05 disa6302

Yes, it was viewer disconnecting. I haven't seen dtls_close_notify_alert in the log.

May 19 '21 19:05 zwei333

In the first a few lines, system was still OK. At 2021-05-11 23:26:24.558, old connection was destroyed, and new connections were created later. Since then system began have problem

[2021-05-11 23:26:24.310] [1620775584310] [1937765456][info] [2021-05-11 23:26:24.310] [1620775584310] [1937765456][info] [2021-05-11 23:26:24.310] [1620775584310] [1937765456][info] [2021-05-11 23:26:24.311] [1620775584311] [1937765456][info] [2021-05-11 23:26:24.311] [1620775584311] [1937765456][info] [2021-05-11 23:26:24.312] [1620775584312] [1937765456][info] [2021-05-11 23:26:24.312] [1620775584312] [1937765456][info] [2021-05-11 23:26:24.313] [1620775584313] [1937765456][info] [2021-05-11 23:26:24.315] [1620775584315] [1937765456][info] [2021-05-11 23:26:24.315] [1620775584315] [1937765456][info] [2021-05-11 23:26:24.316] [1620775584316] [1937765456][info] [2021-05-11 23:26:24.316] [1620775584316] [1937765456][info] [2021-05-11 23:26:24.316] [1620775584316] [1937765456][info] [2021-05-11 23:26:24.316] [1620775584316] [1937765456][info] [2021-05-11 23:26:24.317] [1620775584317] [1937765456][info] [2021-05-11 23:26:24.317] [1620775584317] [1937765456][info] [2021-05-11 23:26:24.317] [1620775584317] [1937765456][info] [2021-05-11 23:26:24.317] [1620775584317] [1937765456][info] [2021-05-11 23:26:24.317] [1620775584317] [1937765456][info] [2021-05-11 23:26:24.558] [1620775584558] [1937765456][info] [2021-05-11 23:26:33.300] [1620775593300] [1835005008][info] [2021-05-11 23:26:33.300] [1620775593300] [1851782224][info] 2021-05-11 23:26:33 INFO signalingClientGetIceConfigInfo(): [2021-05-11 23:26:33.301] [1620775593301] [1851782224][info] [2021-05-11 23:26:33.387] [1620775593387] [1835005008][info] [2021-05-11 23:26:33.387] [1620775593387] [1801450576][info] [2021-05-11 23:26:33.484] [1620775593484] [1851782224][info] [2021-05-11 23:26:33.486] [1620775593486] [1801450576][info] [2021-05-11 23:26:33.490] [1620775593490] [1835005008][info] [2021-05-11 23:26:33.493] [1620775593493] [1885336656][info] [2021-05-11 23:26:33.497] [1620775593497] [1868559440][info] 2021-05-11 23:26:33 INFO signalingClientSendMessageSync(): [2021-05-11 23:26:33.499] [1620775593499] [1885336656][info] [2021-05-11 23:26:33.499] [1620775593499] [1868559440][info] 2021-05-11 23:26:33 INFO signalingClientSendMessageSync(): [2021-05-11 23:26:33.554] [1620775593554] [1893725264][info] [2021-05-11 23:26:33.559] [1620775593559] [1885336656][info] [2021-05-11 23:26:33.560] [1620775593560] [1868559440][info] 2021-05-11 23:26:33 INFO signalingClientSendMessageSync(): [2021-05-11 23:26:33.597] [1620775593597] [1885336656][info] [2021-05-11 23:26:33.619] [1620775593619] [1843393616][info] [2021-05-11 23:26:33.621] [1620775593621] [1893725264][info] [2021-05-11 23:26:33.645] [1620775593645] [1893725264][info] [2021-05-11 23:26:33.700] [1620775593700] [1885336656][info] [2021-05-11 23:26:33.739] [1620775593739] [1801450576][info] [2021-05-11 23:26:33.740] [1620775593740] [1801450576][info] [2021-05-11 23:26:33.740] [1620775593740] [1801450576][info] [2021-05-11 23:26:33.740] [1620775593740] [1801450576][info] [2021-05-11 23:26:33.741] [1620775593741] [1801450576][info] [2021-05-11 23:26:33.741] [1620775593741] [1801450576][info] [2021-05-11 23:26:33.741] [1620775593741] [1801450576][info] [2021-05-11 23:26:33.741] [1620775593741] [1801450576][info] [2021-05-11 23:26:33.741] [1620775593741] [1801450576][info] [2021-05-11 23:26:33.742] [1620775593742] [1801450576][info] [2021-05-11 23:26:33.742] [1620775593742] [1801450576][info] [2021-05-11 23:26:33.742] [1620775593742] [1801450576][info] [2021-05-11 23:26:33.742] [1620775593742] [1801450576][info] [2021-05-11 23:26:33.742] [1620775593742] [1801450576][info] [2021-05-11 23:26:33.743] [1620775593743] [1801450576][info] 2021-05-11 23:26:33 INFO onSctpInboundPacket(): 2021-05-11 23:26:33 ERROR onSctpSessionDataChannelOpen(): [2021-05-11 23:26:36.891] [1620775596891] [1851782224][info] [2021-05-11 23:26:36.892] [1620775596892] [1851782224][info] [2021-05-11 23:26:36.892] [1620775596892] [1851782224][info] [2021-05-11 23:26:36.892] [1620775596892] [1851782224][info] [2021-05-11 23:26:36.892] [1620775596892] [1851782224][info] [2021-05-11 23:26:36.893] [1620775596893] [1851782224][info] [2021-05-11 23:26:36.893] [1620775596893] [1851782224][info] [2021-05-11 23:26:36.893] [1620775596893] [1851782224][info] [2021-05-11 23:26:36.893] [1620775596893] [1851782224][info] [2021-05-11 23:26:36.893] [1620775596893] [1851782224][info] [2021-05-11 23:26:40.894] [1620775600894] [1851782224][info] [2021-05-11 23:26:40.895] [1620775600895] [1851782224][info] [2021-05-11 23:26:40.895] [1620775600895] [1851782224][info] [2021-05-11 23:26:40.895] [1620775600895] [1851782224][info] [2021-05-11 23:26:40.895] [1620775600895] [1851782224][info] [2021-05-11 23:26:40.896] [1620775600896] [1851782224][info] [2021-05-11 23:26:40.896] [1620775600896] [1851782224][info] [2021-05-11 23:26:40.896] [1620775600896] [1851782224][info] [2021-05-11 23:26:40.896] [1620775600896] [1851782224][info] [2021-05-11 23:26:40.897] [1620775600897] [1851782224][info] [2021-05-11 23:26:44.888] [1620775604888] [1851782224][info] [2021-05-11 23:26:44.889] [1620775604889] [1851782224][info] [2021-05-11 23:26:44.889] [1620775604889] [1851782224][info] [2021-05-11 23:26:44.889] [1620775604889] [1851782224][info] [2021-05-11 23:26:44.890] [1620775604890] [1851782224][info] [2021-05-11 23:26:44.890] [1620775604890] [1851782224][info] [2021-05-11 23:26:44.890] [1620775604890] [1851782224][info] [2021-05-11 23:26:44.890] [1620775604890] [1851782224][info] [2021-05-11 23:26:44.890] [1620775604890] [1851782224][info] [2021-05-11 23:26:44.891] [1620775604891] [1851782224][info] KvsAPI.cpp:1106:LogMetricsIceServerStats() ------------- Selected Ice Server Report ------------- KvsAPI.cpp:1107:LogMetricsIceServerStats() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_K26yBOtUrC KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: stun:stun.kinesisvideo.us-east-1.amazonaws.com:443 KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: KvsAPI.cpp:1114:LogMetricsIceServerStats() ICE Server port: 443 KvsAPI.cpp:1115:LogMetricsIceServerStats() Total requests sent: 1 KvsAPI.cpp:1116:LogMetricsIceServerStats() Total responses received: 1 KvsAPI.cpp:1117:LogMetricsIceServerStats() Total round trip time: 22ms KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: turn:54-80-180-89.t-e58da546.kinesisvideo.us-east-1.amazonaws.com:443?transport=udp KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: transport=udp KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: turns:54-80-180-89.t-e58da546.kinesisvideo.us-east-1.amazonaws.com:443?transport=udp KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: transport=udp KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: turns:54-80-180-89.t-e58da546.kinesisvideo.us-east-1.amazonaws.com:443?transport=tcp KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: transport=tcp KvsAPI.cpp:1095:LogMetricsLatency() ------------- Latency Report ------------- KvsAPI.cpp:1096:LogMetricsLatency() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_K26yBOtUrC KvsAPI.cpp:1097:LogMetricsLatency() latency from app start to receiving offer: 371102ms KvsAPI.cpp:1098:LogMetricsLatency() latency from receiving offer to sending frame: 65ms KvsAPI.cpp:1099:LogMetricsLatency() latency from sending frame to srtp ready: 0ms KvsAPI.cpp:461:~kvsWebRTCPeerConnection() Peer Connection af1f8ce3c1cf48e39d9607c2f1000335_2698678_K26yBOtUrC is destructed KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 0 KvsAPI.cpp:474:HandleMessage() Received Offer from peer client af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i Signaling Client Get ICE Config Info KvsAPI.cpp:585:InitializePeerConnection() Start PeerConnection creation KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:587:InitializePeerConnection() Finish PeerConnection creation KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTING KvsAPI.cpp:273:SendMessage() Sending Message Type 1 Signaling Client Sending Message Sync KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:0 1 udp 2130706431 10.17.230.107 45318 typ host raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} KvsAPI.cpp:273:SendMessage() Sending Message Type 2 Signaling Client Sending Message Sync KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:1 1 udp 1694498815 24.91.168.107 48610 typ srflx raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} KvsAPI.cpp:273:SendMessage() Sending Message Type 2 Signaling Client Sending Message Sync KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state state_1 KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i , type 2 KvsAPI.cpp:498:OnIceCandidateHandler() ice candidate gathering finished KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTED KvsAPI.cpp:1072:LogMetricsSelectedIcePair() ------------- Selected Ice Pair Report ------------- KvsAPI.cpp:1073:LogMetricsSelectedIcePair() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i KvsAPI.cpp:1074:LogMetricsSelectedIcePair() Local Candidate IP Address: 10.17.230.107 KvsAPI.cpp:1075:LogMetricsSelectedIcePair() Local Candidate type: host KvsAPI.cpp:1076:LogMetricsSelectedIcePair() Local Candidate port: 45318 KvsAPI.cpp:1077:LogMetricsSelectedIcePair() Local Candidate priority: 2130706431 KvsAPI.cpp:1078:LogMetricsSelectedIcePair() Local Candidate transport protocol: transport=udp KvsAPI.cpp:1079:LogMetricsSelectedIcePair() Local Candidate relay protocol: N/A KvsAPI.cpp:1080:LogMetricsSelectedIcePair() Local Candidate Ice server source: N/A KvsAPI.cpp:1083:LogMetricsSelectedIcePair() Remote Candidate IP Address: 10.17.230.128 KvsAPI.cpp:1084:LogMetricsSelectedIcePair() Remote Candidate type: host KvsAPI.cpp:1085:LogMetricsSelectedIcePair() Remote Candidate port: 57560 KvsAPI.cpp:1086:LogMetricsSelectedIcePair() Remote Candidate priority: 2122260223 KvsAPI.cpp:1087:LogMetricsSelectedIcePair() Remote Candidate transport protocol: transport=udp Unhandled PPID on incoming SCTP message 0 operation returned status code: 0x00000001 KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 0 KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 3099291460 KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 385509674 KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 1624316303 KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 3277553196 KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 312891126 KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 1248897576 KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 721976 KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 721976 KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 294912 KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 294912 KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0

[2021-05-11 23:26:47.563] [1620775607563] [1893725264][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 0 [2021-05-11 23:26:47.564] [1620775607564] [1835005008][info] KvsAPI.cpp:474:HandleMessage() Received Offer from peer client af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE 2021-05-11 23:26:47 INFO signalingClientGetIceConfigInfo(): Signaling Client Get ICE Config Info [2021-05-11 23:26:47.567] [1620775607567] [1835005008][info] KvsAPI.cpp:585:InitializePeerConnection() Start PeerConnection creation [2021-05-11 23:26:47.605] [1620775607605] [1835005008][info] KvsAPI.cpp:587:InitializePeerConnection() Finish PeerConnection creation [2021-05-11 23:26:47.612] [1620775607612] [1843393616][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 [2021-05-11 23:26:47.619] [1620775607619] [1893725264][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTING [2021-05-11 23:26:47.624] [1620775607624] [1843393616][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 [2021-05-11 23:26:47.633] [1620775607633] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 1 2021-05-11 23:26:47 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:26:47.636] [1620775607636] [1893725264][info] KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:0 1 udp 2130706431 10.17.230.107 37497 typ host raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} [2021-05-11 23:26:47.636] [1620775607636] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 2 2021-05-11 23:26:47 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:26:47.689] [1620775607689] [1893725264][info] KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:1 1 udp 1694498815 24.91.168.107 49210 typ srflx raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} [2021-05-11 23:26:47.689] [1620775607689] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 2 2021-05-11 23:26:47 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:26:47.735] [1620775607735] [1793061968][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 [2021-05-11 23:26:47.755] [1620775607755] [1793061968][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 2021-05-11 23:26:47 WARN handleStunPacket(): Unable to fetch request Timestamp from the hash table. No update to RTT for the pair (error code: 0x40100001) 2021-05-11 23:26:47 WARN handleStunPacket(): Dropping response packet because transaction id does not match 2021-05-11 23:26:47 ERROR handleStunPacket(): operation returned status code: 0x40100001 2021-05-11 23:26:47 ERROR incomingDataHandler(): operation returned status code: 0x40100001 [2021-05-11 23:26:47.770] [1620775607770] [1893725264][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state state_1 [2021-05-11 23:26:47.789] [1620775607789] [1793061968][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 [2021-05-11 23:26:47.825] [1620775607825] [1793061968][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 [2021-05-11 23:26:47.831] [1620775607831] [1784673360][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 [2021-05-11 23:26:47.859] [1620775607859] [1784673360][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE , type 2 [2021-05-11 23:26:47.909] [1620775607909] [1893725264][info] KvsAPI.cpp:498:OnIceCandidateHandler() ice candidate gathering finished [2021-05-11 23:26:47.911] [1620775607911] [1843393616][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTED [2021-05-11 23:26:47.912] [1620775607912] [1843393616][info] KvsAPI.cpp:1072:LogMetricsSelectedIcePair() ------------- Selected Ice Pair Report ------------- [2021-05-11 23:26:47.912] [1620775607912] [1843393616][info] KvsAPI.cpp:1073:LogMetricsSelectedIcePair() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:26:47.913] [1620775607913] [1843393616][info] KvsAPI.cpp:1074:LogMetricsSelectedIcePair() Local Candidate IP Address: 10.17.230.107 [2021-05-11 23:26:47.913] [1620775607913] [1843393616][info] KvsAPI.cpp:1075:LogMetricsSelectedIcePair() Local Candidate type: host [2021-05-11 23:26:47.913] [1620775607913] [1843393616][info] KvsAPI.cpp:1076:LogMetricsSelectedIcePair() Local Candidate port: 37497 [2021-05-11 23:26:47.913] [1620775607913] [1843393616][info] KvsAPI.cpp:1077:LogMetricsSelectedIcePair() Local Candidate priority: 2130706431 [2021-05-11 23:26:47.913] [1620775607913] [1843393616][info] KvsAPI.cpp:1078:LogMetricsSelectedIcePair() Local Candidate transport protocol: transport=udp [2021-05-11 23:26:47.914] [1620775607914] [1843393616][info] KvsAPI.cpp:1079:LogMetricsSelectedIcePair() Local Candidate relay protocol: N/A [2021-05-11 23:26:47.914] [1620775607914] [1843393616][info] KvsAPI.cpp:1080:LogMetricsSelectedIcePair() Local Candidate Ice server source: N/A [2021-05-11 23:26:47.917] [1620775607917] [1843393616][info] KvsAPI.cpp:1083:LogMetricsSelectedIcePair() Remote Candidate IP Address: 10.17.230.128 [2021-05-11 23:26:47.917] [1620775607917] [1843393616][info] KvsAPI.cpp:1084:LogMetricsSelectedIcePair() Remote Candidate type: host [2021-05-11 23:26:47.917] [1620775607917] [1843393616][info] KvsAPI.cpp:1085:LogMetricsSelectedIcePair() Remote Candidate port: 42332 [2021-05-11 23:26:47.918] [1620775607918] [1843393616][info] KvsAPI.cpp:1086:LogMetricsSelectedIcePair() Remote Candidate priority: 2122260223 [2021-05-11 23:26:47.918] [1620775607918] [1843393616][info] KvsAPI.cpp:1087:LogMetricsSelectedIcePair() Remote Candidate transport protocol: transport=udp 2021-05-11 23:26:47 INFO onSctpInboundPacket(): Unhandled PPID on incoming SCTP message 0 2021-05-11 23:26:47 ERROR onSctpSessionDataChannelOpen(): operation returned status code: 0x00000001 [2021-05-11 23:26:48.909] [1620775608909] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:26:48.910] [1620775608910] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:26:48.910] [1620775608910] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:26:48.910] [1620775608910] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 0 [2021-05-11 23:26:48.910] [1620775608910] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 0 [2021-05-11 23:26:48.915] [1620775608915] [1835005008][info] [2021-05-11 23:26:48.918] [1620775608918] [1851782224][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:26:48.918] [1620775608918] [1851782224][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i [2021-05-11 23:26:48.918] [1620775608918] [1851782224][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:26:48.918] [1620775608918] [1851782224][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:26:48.918] [1620775608918] [1851782224][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 152592 [2021-05-11 23:26:48.919] [1620775608919] [1851782224][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 152592 [2021-05-11 23:26:48.919] [1620775608919] [1851782224][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:26:48.919] [1620775608919] [1851782224][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:26:48.920] [1620775608920] [1851782224][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:26:48.920] [1620775608920] [1851782224][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 0 [2021-05-11 23:26:48.920] [1620775608920] [1835005008][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:26:48.921] [1620775608921] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:26:48.921] [1620775608921] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:26:48.921] [1620775608921] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:26:52.901] [1620775612901] [1851782224][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:26:52.901] [1620775612901] [1851782224][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i [2021-05-11 23:26:52.901] [1620775612901] [1851782224][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:26:52.902] [1620775612902] [1851782224][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:26:52.912] [1620775612912] [1851782224][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 83032 [2021-05-11 23:26:52.912] [1620775612912] [1851782224][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 83032 [2021-05-11 23:26:52.912] [1620775612912] [1851782224][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:26:52.912] [1620775612912] [1851782224][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:26:52.913] [1620775612913] [1851782224][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:26:52.913] [1620775612913] [1851782224][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:26:52.913] [1620775612913] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:26:52.913] [1620775612913] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:26:52.913] [1620775612913] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:26:52.914] [1620775612914] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:26:52.914] [1620775612914] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 732496 [2021-05-11 23:26:52.915] [1620775612915] [1835005008][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 732496 [2021-05-11 23:26:52.917] [1620775612917] [1835005008][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:26:52.917] [1620775612917] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:26:52.917] [1620775612917] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:26:52.918] [1620775612918] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:26:56.911] [1620775616911] [1851782224][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:26:56.911] [1620775616911] [1851782224][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i [2021-05-11 23:26:56.912] [1620775616912] [1851782224][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:26:56.913] [1620775616913] [1851782224][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:26:56.913] [1620775616913] [1851782224][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 38520 [2021-05-11 23:26:56.913] [1620775616913] [1851782224][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 38520 [2021-05-11 23:26:56.913] [1620775616913] [1851782224][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:26:56.913] [1620775616913] [1851782224][info] [2021-05-11 23:26:56.914] [1620775616914] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:26:56.914] [1620775616914] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:26:56.917] [1620775616917] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:26:56.917] [1620775616917] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:26:56.918] [1620775616918] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 296448 [2021-05-11 23:26:56.918] [1620775616918] [1835005008][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 296448 [2021-05-11 23:26:56.918] [1620775616918] [1835005008][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:26:56.918] [1620775616918] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:26:56.918] [1620775616918] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:26:56.919] [1620775616919] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:26:56.919] [1620775616919] [1851782224][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:26:56.919] [1620775616919] [1851782224][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0

[2021-05-11 23:26:57.129] [1620775617129] [1784673360][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 0 [2021-05-11 23:26:57.132] [1620775617132] [1946154064][info] KvsAPI.cpp:474:HandleMessage() Received Offer from peer client af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac 2021-05-11 23:26:57 INFO signalingClientGetIceConfigInfo(): Signaling Client Get ICE Config Info [2021-05-11 23:26:57.132] [1620775617132] [1946154064][info] KvsAPI.cpp:585:InitializePeerConnection() Start PeerConnection creation [2021-05-11 23:26:57.164] [1620775617164] [1946154064][info] KvsAPI.cpp:587:InitializePeerConnection() Finish PeerConnection creation [2021-05-11 23:26:57.170] [1620775617170] [1784673360][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 [2021-05-11 23:26:57.188] [1620775617188] [1793061968][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTING [2021-05-11 23:26:57.199] [1620775617199] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 1 2021-05-11 23:26:57 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:26:57.202] [1620775617202] [1793061968][info] KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:0 1 udp 2130706431 10.17.230.107 39899 typ host raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} [2021-05-11 23:26:57.206] [1620775617206] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 2 2021-05-11 23:26:57 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:26:57.259] [1620775617259] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 [2021-05-11 23:26:57.267] [1620775617267] [1793061968][info] KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:1 1 udp 1694498815 24.91.168.107 36607 typ srflx raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} [2021-05-11 23:26:57.267] [1620775617267] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 2 2021-05-11 23:26:57 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:26:57.289] [1620775617289] [1793061968][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state state_1 [2021-05-11 23:26:57.304] [1620775617304] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 [2021-05-11 23:26:57.354] [1620775617354] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 [2021-05-11 23:26:57.368] [1620775617368] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 [2021-05-11 23:26:57.447] [1620775617447] [1793061968][info] KvsAPI.cpp:498:OnIceCandidateHandler() ice candidate gathering finished [2021-05-11 23:26:57.466] [1620775617466] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 [2021-05-11 23:26:57.478] [1620775617478] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 [2021-05-11 23:26:57.598] [1620775617598] [1774187600][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTED [2021-05-11 23:26:57.599] [1620775617599] [1774187600][info] KvsAPI.cpp:1072:LogMetricsSelectedIcePair() ------------- Selected Ice Pair Report ------------- [2021-05-11 23:26:57.600] [1620775617600] [1774187600][info] KvsAPI.cpp:1073:LogMetricsSelectedIcePair() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:26:57.601] [1620775617601] [1774187600][info] [2021-05-11 23:26:57.604] [1620775617604] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac , type 2 KvsAPI.cpp:1074:LogMetricsSelectedIcePair() Local Candidate IP Address: 10.17.230.107 [2021-05-11 23:26:57.606] [1620775617606] [1774187600][info] KvsAPI.cpp:1075:LogMetricsSelectedIcePair() Local Candidate type: host [2021-05-11 23:26:57.609] [1620775617609] [1774187600][info] KvsAPI.cpp:1076:LogMetricsSelectedIcePair() Local Candidate port: 39899 [2021-05-11 23:26:57.609] [1620775617609] [1774187600][info] KvsAPI.cpp:1077:LogMetricsSelectedIcePair() Local Candidate priority: 2130706431 [2021-05-11 23:26:57.609] [1620775617609] [1774187600][info] KvsAPI.cpp:1078:LogMetricsSelectedIcePair() Local Candidate transport protocol: transport=udp [2021-05-11 23:26:57.609] [1620775617609] [1774187600][info] KvsAPI.cpp:1079:LogMetricsSelectedIcePair() Local Candidate relay protocol: N/A [2021-05-11 23:26:57.610] [1620775617610] [1774187600][info] KvsAPI.cpp:1080:LogMetricsSelectedIcePair() Local Candidate Ice server source: N/A [2021-05-11 23:26:57.610] [1620775617610] [1774187600][info] KvsAPI.cpp:1083:LogMetricsSelectedIcePair() Remote Candidate IP Address: 10.17.230.128 [2021-05-11 23:26:57.610] [1620775617610] [1774187600][info] KvsAPI.cpp:1084:LogMetricsSelectedIcePair() Remote Candidate type: host [2021-05-11 23:26:57.611] [1620775617611] [1774187600][info] KvsAPI.cpp:1085:LogMetricsSelectedIcePair() Remote Candidate port: 50837 [2021-05-11 23:26:57.611] [1620775617611] [1774187600][info] KvsAPI.cpp:1086:LogMetricsSelectedIcePair() Remote Candidate priority: 2122260223 [2021-05-11 23:26:57.611] [1620775617611] [1774187600][info] KvsAPI.cpp:1087:LogMetricsSelectedIcePair() Remote Candidate transport protocol: transport=udp 2021-05-11 23:26:57 INFO onSctpInboundPacket(): Unhandled PPID on incoming SCTP message 0 2021-05-11 23:26:57 ERROR onSctpSessionDataChannelOpen(): operation returned status code: 0x00000001 [2021-05-11 23:27:00.935] [1620775620935] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:00.938] [1620775620938] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:27:00.939] [1620775620939] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:00.939] [1620775620939] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:00.939] [1620775620939] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 149720 [2021-05-11 23:27:00.939] [1620775620939] [1835005008][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 149720 [2021-05-11 23:27:00.939] [1620775620939] [1835005008][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:00.940] [1620775620940] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:00.940] [1620775620940] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:00.940] [1620775620940] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:00.942] [1620775620942] [1851782224][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:00.943] [1620775620943] [1851782224][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i [2021-05-11 23:27:00.944] [1620775620944] [1851782224][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:00.944] [1620775620944] [1851782224][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:00.944] [1620775620944] [1851782224][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 37168 [2021-05-11 23:27:00.944] [1620775620944] [1851782224][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 37168 [2021-05-11 23:27:00.945] [1620775620945] [1851782224][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:00.945] [1620775620945] [1851782224][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:00.945] [1620775620945] [1851782224][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:00.945] [1620775620945] [1851782224][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:00.946] [1620775620946] [1946154064][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:00.946] [1620775620946] [1946154064][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:27:00.947] [1620775620947] [1946154064][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:00.947] [1620775620947] [1946154064][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 0 [2021-05-11 23:27:00.947] [1620775620947] [1946154064][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 443456808 [2021-05-11 23:27:00.947] [1620775620947] [1946154064][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 1633553257 [2021-05-11 23:27:00.947] [1620775620947] [1946154064][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 2227380873 [2021-05-11 23:27:00.948] [1620775620948] [1946154064][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 500738927 [2021-05-11 23:27:00.948] [1620775620948] [1946154064][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 4236156105 [2021-05-11 23:27:00.949] [1620775620949] [1946154064][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 3478375516 [2021-05-11 23:27:04.778] [1620775624778] [1885336656][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_DISCONNECTED [2021-05-11 23:27:04.779] [1620775624779] [1937765456][info] KvsAPI.cpp:943:Stop() Stopping peer connection thread [2021-05-11 23:27:04.780] [1620775624780] [1937765456][info] KvsAPI.cpp:948:Stop() peer connection thread stopped [2021-05-11 23:27:04.780] [1620775624780] [1937765456][info] KvsAPI.cpp:1106:LogMetricsIceServerStats() ------------- Selected Ice Server Report ------------- [2021-05-11 23:27:04.780] [1620775624780] [1937765456][info] KvsAPI.cpp:1107:LogMetricsIceServerStats() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i [2021-05-11 23:27:04.781] [1620775624781] [1937765456][info] KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: stun:stun.kinesisvideo.us-east-1.amazonaws.com:443 [2021-05-11 23:27:04.781] [1620775624781] [1937765456][info] KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: [2021-05-11 23:27:04.781] [1620775624781] [1937765456][info] KvsAPI.cpp:1114:LogMetricsIceServerStats() ICE Server port: 443 [2021-05-11 23:27:04.781] [1620775624781] [1937765456][info] KvsAPI.cpp:1115:LogMetricsIceServerStats() Total requests sent: 1 [2021-05-11 23:27:04.781] [1620775624781] [1937765456][info] KvsAPI.cpp:1116:LogMetricsIceServerStats() Total responses received: 1 [2021-05-11 23:27:04.782] [1620775624782] [1937765456][info] KvsAPI.cpp:1117:LogMetricsIceServerStats() Total round trip time: 41ms [2021-05-11 23:27:04.782] [1620775624782] [1937765456][info] KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: turn:54-80-180-89.t-e58da546.kinesisvideo.us-east-1.amazonaws.com:443?transport=udp [2021-05-11 23:27:04.782] [1620775624782] [1937765456][info] KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: transport=udp [2021-05-11 23:27:04.783] [1620775624783] [1937765456][info] KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: turns:54-80-180-89.t-e58da546.kinesisvideo.us-east-1.amazonaws.com:443?transport=udp [2021-05-11 23:27:04.784] [1620775624784] [1937765456][info] KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: transport=udp [2021-05-11 23:27:04.786] [1620775624786] [1937765456][info] KvsAPI.cpp:1110:LogMetricsIceServerStats() ICE Server URL: turns:54-80-180-89.t-e58da546.kinesisvideo.us-east-1.amazonaws.com:443?transport=tcp [2021-05-11 23:27:04.786] [1620775624786] [1937765456][info] KvsAPI.cpp:1111:LogMetricsIceServerStats() ICE Server protocol: transport=tcp [2021-05-11 23:27:04.786] [1620775624786] [1937765456][info] KvsAPI.cpp:1095:LogMetricsLatency() ------------- Latency Report ------------- [2021-05-11 23:27:04.787] [1620775624787] [1937765456][info] KvsAPI.cpp:1096:LogMetricsLatency() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_nz-oCW8C-i [2021-05-11 23:27:04.787] [1620775624787] [1937765456][info] KvsAPI.cpp:1097:LogMetricsLatency() latency from app start to receiving offer: 386339ms [2021-05-11 23:27:04.787] [1620775624787] [1937765456][info] KvsAPI.cpp:1098:LogMetricsLatency() latency from receiving offer to sending frame: 229ms [2021-05-11 23:27:04.787] [1620775624787] [1937765456][info] KvsAPI.cpp:1099:LogMetricsLatency() latency from sending frame to srtp ready: 250ms [2021-05-11 23:27:04.790] [1620775624790] [1937765456][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CLOSED [2021-05-11 23:27:04.909] [1620775624909] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:04.910] [1620775624910] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:27:04.911] [1620775624911] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:04.911] [1620775624911] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:04.911] [1620775624911] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 79000 [2021-05-11 23:27:04.911] [1620775624911] [1835005008][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 79000 [2021-05-11 23:27:04.912] [1620775624912] [1835005008][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:04.912] [1620775624912] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:04.912] [1620775624912] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:04.912] [1620775624912] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:04.914] [1620775624914] [1946154064][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:04.917] [1620775624917] [1946154064][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:27:04.917] [1620775624917] [1946154064][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:04.917] [1620775624917] [1946154064][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:04.918] [1620775624918] [1946154064][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 730320 [2021-05-11 23:27:04.918] [1620775624918] [1946154064][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 730320 [2021-05-11 23:27:04.918] [1620775624918] [1946154064][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:04.918] [1620775624918] [1946154064][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:04.918] [1620775624918] [1946154064][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:04.923] [1620775624923] [1946154064][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 2021-05-11 23:27:05 WARN freeConnectionListener(): Connection listener handler thread shutdown timed out 2021-05-11 23:27:06 WARN freeSocketConnection(): Shutting down socket connection timedout after 1 seconds

[2021-05-11 23:27:07.888] [1620775627888] [1885336656][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 0 [2021-05-11 23:27:07.889] [1620775627889] [1784673360][info] KvsAPI.cpp:474:HandleMessage() Received Offer from peer client af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY 2021-05-11 23:27:07 INFO signalingClientGetIceConfigInfo(): Signaling Client Get ICE Config Info [2021-05-11 23:27:07.889] [1620775627889] [1784673360][info] KvsAPI.cpp:585:InitializePeerConnection() Start PeerConnection creation 2021-05-11 23:27:07 WARN freeSocketConnection(): Shutting down socket connection timedout after 1 seconds [2021-05-11 23:27:07.919] [1620775627919] [1885336656][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 [2021-05-11 23:27:07.921] [1620775627921] [1784673360][info] KvsAPI.cpp:587:InitializePeerConnection() Finish PeerConnection creation [2021-05-11 23:27:07.936] [1620775627936] [1885336656][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 [2021-05-11 23:27:07.938] [1620775627938] [1851782224][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTING [2021-05-11 23:27:07.945] [1620775627945] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 1 2021-05-11 23:27:07 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:27:07.948] [1620775627948] [1851782224][info] KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:0 1 udp 2130706431 10.17.230.107 40984 typ host raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} [2021-05-11 23:27:07.949] [1620775627949] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 2 2021-05-11 23:27:07 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:27:08.001] [1620775628001] [1851782224][info] KvsAPI.cpp:503:OnIceCandidateHandler() Add local Ice candidate {"candidate":"candidate:1 1 udp 1694498815 24.91.168.107 42168 typ srflx raddr 0.0.0.0 rport 0 generation 0 network-cost 999","sdpMid":"0","sdpMLineIndex":0} [2021-05-11 23:27:08.005] [1620775628005] [1868559440][info] KvsAPI.cpp:273:SendMessage() Sending Message Type 2 2021-05-11 23:27:08 INFO signalingClientSendMessageSync(): Signaling Client Sending Message Sync [2021-05-11 23:27:08.039] [1620775628039] [1851782224][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state state_1 [2021-05-11 23:27:08.060] [1620775628060] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 [2021-05-11 23:27:08.082] [1620775628082] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 [2021-05-11 23:27:08.117] [1620775628117] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 [2021-05-11 23:27:08.156] [1620775628156] [1851782224][info] KvsAPI.cpp:498:OnIceCandidateHandler() ice candidate gathering finished [2021-05-11 23:27:08.165] [1620775628165] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 [2021-05-11 23:27:08.196] [1620775628196] [1885336656][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_CONNECTED [2021-05-11 23:27:08.196] [1620775628196] [1885336656][info] KvsAPI.cpp:1072:LogMetricsSelectedIcePair() ------------- Selected Ice Pair Report ------------- [2021-05-11 23:27:08.197] [1620775628197] [1885336656][info] KvsAPI.cpp:1073:LogMetricsSelectedIcePair() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY [2021-05-11 23:27:08.197] [1620775628197] [1885336656][info] KvsAPI.cpp:1074:LogMetricsSelectedIcePair() Local Candidate IP Address: 10.17.230.107 [2021-05-11 23:27:08.197] [1620775628197] [1885336656][info] KvsAPI.cpp:1075:LogMetricsSelectedIcePair() Local Candidate type: host [2021-05-11 23:27:08.197] [1620775628197] [1885336656][info] KvsAPI.cpp:1076:LogMetricsSelectedIcePair() Local Candidate port: 40984 [2021-05-11 23:27:08.197] [1620775628197] [1885336656][info] KvsAPI.cpp:1077:LogMetricsSelectedIcePair() Local Candidate priority: 2130706431 [2021-05-11 23:27:08.198] [1620775628198] [1885336656][info] KvsAPI.cpp:1078:LogMetricsSelectedIcePair() Local Candidate transport protocol: transport=udp [2021-05-11 23:27:08.198] [1620775628198] [1885336656][info] KvsAPI.cpp:1079:LogMetricsSelectedIcePair() Local Candidate relay protocol: N/A [2021-05-11 23:27:08.198] [1620775628198] [1885336656][info] KvsAPI.cpp:1080:LogMetricsSelectedIcePair() Local Candidate Ice server source: N/A [2021-05-11 23:27:08.199] [1620775628199] [1885336656][info] KvsAPI.cpp:1083:LogMetricsSelectedIcePair() Remote Candidate IP Address: 10.17.230.128 [2021-05-11 23:27:08.199] [1620775628199] [1885336656][info] KvsAPI.cpp:1084:LogMetricsSelectedIcePair() Remote Candidate type: host [2021-05-11 23:27:08.199] [1620775628199] [1885336656][info] KvsAPI.cpp:1085:LogMetricsSelectedIcePair() Remote Candidate port: 42564 [2021-05-11 23:27:08.199] [1620775628199] [1885336656][info] KvsAPI.cpp:1086:LogMetricsSelectedIcePair() Remote Candidate priority: 2122260223 [2021-05-11 23:27:08.200] [1620775628200] [1885336656][info] KvsAPI.cpp:1087:LogMetricsSelectedIcePair() Remote Candidate transport protocol: transport=udp [2021-05-11 23:27:08.208] [1620775628208] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 2021-05-11 23:27:08 INFO onSctpInboundPacket(): Unhandled PPID on incoming SCTP message 0 2021-05-11 23:27:08 ERROR onSctpSessionDataChannelOpen(): operation returned status code: 0x00000001 [2021-05-11 23:27:08.302] [1620775628302] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_XfjnFLzsFY , type 2 [2021-05-11 23:27:08.912] [1620775628912] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:08.913] [1620775628913] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:27:08.913] [1620775628913] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:08.913] [1620775628913] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:08.913] [1620775628913] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 38344 [2021-05-11 23:27:08.913] [1620775628913] [1835005008][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 38344 [2021-05-11 23:27:08.914] [1620775628914] [1835005008][info] [2021-05-11 23:27:08.917] [1620775628917] [1946154064][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:08.917] [1620775628917] [1946154064][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:27:08.917] [1620775628917] [1946154064][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:08.917] [1620775628917] [1946154064][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:08.918] [1620775628918] [1946154064][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 301392 [2021-05-11 23:27:08.918] [1620775628918] [1946154064][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 301392 [2021-05-11 23:27:08.918] [1620775628918] [1946154064][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:08.918] [1620775628918] [1946154064][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:08.918] [1620775628918] [1946154064][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:08.918] [1620775628918] [1946154064][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:08.919] [1620775628919] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:08.919] [1620775628919] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:08.919] [1620775628919] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:12.909] [1620775632909] [1946154064][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:12.911] [1620775632911] [1946154064][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:27:12.912] [1620775632912] [1946154064][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:12.913] [1620775632913] [1946154064][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:12.913] [1620775632913] [1946154064][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 153984 [2021-05-11 23:27:12.913] [1620775632913] [1946154064][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 153984 [2021-05-11 23:27:12.913] [1620775632913] [1946154064][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:12.913] [1620775632913] [1946154064][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:12.914] [1620775632914] [1946154064][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:12.915] [1620775632915] [1946154064][info] [2021-05-11 23:27:12.918] [1620775632918] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:12.918] [1620775632918] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:27:12.918] [1620775632918] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:12.919] [1620775632919] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:12.919] [1620775632919] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 35600 [2021-05-11 23:27:12.919] [1620775632919] [1835005008][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 35600 [2021-05-11 23:27:12.920] [1620775632920] [1835005008][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:12.920] [1620775632920] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:12.920] [1620775632920] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:12.921] [1620775632921] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:16.899] [1620775636899] [1835005008][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:16.899] [1620775636899] [1835005008][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tn4UCHuXBE [2021-05-11 23:27:16.899] [1620775636899] [1835005008][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:16.900] [1620775636900] [1835005008][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:16.904] [1620775636904] [1835005008][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 37656 [2021-05-11 23:27:16.905] [1620775636905] [1835005008][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 37656 [2021-05-11 23:27:16.909] [1620775636909] [1835005008][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:16.909] [1620775636909] [1835005008][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:16.910] [1620775636910] [1835005008][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:16.910] [1620775636910] [1835005008][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:16.912] [1620775636912] [1946154064][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:16.912] [1620775636912] [1946154064][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:27:16.912] [1620775636912] [1946154064][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:16.913] [1620775636913] [1946154064][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:16.913] [1620775636913] [1946154064][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 82968 [2021-05-11 23:27:16.913] [1620775636913] [1946154064][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 82968 [2021-05-11 23:27:16.913] [1620775636913] [1946154064][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:16.913] [1620775636913] [1946154064][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:16.915] [1620775636915] [1946154064][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:16.919] [1620775636919] [1946154064][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:18.948] [1620775638948] [1893725264][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_DISCONNECTED [2021-05-11 23:27:20.891] [1620775640891] [1946154064][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:20.891] [1620775640891] [1946154064][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:27:20.892] [1620775640892] [1946154064][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:20.892] [1620775640892] [1946154064][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:20.892] [1620775640892] [1946154064][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 37992 [2021-05-11 23:27:20.892] [1620775640892] [1946154064][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 37992 [2021-05-11 23:27:20.893] [1620775640893] [1946154064][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:20.893] [1620775640893] [1946154064][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:20.893] [1620775640893] [1946154064][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:20.893] [1620775640893] [1946154064][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:24.892] [1620775644892] [1946154064][info] KvsAPI.cpp:1058:LogMetricsRtpTransmission() ------------- Periodic Rtp Transmission Report ------------- [2021-05-11 23:27:24.893] [1620775644893] [1946154064][info] KvsAPI.cpp:1059:LogMetricsRtpTransmission() Connected with peer af1f8ce3c1cf48e39d9607c2f1000335_2698678_tVs3RCpac [2021-05-11 23:27:24.893] [1620775644893] [1946154064][info] KvsAPI.cpp:1060:LogMetricsRtpTransmission() Most recent RTT: 0ms [2021-05-11 23:27:24.893] [1620775644893] [1946154064][info] KvsAPI.cpp:1061:LogMetricsRtpTransmission() measured frame rate: 20 [2021-05-11 23:27:24.893] [1620775644893] [1946154064][info] KvsAPI.cpp:1062:LogMetricsRtpTransmission() measured bit rate: 37288 [2021-05-11 23:27:24.894] [1620775644894] [1946154064][info] KvsAPI.cpp:1063:LogMetricsRtpTransmission() measured throughput: 37288 [2021-05-11 23:27:24.894] [1620775644894] [1946154064][info] KvsAPI.cpp:1064:LogMetricsRtpTransmission() current dropped packets per million: 0 [2021-05-11 23:27:24.894] [1620775644894] [1946154064][info] KvsAPI.cpp:1065:LogMetricsRtpTransmission() current retransmitted packets per million: 0 [2021-05-11 23:27:24.894] [1620775644894] [1946154064][info] KvsAPI.cpp:1066:LogMetricsRtpTransmission() total dropped packets per million: 0 [2021-05-11 23:27:24.894] [1620775644894] [1946154064][info] KvsAPI.cpp:1067:LogMetricsRtpTransmission() total retransmitted packets per million: 0 [2021-05-11 23:27:28.605] [1620775648605] [1793061968][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_DISCONNECTED

2021-05-11 23:27:33 ERROR executeFailedIceAgentState(): IceAgent failed with 0x5a000025 [2021-05-11 23:27:33.958] [1620775653958] [1893725264][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_FAILED [2021-05-11 23:27:39.295] [1620775659295] [1851782224][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_DISCONNECTED [2021-05-11 23:27:39.955] [1620775659955] [1765798992][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 0 [2021-05-11 23:27:39.956] [1620775659956] [1784673360][info] KvsAPI.cpp:474:HandleMessage() Received Offer from peer client af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn 2021-05-11 23:27:39 INFO signalingClientGetIceConfigInfo(): Signaling Client Get ICE Config Info [2021-05-11 23:27:39.956] [1620775659956] [1784673360][info] KvsAPI.cpp:585:InitializePeerConnection() Start PeerConnection creation [2021-05-11 23:27:39.969] [1620775659969] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 [2021-05-11 23:27:39.984] [1620775659984] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 [2021-05-11 23:27:39.998] [1620775659998] [1784673360][info] KvsAPI.cpp:587:InitializePeerConnection() Finish PeerConnection creation [2021-05-11 23:27:39.019] [1620775659019] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8843, data=0x4fa56000, size=4030, iskeyframe=0, pts=510139ms, apiFrame.presentationTs=16207756589713560 [2021-05-11 23:27:39.071] [1620775659071] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8844, data=0x4fa8b680, size=4065, iskeyframe=0, pts=510189ms, apiFrame.presentationTs=16207756590213560 [2021-05-11 23:27:39.122] [1620775659122] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8845, data=0x4fa8ff80, size=4197, iskeyframe=0, pts=510239ms, apiFrame.presentationTs=16207756590713560 [2021-05-11 23:27:39.176] [1620775659176] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8846, data=0x4faa9700, size=4095, iskeyframe=0, pts=510289ms, apiFrame.presentationTs=16207756591213560 [2021-05-11 23:27:39.219] [1620775659219] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8847, data=0x4fab5380, size=4058, iskeyframe=0, pts=510339ms, apiFrame.presentationTs=16207756591713560 [2021-05-11 23:27:39.270] [1620775659270] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8848, data=0x4fa75f80, size=3934, iskeyframe=0, pts=510389ms, apiFrame.presentationTs=16207756592213560 [2021-05-11 23:27:39.322] [1620775659322] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8849, data=0x4fa6de80, size=3977, iskeyframe=0, pts=510439ms, apiFrame.presentationTs=16207756592713560 [2021-05-11 23:27:39.374] [1620775659374] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8850, data=0x4fa9c380, size=4007, iskeyframe=0, pts=510489ms, apiFrame.presentationTs=16207756593213560 [2021-05-11 23:27:39.428] [1620775659428] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8851, data=0x4fa56000, size=3992, iskeyframe=0, pts=510539ms, apiFrame.presentationTs=16207756593713560 [2021-05-11 23:27:39.469] [1620775659469] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8852, data=0x4fa70400, size=4289, iskeyframe=0, pts=510589ms, apiFrame.presentationTs=16207756594213560 [2021-05-11 23:27:39.520] [1620775659520] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8853, data=0x4fa75f80, size=4168, iskeyframe=0, pts=510639ms, apiFrame.presentationTs=16207756594713560 [2021-05-11 23:27:39.571] [1620775659571] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8854, data=0x4fa8ff80, size=4204, iskeyframe=0, pts=510689ms, apiFrame.presentationTs=16207756595213560 [2021-05-11 23:27:39.628] [1620775659628] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8855, data=0x4faa0500, size=4208, iskeyframe=0, pts=510739ms, apiFrame.presentationTs=16207756595713560 [2021-05-11 23:27:39.669] [1620775659669] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8856, data=0x4fab5380, size=4068, iskeyframe=0, pts=510789ms, apiFrame.presentationTs=16207756596213560 [2021-05-11 23:27:39.720] [1620775659720] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8857, data=0x4fabed80, size=4137, iskeyframe=0, pts=510839ms, apiFrame.presentationTs=16207756596713560 [2021-05-11 23:27:39.771] [1620775659771] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8858, data=0x4fac1580, size=4165, iskeyframe=0, pts=510889ms, apiFrame.presentationTs=16207756597213560 [2021-05-11 23:27:39.858] [1620775659858] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8859, data=0x4fa85080, size=4180, iskeyframe=0, pts=510939ms, apiFrame.presentationTs=16207756597713560 [2021-05-11 23:27:39.869] [1620775659869] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8860, data=0x4fa97e00, size=4091, iskeyframe=0, pts=510989ms, apiFrame.presentationTs=16207756598213560 [2021-05-11 23:27:39.946] [1620775659946] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8861, data=0x4fa70400, size=4010, iskeyframe=0, pts=511039ms, apiFrame.presentationTs=16207756598713560 [2021-05-11 23:27:39.980] [1620775659980] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=8862, data=0x4fa75f80, size=4060, iskeyframe=0, pts=511089ms, apiFrame.presentationTs=16207756599213560 [2021-05-11 23:27:40.000] [1620775660000] [1784673360][error] KvsAPI.cpp:778:HandleOffer() Dropping offer as peerConnection is shutting down [2021-05-11 23:27:40.001] [1620775660001] [1784673360][info] webrtc-main.cpp:100:handleLogError() Handling webrtc error log [2021-05-11 23:27:40.099] [1620775660099] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 [2021-05-11 23:27:40.112] [1620775660112] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 [2021-05-11 23:27:40.183] [1620775660183] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 [2021-05-11 23:27:40.246] [1620775660246] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 [2021-05-11 23:27:40.259] [1620775660259] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 [2021-05-11 23:27:40.324] [1620775660324] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_SR_Q0acPMn , type 2 2021-05-11 23:27:43 ERROR executeFailedIceAgentState(): IceAgent failed with 0x5a000025 [2021-05-11 23:27:43.669] [1620775663669] [1793061968][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_FAILED 2021-05-11 23:27:54 ERROR executeFailedIceAgentState(): IceAgent failed with 0x5a000025 [2021-05-11 23:27:54.361] [1620775674361] [1851782224][info] KvsAPI.cpp:537:OnConnectionStateChange() New peer connection state RTC_PEER_CONNECTION_STATE_FAILED [2021-05-11 23:28:04.709] [1620775684709] [1757410384][info] KvsAPI.cpp:299:SignalingChannelMessageReceived() Signaling Message from af1f8ce3c1cf48e39d9607c2f1000335_2698678_Y2GXVWkQkV , type 0 [2021-05-11 23:28:04.710] [1620775684710] [1784673360][info] KvsAPI.cpp:474:HandleMessage() Received Offer from peer client af1f8ce3c1cf48e39d9607c2f1000335_2698678_Y2GXVWkQkV 2021-05-11 23:28:04 INFO signalingClientGetIceConfigInfo(): Signaling Client Get ICE Config Info [2021-05-11 23:28:04.710] [1620775684710] [1784673360][info] KvsAPI.cpp:585:InitializePeerConnection() Start PeerConnection creation [2021-05-11 23:28:04.744] [1620775684744] [1784673360][info] KvsAPI.cpp:587:InitializePeerConnection() Finish PeerConnection creation [2021-05-11 23:28:03.773] [1620775683773] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9338, data=0x4faa5200, size=4172, iskeyframe=0, pts=534889ms, apiFrame.presentationTs=16207756837216430 [2021-05-11 23:28:03.824] [1620775683824] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9339, data=0x4faadb80, size=4078, iskeyframe=0, pts=534939ms, apiFrame.presentationTs=16207756837716430 [2021-05-11 23:28:03.876] [1620775683876] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9340, data=0x4f82a600, size=4133, iskeyframe=0, pts=534989ms, apiFrame.presentationTs=16207756838216430 [2021-05-11 23:28:03.920] [1620775683920] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9341, data=0x4fab2980, size=4123, iskeyframe=0, pts=535039ms, apiFrame.presentationTs=16207756838716430 [2021-05-11 23:28:04.005] [1620775684005] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9342, data=0x4fa46680, size=4081, iskeyframe=0, pts=535089ms, apiFrame.presentationTs=16207756839216430 [2021-05-11 23:28:04.096] [1620775684096] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9343, data=0x4fa5fa00, size=4125, iskeyframe=0, pts=535139ms, apiFrame.presentationTs=16207756839716430 [2021-05-11 23:28:04.106] [1620775684106] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9344, data=0x4fa7a480, size=4121, iskeyframe=0, pts=535189ms, apiFrame.presentationTs=16207756840216430 [2021-05-11 23:28:04.128] [1620775684128] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9345, data=0x4fa81d80, size=4200, iskeyframe=0, pts=535239ms, apiFrame.presentationTs=16207756840716430 [2021-05-11 23:28:04.169] [1620775684169] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9346, data=0x4fa8c400, size=4218, iskeyframe=0, pts=535289ms, apiFrame.presentationTs=16207756841216430 [2021-05-11 23:28:04.220] [1620775684220] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9347, data=0x4fa9d500, size=4223, iskeyframe=0, pts=535339ms, apiFrame.presentationTs=16207756841716430 [2021-05-11 23:28:04.271] [1620775684271] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9348, data=0x4faabe80, size=4201, iskeyframe=0, pts=535389ms, apiFrame.presentationTs=16207756842216430 [2021-05-11 23:28:04.324] [1620775684324] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9349, data=0x4fa4a500, size=4232, iskeyframe=0, pts=535439ms, apiFrame.presentationTs=16207756842716430 [2021-05-11 23:28:04.376] [1620775684376] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9350, data=0x4fa9e680, size=4064, iskeyframe=0, pts=535489ms, apiFrame.presentationTs=16207756843216430 [2021-05-11 23:28:04.429] [1620775684429] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9351, data=0x4fab2980, size=4088, iskeyframe=0, pts=535539ms, apiFrame.presentationTs=16207756843716430 [2021-05-11 23:28:04.481] [1620775684481] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9352, data=0x4f83f000, size=4045, iskeyframe=0, pts=535589ms, apiFrame.presentationTs=16207756844216430 [2021-05-11 23:28:04.522] [1620775684522] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9353, data=0x4fa4a500, size=3970, iskeyframe=0, pts=535639ms, apiFrame.presentationTs=16207756844716430 [2021-05-11 23:28:04.574] [1620775684574] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9354, data=0x4fa6dd80, size=4179, iskeyframe=0, pts=535689ms, apiFrame.presentationTs=16207756845216430 [2021-05-11 23:28:04.629] [1620775684629] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9355, data=0x4fa89a80, size=4443, iskeyframe=0, pts=535739ms, apiFrame.presentationTs=16207756845716430 [2021-05-11 23:28:04.669] [1620775684669] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9356, data=0x4fa8c000, size=4452, iskeyframe=0, pts=535789ms, apiFrame.presentationTs=16207756846216430 [2021-05-11 23:28:04.719] [1620775684719] [1809839184][trace] SrcICatch.cpp:287:handleVideoFrame() index=9357, data=0x4fa9c980, size=4424, iskeyframe=0, pts=535839ms, apiFrame.presentationTs=16207756846716430 [2021-05-11 23:28:04.747] [1620775684747] [1784673360][error] KvsAPI.cpp:778:HandleOffer() Dropping offer as peerConnection is shutting down [2021-05-11 23:28:04.748] [1620775684748] [1784673360][info] webrtc-main.cpp:100:handleLogError() Handling webrtc error log [

May 19 '21 19:05 zwei333

@zwei333 ,

Is it possible to run the sample without changes and attach the logs?

How do we know the viewer is disconnecting? The log line I am specifically looking for is: "Detected DTLS close_notify alert"

May 24 '21 16:05 disa6302

Unfortunately, we can't run the sample viewer to connect to our camera.

Our mobile app viewer is not implemented with kvs code. webrtc.org's source code is used. The library has JNI and andoid java api. When disconnecting, this API is called https://chromium.googlesource.com/external/webrtc/+/master/sdk/android/api/org/webrtc/PeerConnection.java, line 1244 dispose() Are you familiar with webrtc.org's library? Is its connection closing procedure expected by kvs-master?

May 24 '21 16:05 zwei333

When SDK receives dtls closed event, it sets RTC_PEER_CONNECTION_STATE_CLOSED state When SDK receives ICE_AGENT_STATE_DISCONNECTED, it sets RTC_PEER_CONNECTION_STATE_DISCONNECTED state. Question: What causes dtls closed event, and what causes ICE_AGENT_STATE_DISCONNECTED?

May 26 '21 05:05 zwei333

Is there DTLS traffic between peers every a few seconds to keep the "connection" Is there a heartbeat between ICE pairs to keep the ICE pair active?

May 26 '21 05:05 zwei333

@zwei333 ,

Yes. There are STUN packet keep alives that are exchanged periodically and if the master does not receive any packet from the viewer, the state transitions to disconnected

May 26 '21 15:05 disa6302

@disa6302 I did experiment by turning off phone wifi (phone doesn't have cell signal). After 30 seconds, the state changes to disconnected. I guess that is because STUN keep alive messages are not received in 30 seconds. How about dtls? I know when peer closes it will cause camera to have dtls_closed event. How does it work? The reason I am asking is that when I closed streaming from phone app, camera was supposed to get dtls_closed event. But it didn't happen. After 30 seconds, ICE_AGENT_STATE_DISCONNECTED came.

May 26 '21 15:05 zwei333

Back to the original report of this issue. There were three peerConnections from camera's point of view. On mobile app side, it only had the last one. The other two were dead. No dtls_closed event poped up. After about 30 seconds, RTC_PEER_CONNECTION_STATE_DISCONNECTED came to camera for the first connection. In response to this event, webrtc app called freePeerConnection(&pPeerConnection_);

After about 1 second, webrtc SDK printed out WARNING message 2021-05-11 23:27:05 WARN freeConnectionListener(): Connection listener handler thread shutdown timed out 2021-05-11 23:27:06 WARN freeSocketConnection(): Shutting down socket connection timedout after 1 seconds And call of freePeerConnection never returned.

Is there any possibility of deadlock?

May 26 '21 15:05 zwei333

It is a possibility. But we would need a stack trace to investigate. Can you provide one?

Jun 03 '21 18:06 disa6302

It is hard to dump the stacktrace on the embedded system. Can we study the code logic to investigate possible lock up?

Jun 10 '21 19:06 zwei333

It looks like this issue has not been active for a long time. If the issue is not resolved, please add an update to the ticket, else it will be automatically resolved in a few days.

Aug 19 '21 21:08 github-actions[bot]

I have gone through the code logic, but nothing stands out. Will see if I can trace what could be causing this or look into reproducing the issue.

Aug 19 '21 21:08 disa6302

@zwei333 ,

I cannot find anything in the code path that stands out to indicate the issue. We will need a stack trace to investigate this.

Sep 22 '21 19:09 disa6302

The issue has been stale for a while and hence it has been auto-closed. If the issue persists, feel free to open a new issue with details.

Sep 27 '21 00:09 github-actions[bot]

This is a very old issue. We encourage you to check if this is still an issue in the latest release and if you find that this is still a problem, please feel free to open a new one.

May 19 '22 00:05 github-actions[bot]

This is a very old issue. We encourage you to check if this is still an issue in the latest release and if you find that this is still a problem, please feel free to open a new one.

Nov 17 '22 00:11 github-actions[bot]

amazon-kinesis-video-streams-webrtc-sdk-c amazon-kinesis-video-streams-webrtc-sdk-c copied to clipboard

[BUG] Calling freePeerConnection doesn't return

amazon-kinesis-video-streams-webrtc-sdk-c
amazon-kinesis-video-streams-webrtc-sdk-c copied to clipboard