Describe the bug
Enabling camera after enabling screen sharing doesn't work on Firefox (107.0.1 (64-bit) and 108.0.1 (64-bit)). Reproducible in LiveKit's playground https://livekit.io/playground#code=60c0983cd7e07fd1179620ce
- Open playground in FireFox and click run
- Click Start Screen Share and allow screen sharing
- Click Start Video and allow camera
- Open the playground in any other browser and join the same room and click run - only screen sharing is visible
Expected behavior
Both camera and screen sharing are visible
Additional context
I was able to capture some logs from livekit-server. There are some error rows.
2022-12-19T12:59:27.741Z INFO livekit rtc/participant.go:1519 pending track added {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "track": "sid:\"TR_VSPZvNVyBAEk65\" type:VIDEO width:1791 height:1163 source:SCREEN_SHARE layers:{quality:HIGH width:1791 height:1163 bitrate:300000}", "request": "cid:\"{013a154c-52c9-4288-be6c-03cad626faf0}\" type:VIDEO width:1791 height:1163 source:SCREEN_SHARE layers:{quality:HIGH width:1791 height:1163 bitrate:300000}"}
2022-12-19T12:59:27.741Z DEBUG livekit rtc/participant.go:1524 sending track published {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "cid": "{013a154c-52c9-4288-be6c-03cad626faf0}", "trackInfo": "sid:\"TR_VSPZvNVyBAEk65\" type:VIDEO width:1791 height:1163 source:SCREEN_SHARE layers:{quality:HIGH width:1791 height:1163 bitrate:300000}"}
2022-12-19T12:59:27.884Z INFO livekit rtc/participant.go:421 received offer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:27.886Z DEBUG livekit rtc/transport.go:479 ice connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER", "state": "checking"}
2022-12-19T12:59:27.886Z DEBUG livekit rtc/transport.go:445 ice gathering state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER", "state": "gathering"}
2022-12-19T12:59:27.886Z INFO livekit rtc/participant.go:449 sending answer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:27.887Z DEBUG livekit rtc/transport.go:445 ice gathering state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER", "state": "complete"}
2022-12-19T12:59:27.887Z WARN livekit rtc/participant_signal.go:184 could not send message to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "message": "*livekit.SignalResponse_Update", "error": "no response sink"}
2022-12-19T12:59:27.887Z ERROR livekit rtc/room.go:875 could not send update to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "error": "no response sink"}
2022-12-19T12:59:27.949Z DEBUG livekit rtc/transport.go:479 ice connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER", "state": "connected"}
2022-12-19T12:59:27.949Z INFO livekit rtc/transport.go:486 selected ICE candidate pair {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER", "pair": "(local) udp4 host my.ip.0.0:57265 <-> (remote) udp4 prflx my.ip.0.0:63154 related :0"}
2022-12-19T12:59:28.004Z DEBUG livekit rtc/transport.go:492 peer connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER", "state": "connected"}
2022-12-19T12:59:28.004Z INFO livekit rtc/transport.go:1368 ice candidates {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER", "lc": ["udp4 host my.ip.0.0:57265", "tcp4 host my.ip.0.0:7881", "udp6 host my.ip.0.0:57957", "tcp6 host my.ip.0.0:7881"], "rc": ["candidate:0 1 UDP 2122252543 my.ip.0.0 57795 typ host", "candidate:3 1 TCP 2105524479 my.ip.0.0 9 typ host tcptype active", "candidate:0 1 UDP 2122252543 my.ip.0.0 63154 typ host", "candidate:3 1 TCP 2105524479 my.ip.0.0 9 typ host tcptype active", "candidate:0 2 UDP 2122252542 my.ip.0.0 58025 typ host", "candidate:3 2 TCP 2105524478 my.ip.0.0 9 typ host tcptype active"], "lc (filtered)": [], "rc (filtered)": []}
2022-12-19T12:59:28.029Z DEBUG livekit rtc/participant.go:1583 media track received {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "{e07acaab-7265-4ef5-a47d-37787784b197}", "kind": "video"}
2022-12-19T12:59:28.030Z DEBUG livekit rtc/uptrackmanager.go:307 added published track {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "trackInfo": "sid:\"TR_VSPZvNVyBAEk65\" type:VIDEO width:1791 height:1163 source:SCREEN_SHARE layers:{quality:HIGH width:1791 height:1163 bitrate:300000} mime_type:\"video/VP8\""}
2022-12-19T12:59:28.030Z DEBUG livekit rtc/mediatrack.go:221 AddReceiver {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/VP8"}
2022-12-19T12:59:28.030Z DEBUG livekit rtc/mediatrackreceiver.go:201 setup receiver {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/VP8", "priority": 0, "receivers": [{"TrackReceiver":{}}]}
2022-12-19T12:59:28.030Z DEBUG livekit sfu/streamtrackermanager.go:131 StreamTrackerManager add track {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0}
2022-12-19T12:59:28.031Z DEBUG livekit buffer/buffer.go:210 Setting feedback {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "type": "nack"}
2022-12-19T12:59:28.031Z DEBUG livekit buffer/buffer.go:210 Setting feedback {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "type": "nack"}
2022-12-19T12:59:28.031Z DEBUG livekit buffer/buffer.go:197 Setting feedback {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "type": "goog-remb"}
2022-12-19T12:59:28.031Z DEBUG livekit buffer/buffer.go:198 REMB not supported, RTCP feedback will not be generated {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0}
2022-12-19T12:59:28.031Z DEBUG livekit buffer/buffer.go:201 Setting feedback {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "type": "transport-cc"}
2022-12-19T12:59:28.031Z DEBUG livekit sfu/streamtrackermanager.go:133 StreamTrackerManager OnStatusChanged {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "status": "active"}
2022-12-19T12:59:28.032Z INFO livekit sfu/streamtrackermanager.go:393 available layers changed - layer seen {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "added": 0, "availableLayers": [0], "exemptedLayers": []}
2022-12-19T12:59:28.032Z WARN livekit rtc/participant_signal.go:184 could not send message to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "message": "*livekit.SignalResponse_Update", "error": "no response sink"}
2022-12-19T12:59:28.032Z ERROR livekit rtc/room.go:875 could not send update to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "error": "no response sink"}
2022-12-19T12:59:28.032Z DEBUG livekit rtc/room.go:720 subscribing to new track {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "publisher": "1516", "publisherID": "PA_ggjKKZiNxfnD", "trackID": "TR_VSPZvNVyBAEk65"}
2022-12-19T12:59:28.032Z DEBUG livekit rtc/uptrackmanager.go:126 subscribing participant to tracks {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "subscriber": "1517", "subscriberID": "PA_RsRViTyWgtUE", "trackIDs": ["TR_VSPZvNVyBAEk65"]}
2022-12-19T12:59:28.032Z DEBUG livekit rtc/participant.go:2011 queuing subscribe {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false}
2022-12-19T12:59:28.033Z DEBUG livekit rtc/room.go:720 subscribing to new track {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "publisher": "1516", "publisherID": "PA_ggjKKZiNxfnD", "trackID": "TR_VSPZvNVyBAEk65"}
2022-12-19T12:59:28.033Z DEBUG livekit rtc/uptrackmanager.go:126 subscribing participant to tracks {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "subscriber": "1515", "subscriberID": "PA_bFVTEPfcR5g4", "trackIDs": ["TR_VSPZvNVyBAEk65"]}
2022-12-19T12:59:28.033Z DEBUG livekit rtc/participant.go:2011 queuing subscribe {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false}
2022-12-19T12:59:28.033Z WARN livekit rtc/participant_signal.go:184 could not send message to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "message": "*livekit.SignalResponse_Update", "error": "no response sink"}
2022-12-19T12:59:28.033Z INFO livekit rtc/participant.go:1192 mediaTrack published {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "kind": "video", "trackID": "TR_VSPZvNVyBAEk65", "rid": "", "SSRC": 2353365818, "mime": "video/VP8"}
2022-12-19T12:59:28.033Z DEBUG livekit rtc/participant.go:1023 sending subscription permission update {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "publisherID": "PA_ggjKKZiNxfnD", "trackID": "TR_VSPZvNVyBAEk65", "allowed": true}
2022-12-19T12:59:28.033Z INFO livekit rtc/participant.go:912 added subscribedTrack {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "publisherID": "PA_ggjKKZiNxfnD", "publisherIdentity": "1516", "trackID": "TR_VSPZvNVyBAEk65"}
2022-12-19T12:59:28.034Z INFO livekit rtc/participant.go:912 added subscribedTrack {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "publisherID": "PA_ggjKKZiNxfnD", "publisherIdentity": "1516", "trackID": "TR_VSPZvNVyBAEk65"}
2022-12-19T12:59:28.034Z WARN livekit rtc/participant_signal.go:184 could not send message to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "message": "*livekit.SignalResponse_ConnectionQuality", "error": "no response sink"}
2022-12-19T12:59:28.034Z DEBUG livekit rtc/participant.go:1023 sending subscription permission update {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "publisherID": "PA_ggjKKZiNxfnD", "trackID": "TR_VSPZvNVyBAEk65", "allowed": true}
2022-12-19T12:59:28.034Z WARN livekit rtc/participant_signal.go:184 could not send message to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "message": "*livekit.SignalResponse_SubscriptionPermissionUpdate", "error": "no response sink"}
2022-12-19T12:59:28.034Z ERROR livekit rtc/participant.go:1034 could not send subscription permission update {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "error": "no response sink"}
2022-12-19T12:59:28.061Z DEBUG livekit rtc/transport.go:527 lossy data channel open {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:28.061Z DEBUG livekit rtc/transport.go:514 reliable data channel open {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:28.184Z INFO livekit rtc/transport.go:1458 skipping negotiation, trying again later {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER"}
2022-12-19T12:59:28.184Z INFO livekit rtc/participant.go:1170 sending offer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "transport": "SUBSCRIBER"}
2022-12-19T12:59:28.262Z INFO livekit rtc/signalhandler.go:61 updated subscribed track settings {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "settings": "track_sids:\"TR_VSPZvNVyBAEk65\" disabled:true quality:HIGH"}
2022-12-19T12:59:28.262Z DEBUG livekit rtc/subscribedtrack.go:136 updating video layer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false}
2022-12-19T12:59:28.262Z INFO livekit sfu/forwarder.go:333 setting max spatial layer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "layer": 0}
2022-12-19T12:59:28.262Z INFO livekit rtc/dynacastquality.go:61 setting subscriber max quality {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/VP8", "subscriberID": "PA_RsRViTyWgtUE", "quality": "HIGH"}
2022-12-19T12:59:28.262Z INFO livekit rtc/dynacastquality.go:127 notifying quality change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/VP8", "maxSubscriberQuality": {"PA_RsRViTyWgtUE":2}, "maxSubscriberNodeQuality": {}, "maxSubscribedQuality": "HIGH", "force": false}
2022-12-19T12:59:28.262Z INFO livekit rtc/dynacastmanager.go:177 processing quality change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "force": false, "committedMaxSubscribedQuality": {}, "maxSubscribedQuality": {"video/VP8":2}}
2022-12-19T12:59:28.263Z INFO livekit rtc/dynacastmanager.go:228 committing quality change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "force": false, "committedMaxSubscribedQuality": {}, "maxSubscribedQuality": {"video/VP8":2}}
2022-12-19T12:59:28.263Z INFO livekit rtc/dynacastmanager.go:281 subscribedMaxQualityChange {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "subscribedCodecs": [{"codec":"video/VP8","qualities":[{"enabled":true},{"quality":1,"enabled":true},{"quality":2,"enabled":true}]}], "maxSubscribedQualities": [{"CodecMime":"video/VP8","Quality":2}]}
2022-12-19T12:59:28.263Z INFO livekit rtc/participant.go:1447 sending max subscribed quality {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "qualities": [{"codec":"vp8","qualities":[{"enabled":true},{"quality":1,"enabled":true},{"quality":2,"enabled":true}]}], "max": [{"CodecMime":"video/VP8","Quality":2}]}
2022-12-19T12:59:28.263Z INFO livekit rtc/signalhandler.go:61 updated subscribed track settings {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "settings": "track_sids:\"TR_VSPZvNVyBAEk65\" quality:HIGH"}
2022-12-19T12:59:28.270Z INFO livekit rtc/participant.go:435 received answer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "transport": "SUBSCRIBER"}
2022-12-19T12:59:28.271Z DEBUG livekit sfu/downtrack.go:304 DownTrack.Bind {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "codecs": [{"MimeType":"video/VP8","ClockRate":90000,"Channels":0,"SDPFmtpLine":"max-fs=12288;max-fr=60","RTCPFeedback":[{"Type":"nack","Parameter":""},{"Type":"nack","Parameter":"pli"},{"Type":"ccm","Parameter":"fir"},{"Type":"goog-remb","Parameter":""},{"Type":"transport-cc","Parameter":""}],"PayloadType":120}], "matchCodec": {"MimeType":"video/VP8","ClockRate":90000,"Channels":0,"SDPFmtpLine":"","RTCPFeedback":[{"Type":"goog-remb","Parameter":""},{"Type":"ccm","Parameter":"fir"},{"Type":"nack","Parameter":""},{"Type":"nack","Parameter":"pli"}],"PayloadType":96}, "ssrc": 3615986145}
2022-12-19T12:59:28.271Z DEBUG livekit sfu/downtrack.go:330 downtrack bound {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false}
2022-12-19T12:59:28.271Z INFO livekit sfu/forwarder.go:1276 stream allocation: optimal {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "allocation": "VideoAllocation{state: AWAITING_MEASUREMENT, change: RESUMING, bw: 0, del: 0, avail: [0], exempt: [], rates: [[0 0 0 0] [0 0 0 0] [0 0 0 0]], target: VideoLayer{s: 0, t: 3}, dist: 0}"}
2022-12-19T12:59:28.272Z DEBUG livekit sfu/streamallocator.go:981 streamed tracks changed {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "transport": "SUBSCRIBER", "trackID": "TR_VSPZvNVyBAEk65", "state": "active"}
2022-12-19T12:59:28.272Z DEBUG livekit sfu/downtrack.go:433 sending PLI for layer lock {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "generation": 1, "layer": 0}
2022-12-19T12:59:28.272Z DEBUG livekit buffer/buffer.go:339 send pli {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 2353365818, "force": false}
2022-12-19T12:59:28.362Z DEBUG livekit rtc/subscribedtrack.go:136 updating video layer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false}
2022-12-19T12:59:28.363Z INFO livekit rtc/signalhandler.go:61 updated subscribed track settings {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "settings": "track_sids:\"TR_VSPZvNVyBAEk65\" width:914 height:639"}
2022-12-19T12:59:28.464Z DEBUG livekit rtc/subscribedtrack.go:136 updating video layer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false}
2022-12-19T12:59:28.472Z DEBUG livekit sfu/downtrack.go:433 sending PLI for layer lock {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "generation": 1, "layer": 0}
2022-12-19T12:59:28.672Z DEBUG livekit sfu/downtrack.go:433 sending PLI for layer lock {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "generation": 1, "layer": 0}
2022-12-19T12:59:28.872Z DEBUG livekit sfu/downtrack.go:433 sending PLI for layer lock {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "generation": 1, "layer": 0}
2022-12-19T12:59:28.872Z DEBUG livekit buffer/buffer.go:339 send pli {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 2353365818, "force": false}
2022-12-19T12:59:29.072Z DEBUG livekit sfu/downtrack.go:433 sending PLI for layer lock {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "generation": 1, "layer": 0}
2022-12-19T12:59:29.202Z INFO livekit sfu/forwarder.go:1466 locking to target layer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "current": "VideoLayer{s: -1, t: -1}", "target": "VideoLayer{s: 0, t: 3}"}
2022-12-19T12:59:29.202Z INFO livekit rtc/dynacastquality.go:61 setting subscriber max quality {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/VP8", "subscriberID": "PA_RsRViTyWgtUE", "quality": "HIGH"}
2022-12-19T12:59:29.202Z DEBUG livekit sfu/downtrack.go:535 forwarding key frame {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "layer": 0}
2022-12-19T12:59:30.382Z DEBUG livekit sfu/downtrack.go:535 forwarding key frame {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "layer": 0}
2022-12-19T12:59:31.292Z DEBUG livekit buffer/fps.go:159 frame rate calculated {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/vp8", "layer": 0, "rate": [5.8694057,0,0,0]}
2022-12-19T12:59:31.292Z DEBUG livekit rtc/subscribedtrack.go:136 updating video layer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false}
2022-12-19T12:59:31.292Z DEBUG livekit rtc/subscribedtrack.go:136 updating video layer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false}
2022-12-19T12:59:32.032Z INFO livekit sfu/forwarder.go:1276 stream allocation: optimal {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1517", "pID": "PA_RsRViTyWgtUE", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "allocation": "VideoAllocation{state: OPTIMAL, change: NONE, bw: 620071, del: 620071, avail: [0], exempt: [], rates: [[620071 0 0 0] [0 0 0 0] [0 0 0 0]], target: VideoLayer{s: 0, t: 0}, dist: 0}"}
2022-12-19T12:59:38.031Z INFO livekit rtc/dynacastquality.go:127 notifying quality change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/VP8", "maxSubscriberQuality": {"PA_RsRViTyWgtUE":2}, "maxSubscriberNodeQuality": {}, "maxSubscribedQuality": "HIGH", "force": true}
2022-12-19T12:59:38.031Z INFO livekit rtc/dynacastmanager.go:177 processing quality change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "force": false, "committedMaxSubscribedQuality": {"video/VP8":2}, "maxSubscribedQuality": {"video/VP8":2}}
2022-12-19T12:59:39.967Z DEBUG livekit rtc/signalhandler.go:24 add track request {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "{2ee04a7b-c411-49d7-b0a9-8a44bf433397}"}
2022-12-19T12:59:39.968Z INFO livekit rtc/participant.go:1519 pending track added {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VCLoF87h6RJT5i", "track": "sid:\"TR_VCLoF87h6RJT5i\" type:VIDEO width:1280 height:720 source:CAMERA layers:{width:320 height:180 bitrate:120000} layers:{quality:MEDIUM width:640 height:360 bitrate:300000} layers:{quality:HIGH width:1280 height:720 bitrate:1700000}", "request": "cid:\"{2ee04a7b-c411-49d7-b0a9-8a44bf433397}\" type:VIDEO width:1280 height:720 source:CAMERA layers:{width:320 height:180 bitrate:120000} layers:{quality:MEDIUM width:640 height:360 bitrate:300000} layers:{quality:HIGH width:1280 height:720 bitrate:1700000}"}
2022-12-19T12:59:39.968Z DEBUG livekit rtc/participant.go:1524 sending track published {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "cid": "{2ee04a7b-c411-49d7-b0a9-8a44bf433397}", "trackInfo": "sid:\"TR_VCLoF87h6RJT5i\" type:VIDEO width:1280 height:720 source:CAMERA layers:{width:320 height:180 bitrate:120000} layers:{quality:MEDIUM width:640 height:360 bitrate:300000} layers:{quality:HIGH width:1280 height:720 bitrate:1700000}"}
2022-12-19T12:59:40.102Z INFO livekit rtc/participant.go:421 received offer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:40.104Z INFO livekit rtc/participant.go:449 sending answer {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:40.167Z ERROR livekit.pc logger/logadapter.go:108 Incoming unhandled RTP ssrc(541364175), OnTrack will not be fired. stream id RTP Extensions required for Simulcast {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:40.172Z ERROR livekit.pc logger/logadapter.go:108 Incoming unhandled RTP ssrc(2367973597), OnTrack will not be fired. stream id RTP Extensions required for Simulcast {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:40.718Z ERROR livekit.pc logger/logadapter.go:108 Incoming unhandled RTP ssrc(1544465069), OnTrack will not be fired. stream id RTP Extensions required for Simulcast {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:45.094Z DEBUG livekit rtc/transport.go:479 ice connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER", "state": "failed"}
2022-12-19T12:59:45.094Z DEBUG livekit rtc/transport.go:492 peer connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER", "state": "failed"}
2022-12-19T12:59:45.094Z INFO livekit rtc/transport.go:505 peer connection failed {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER"}
2022-12-19T12:59:45.094Z INFO livekit rtc/transport.go:1368 ice candidates {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER", "lc": ["udp4 host my.ip.0.0:56457", "tcp4 host my.ip.0.0:7881", "udp6 host my.ip.0.0:51114", "tcp6 host my.ip.0.0:7881"], "rc": [], "lc (filtered)": [], "rc (filtered)": []}
2022-12-19T12:59:46.370Z DEBUG livekit rtc/transport.go:479 ice connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "PUBLISHER", "state": "failed"}
2022-12-19T12:59:46.370Z DEBUG livekit rtc/transport.go:492 peer connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "PUBLISHER", "state": "failed"}
2022-12-19T12:59:46.370Z INFO livekit rtc/transport.go:505 peer connection failed {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "PUBLISHER"}
2022-12-19T12:59:46.370Z INFO livekit rtc/transportmanager.go:547 prefer TCP transport on both peer connections {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false}
2022-12-19T12:59:46.370Z INFO livekit rtc/transportmanager.go:461 setting ICE config {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "iceConfig": {"PreferSub":1,"PreferPub":1}}
2022-12-19T12:59:46.370Z INFO livekit rtc/transport.go:1368 ice candidates {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "PUBLISHER", "lc": ["tcp4 host my.ip.0.0:7881", "udp4 host my.ip.0.0:54563", "udp6 host my.ip.0.0:52133", "tcp6 host my.ip.0.0:7881"], "rc": ["candidate:0 1 UDP 2122252543 my.ip.0.0 63182 typ host", "candidate:3 1 TCP 2105524479 my.ip.0.0 9 typ host tcptype active", "candidate:0 1 UDP 2122252543 my.ip.0.0 62658 typ host", "candidate:3 1 TCP 2105524479 my.ip.0.0 9 typ host tcptype active", "candidate:0 2 UDP 2122252542 my.ip.0.0 65263 typ host", "candidate:3 2 TCP 2105524478 my.ip.0.0 9 typ host tcptype active", "", "candidate:0 1 UDP 2122252543 my.ip.0.0 58779 typ host", "candidate:3 1 TCP 2105524479 my.ip.0.0 9 typ host tcptype active", "candidate:0 1 UDP 2122252543 my.ip.0.0 54237 typ host", "candidate:3 1 TCP 2105524479 my.ip.0.0 9 typ host tcptype active"], "lc (filtered)": [], "rc (filtered)": []}
2022-12-19T13:00:01.371Z INFO livekit rtc/participant.go:1300 closing disconnected participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false}
2022-12-19T13:00:01.371Z INFO livekit rtc/participant.go:572 participant closing {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "sendLeave": true, "reason": "PEER_CONNECTION_DISCONNECTED"}
2022-12-19T13:00:01.371Z WARN livekit rtc/participant_signal.go:184 could not send message to participant {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "message": "*livekit.SignalResponse_Leave", "error": "no response sink"}
2022-12-19T13:00:01.372Z DEBUG livekit rtc/participant.go:1151 updating participant state {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "state": "DISCONNECTED"}
2022-12-19T13:00:01.372Z INFO livekit rtc/participant.go:1868 exiting publisher RTCP worker {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false}
2022-12-19T13:00:01.372Z DEBUG livekit rtc/room.go:246 participant state changed {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "state": "DISCONNECTED", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "oldState": "ACTIVE"}
2022-12-19T13:00:01.373Z INFO livekit rtc/room.go:403 closing participant for removal {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "pID": "PA_bFVTEPfcR5g4", "participant": "1515"}
2022-12-19T13:00:01.373Z INFO livekit sfu/downtrack.go:692 close down track {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "flushBlankFrame": true}
2022-12-19T13:00:01.373Z INFO livekit sfu/downtrack.go:729 rtp stats {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "direction": "downstream", "stats": ""}
2022-12-19T13:00:01.373Z INFO livekit rtc/dynacastquality.go:61 setting subscriber max quality {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VSPZvNVyBAEk65", "relayed": false, "mime": "video/VP8", "subscriberID": "PA_bFVTEPfcR5g4", "quality": "OFF"}
2022-12-19T13:00:01.374Z DEBUG livekit rtc/transport.go:1214 leaving events processor {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "PUBLISHER"}
2022-12-19T13:00:01.374Z DEBUG livekit rtc/mediatracksubscriptions.go:407 removing PeerConnection track {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "publisher": "1516", "publisherID": "PA_ggjKKZiNxfnD", "kind": "VIDEO"}
2022-12-19T13:00:01.374Z INFO livekit rtc/participant.go:959 removed subscribedTrack {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "publisherID": "PA_ggjKKZiNxfnD", "publisherIdentity": "1516", "trackID": "TR_VSPZvNVyBAEk65", "kind": "video"}
2022-12-19T13:00:01.374Z DEBUG livekit rtc/transport.go:479 ice connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "PUBLISHER", "state": "closed"}
2022-12-19T13:00:01.374Z DEBUG livekit rtc/transport.go:492 peer connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "PUBLISHER", "state": "closed"}
2022-12-19T13:00:01.374Z DEBUG livekit rtc/transport.go:1214 leaving events processor {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER"}
2022-12-19T13:00:01.375Z DEBUG livekit rtc/transport.go:492 peer connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER", "state": "closed"}
2022-12-19T13:00:01.375Z DEBUG livekit rtc/transport.go:479 ice connection state change {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1515", "pID": "PA_bFVTEPfcR5g4", "remote": false, "transport": "SUBSCRIBER", "state": "closed"}
2022-12-19T13:00:01.397Z INFO livekit service/roommanager.go:453 RTC session finishing {"participant": "1515", "pID": "PA_bFVTEPfcR5g4", "room": "521501298", "roomID": "RM_P8HE9e7LhR8X"}
2022-12-19T13:00:11.208Z ERROR livekit supervisor/participant_supervisor.go:165 supervisor error on publication {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VCLoF87h6RJT5i", "error": "publish time out"}
2022-12-19T13:00:11.209Z INFO livekit rtc/participant.go:1993 publication failed {"room": "521501298", "roomID": "RM_P8HE9e7LhR8X", "participant": "1516", "pID": "PA_ggjKKZiNxfnD", "remote": false, "trackID": "TR_VCLoF87h6RJT5i"}