Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Enabling camera after enabling screen sharing doesn't work on Firefox #1238

Open
burzomir opened this issue Dec 19, 2022 · 10 comments
Open

Enabling camera after enabling screen sharing doesn't work on Firefox #1238

burzomir opened this issue Dec 19, 2022 · 10 comments

Comments

@burzomir
Copy link

burzomir commented Dec 19, 2022 •
edited

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

  1. Open playground in FireFox and click run
  2. Click Start Screen Share and allow screen sharing
  3. Click Start Video and allow camera
  4. 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"}
@jibon57
Copy link

jibon57 commented Dec 21, 2022 •
edited

I discussed this issue in slack before & @boks1971 made one fix but it wasn't merge. This mainly happen if you try to share webcam quickly after share screen & simulcast is on. As a work around either disable simulcast or enable webcam first then enable screen share :-D

@burzomir
Copy link
Author

@jibon57 Thanks for suggestion, but this work around won't work. We need both simulcast and flexibility to decide when to enable/disable screen sharing and camera.

@burzomir
Copy link
Author

@boks1971 How's your fix? Do you have any branch that I could try out?

@boks1971
Copy link
Contributor

@burzomir I made this Pion PR long long time ago - pion/webrtc#2107, but it did not make it into main line. At that time, I had an LiveKit server branch which @jibon57 tested out. But, I do not have that anymore. I am guessing the Pion PR may not apply now. It is quite old.

@burzomir
Copy link
Author

burzomir commented Mar 25, 2023 •
edited

@boks1971 @davidzhao I recently tried to deploy the main branch (65ad4b2) and the issue is still the same.

  1. The participant 466 successfully published screen share track TR_VSFJjnmLZK9wWd
  2. Then the participant 466 tried to publish camera track TR_VCCwxDyo9hpdBb but it fails with publish time out. On the client side he still receives successful publication but other participants cannot see his camera.

Also the problem is that when I try to publish the camera track again, even after unpublishing the screen share track, the camera no longer works, until I fully disconnect and go back into the room.

Logs 
2023-03-25T12:37:28.732Z	INFO	livekit	rtc/room.go:331	new participant joined	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "pID": "PA_oDwagMsnfUSD", "participant": "466", "protocol": 9, "options": {"AutoSubscribe":true}}
2023-03-25T12:37:28.733Z	INFO	livekit	service/rtcservice.go:258	new client WS connected	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "connID": "CO_eGarTy4CZgSr"}
2023-03-25T12:37:28.733Z	INFO	livekit	rtc/room.go:265	participant state changed	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "state": "JOINED", "participant": "466", "pID": "PA_oDwagMsnfUSD", "oldState": "JOINING"}
2023-03-25T12:37:29.843Z	INFO	livekit	rtc/transport.go:597	selected ICE candidate pair	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "transport": "SUBSCRIBER", "pair": "(local) udp4 host 91.250.106.15:57670 <-> (remote) udp4 prflx 91.90.163.34:60401 related :0"}
2023-03-25T12:37:30.005Z	INFO	livekit	rtc/room.go:265	participant state changed	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "state": "ACTIVE", "participant": "466", "pID": "PA_oDwagMsnfUSD", "oldState": "JOINED"}
2023-03-25T12:37:38.174Z	INFO	livekit	rtc/signalhandler.go:44	client leaving room	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "464", "pID": "PA_TpDeTe4b2ffZ", "remote": false}
2023-03-25T12:37:38.174Z	INFO	livekit	rtc/participant.go:628	participant closing	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "464", "pID": "PA_TpDeTe4b2ffZ", "remote": false, "sendLeave": true, "reason": "CLIENT_REQUEST_LEAVE"}
2023-03-25T12:37:38.174Z	INFO	livekit	rtc/participant_signal.go:285	closing signal connection	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "464", "pID": "PA_TpDeTe4b2ffZ", "remote": false}
2023-03-25T12:37:38.176Z	INFO	livekit	service/rtcservice.go:231	finishing WS connection	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "464", "pID": "PA_TpDeTe4b2ffZ", "remote": false, "connID": "CO_SxjB7m7GMeAN"}
2023-03-25T12:37:44.025Z	INFO	livekit	rtc/room.go:331	new participant joined	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "pID": "PA_LgTwqN9vMn6C", "participant": "467", "protocol": 9, "options": {"AutoSubscribe":true}}
2023-03-25T12:37:44.026Z	INFO	livekit	rtc/room.go:265	participant state changed	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "state": "JOINED", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "oldState": "JOINING"}
2023-03-25T12:37:44.026Z	INFO	livekit	service/rtcservice.go:258	new client WS connected	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "connID": "CO_kvNnomch25TY"}
2023-03-25T12:37:45.160Z	INFO	livekit	rtc/transport.go:597	selected ICE candidate pair	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "transport": "SUBSCRIBER", "pair": "(local) udp4 host 91.250.106.15:59216 <-> (remote) udp4 prflx 91.90.163.34:63928 related :0"}
2023-03-25T12:37:45.298Z	INFO	livekit	rtc/participant.go:1486	pending track added	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "track": "sid:\"TR_VCT4eVdDbuVwLY\"  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:\"eaccfbcf-bf18-4232-8d01-2b972c26c547\"  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}"}
2023-03-25T12:37:45.376Z	INFO	livekit	rtc/room.go:265	participant state changed	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "state": "ACTIVE", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "oldState": "JOINED"}
2023-03-25T12:37:45.527Z	INFO	livekit	rtc/transport.go:597	selected ICE candidate pair	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "transport": "PUBLISHER", "pair": "(local) udp4 host 91.250.106.15:51554 <-> (remote) udp4 srflx 91.90.163.34:59411 related 192.168.50.44:59411"}
2023-03-25T12:37:45.641Z	INFO	livekit	rtc/participant.go:1146	mediaTrack published	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "kind": "video", "trackID": "TR_VCT4eVdDbuVwLY", "rid": "q", "SSRC": 1597873360, "mime": "video/VP8"}
2023-03-25T12:37:45.642Z	INFO	livekit	rtc/subscriptionmanager.go:133	subscribing to track	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY"}
2023-03-25T12:37:45.649Z	INFO	livekit	rtc/participant.go:1146	mediaTrack published	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "kind": "video", "trackID": "TR_VCT4eVdDbuVwLY", "rid": "h", "SSRC": 1232099175, "mime": "video/VP8"}
2023-03-25T12:37:45.856Z	INFO	livekit	rtc/subscribedtrack.go:180	updated subscribed track enabled	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "publisherID": "PA_LgTwqN9vMn6C", "publisher": "467", "enabled": false}
2023-03-25T12:37:45.865Z	INFO	livekit	rtc/subscribedtrack.go:180	updated subscribed track enabled	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "publisherID": "PA_LgTwqN9vMn6C", "publisher": "467", "enabled": true}
2023-03-25T12:37:45.865Z	INFO	livekit	rtc/dynacastmanager.go:227	committing quality change	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "relayed": false, "force": false, "committedMaxSubscribedQuality": {}, "maxSubscribedQuality": {"video/VP8":2}}
2023-03-25T12:37:45.865Z	INFO	livekit	rtc/participant.go:1416	sending max subscribed quality	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "qualities": [{"codec":"vp8","qualities":[{"enabled":true},{"quality":1,"enabled":true},{"quality":2,"enabled":true}]}], "max": [{"CodecMime":"video/VP8","Quality":2}]}
2023-03-25T12:37:45.904Z	INFO	livekit	sfu/forwarder.go:1526	upgrading layer	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "relayed": false, "current": "VideoLayer{s: -1, t: -1}", "target": "VideoLayer{s: 1, t: 3}", "max": "VideoLayer{s: 2, t: 3}", "layer": 1, "req": 1, "maxPublished": 1, "feed": 1232099175}
2023-03-25T12:37:45.911Z	INFO	livekit	rtc/participant.go:1146	mediaTrack published	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "kind": "video", "trackID": "TR_VCT4eVdDbuVwLY", "rid": "f", "SSRC": 114951112, "mime": "video/VP8"}
2023-03-25T12:37:45.911Z	INFO	livekit	sfu/forwarder.go:1526	upgrading layer	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "relayed": false, "current": "VideoLayer{s: 1, t: 3}", "target": "VideoLayer{s: 2, t: 3}", "max": "VideoLayer{s: 2, t: 3}", "layer": 2, "req": 2, "maxPublished": 2, "feed": 114951112}
2023-03-25T12:37:45.911Z	INFO	livekit	sfu/forwarder.go:1568	reached max layer	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "relayed": false, "current": "VideoLayer{s: 2, t: 3}", "target": "VideoLayer{s: 2, t: 3}", "max": "VideoLayer{s: 2, t: 3}", "layer": 2, "req": 2, "maxPublished": 2, "feed": 114951112}
2023-03-25T12:38:18.834Z	INFO	livekit	rtc/participant.go:1486	pending track added	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "track": "sid:\"TR_VSFJjnmLZK9wWd\"  type:VIDEO  width:1791  height:1163  source:SCREEN_SHARE  layers:{quality:HIGH  width:1791  height:1163  bitrate:300000}", "request": "cid:\"{4468b7ee-7f99-448c-bbcf-f7d428afbc91}\"  type:VIDEO  width:1791  height:1163  source:SCREEN_SHARE  layers:{quality:HIGH  width:1791  height:1163  bitrate:300000}"}
2023-03-25T12:38:19.072Z	INFO	livekit	rtc/transport.go:597	selected ICE candidate pair	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "transport": "PUBLISHER", "pair": "(local) udp4 host 91.250.106.15:54347 <-> (remote) udp4 prflx 91.90.163.34:55636 related :0"}
2023-03-25T12:38:19.178Z	INFO	livekit	rtc/participant.go:1146	mediaTrack published	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "kind": "video", "trackID": "TR_VSFJjnmLZK9wWd", "rid": "", "SSRC": 714812471, "mime": "video/VP8"}
2023-03-25T12:38:19.178Z	INFO	livekit	rtc/subscriptionmanager.go:133	subscribing to track	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd"}
2023-03-25T12:38:19.396Z	INFO	livekit	rtc/subscribedtrack.go:180	updated subscribed track enabled	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "publisherID": "PA_oDwagMsnfUSD", "publisher": "466", "enabled": false}
2023-03-25T12:38:19.400Z	INFO	livekit	rtc/subscribedtrack.go:180	updated subscribed track enabled	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "publisherID": "PA_oDwagMsnfUSD", "publisher": "466", "enabled": true}
2023-03-25T12:38:19.400Z	INFO	livekit	rtc/dynacastmanager.go:227	committing quality change	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "relayed": false, "force": false, "committedMaxSubscribedQuality": {}, "maxSubscribedQuality": {"video/VP8":2}}
2023-03-25T12:38:19.400Z	INFO	livekit	rtc/participant.go:1416	sending max subscribed quality	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "qualities": [{"codec":"vp8","qualities":[{"enabled":true},{"quality":1,"enabled":true},{"quality":2,"enabled":true}]}], "max": [{"CodecMime":"video/VP8","Quality":2}]}
2023-03-25T12:38:20.206Z	INFO	livekit	sfu/forwarder.go:1526	upgrading layer	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "relayed": false, "current": "VideoLayer{s: -1, t: -1}", "target": "VideoLayer{s: 0, t: 3}", "max": "VideoLayer{s: 0, t: 3}", "layer": 0, "req": 0, "maxPublished": 0, "feed": 714812471}
2023-03-25T12:38:20.206Z	INFO	livekit	sfu/forwarder.go:1568	reached max layer	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "relayed": false, "current": "VideoLayer{s: 0, t: -1}", "target": "VideoLayer{s: 0, t: 3}", "max": "VideoLayer{s: 0, t: 3}", "layer": 0, "req": 0, "maxPublished": 0, "feed": 714812471}
2023-03-25T12:38:29.006Z	INFO	livekit	rtc/participant.go:1486	pending track added	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCCwxDyo9hpdBb", "track": "sid:\"TR_VCCwxDyo9hpdBb\"  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:\"{945b4841-9b8c-4d09-947d-7cf0adc92a5e}\"  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}"}
2023-03-25T12:38:29.214Z	ERROR	livekit.pc	logger/logadapter.go:108	Incoming unhandled RTP ssrc(2682674836), OnTrack will not be fired. stream id RTP Extensions required for Simulcast	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "transport": "PUBLISHER"}
github.com/livekit/livekit-server/pkg/logger.(*logAdapter).Errorf
	/workspace/pkg/logger/logadapter.go:108
github.com/pion/webrtc/v3.(*PeerConnection).undeclaredRTPMediaProcessor.func1
	/go/pkg/mod/github.com/pion/webrtc/v3@v3.1.58/peerconnection.go:1660
2023-03-25T12:38:29.215Z	ERROR	livekit.pc	logger/logadapter.go:108	Incoming unhandled RTP ssrc(793246391), OnTrack will not be fired. stream id RTP Extensions required for Simulcast	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "transport": "PUBLISHER"}
github.com/livekit/livekit-server/pkg/logger.(*logAdapter).Errorf
	/workspace/pkg/logger/logadapter.go:108
github.com/pion/webrtc/v3.(*PeerConnection).undeclaredRTPMediaProcessor.func1
	/go/pkg/mod/github.com/pion/webrtc/v3@v3.1.58/peerconnection.go:1660
2023-03-25T12:38:59.732Z	ERROR	livekit	supervisor/participant_supervisor.go:142	supervisor error on publication	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCCwxDyo9hpdBb", "error": "publish time out"}
github.com/livekit/livekit-server/pkg/rtc/supervisor.(*ParticipantSupervisor).checkPublications
	/workspace/pkg/rtc/supervisor/participant_supervisor.go:142
github.com/livekit/livekit-server/pkg/rtc/supervisor.(*ParticipantSupervisor).checkState
	/workspace/pkg/rtc/supervisor/participant_supervisor.go:131
2023-03-25T12:39:20.597Z	INFO	livekit	rtc/subscribedtrack.go:180	updated subscribed track enabled	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "publisherID": "PA_oDwagMsnfUSD", "publisher": "466", "enabled": false}
2023-03-25T12:39:25.697Z	INFO	livekit	rtc/dynacastmanager.go:227	committing quality change	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "relayed": false, "force": true, "committedMaxSubscribedQuality": {"video/VP8":2}, "maxSubscribedQuality": {"video/VP8":3}}
2023-03-25T12:39:25.698Z	INFO	livekit	rtc/participant.go:1416	sending max subscribed quality	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VSFJjnmLZK9wWd", "qualities": [{"codec":"vp8","qualities":[{},{"quality":1},{"quality":2}]}], "max": [{"CodecMime":"video/VP8","Quality":3}]}
2023-03-25T12:39:26.649Z	ERROR	livekit.pc	logger/logadapter.go:108	Incoming unhandled RTP ssrc(345282385), OnTrack will not be fired. stream id RTP Extensions required for Simulcast	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "transport": "PUBLISHER"}
github.com/livekit/livekit-server/pkg/logger.(*logAdapter).Errorf
	/workspace/pkg/logger/logadapter.go:108
github.com/pion/webrtc/v3.(*PeerConnection).undeclaredRTPMediaProcessor.func1
	/go/pkg/mod/github.com/pion/webrtc/v3@v3.1.58/peerconnection.go:1660
2023-03-25T12:39:28.995Z	INFO	livekit	rtc/subscribedtrack.go:180	updated subscribed track enabled	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "466", "pID": "PA_oDwagMsnfUSD", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "publisherID": "PA_LgTwqN9vMn6C", "publisher": "467", "enabled": false}
2023-03-25T12:39:34.097Z	INFO	livekit	rtc/dynacastmanager.go:227	committing quality change	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "relayed": false, "force": true, "committedMaxSubscribedQuality": {"video/VP8":2}, "maxSubscribedQuality": {"video/VP8":3}}
2023-03-25T12:39:34.097Z	INFO	livekit	rtc/participant.go:1416	sending max subscribed quality	{"room": "521501298", "roomID": "RM_mftVSjXFDTjA", "participant": "467", "pID": "PA_LgTwqN9vMn6C", "remote": false, "trackID": "TR_VCT4eVdDbuVwLY", "qualities": [{"codec":"vp8","qualities":[{},{"quality":1},{"quality":2}]}], "max": [{"CodecMime":"video/VP8","Quality":3}]}

@burzomir
Copy link
Author

burzomir commented Apr 1, 2023 •
edited

@davidzhao

I tried to set rtc.reconnect_on_publication_error to true for livekit-server. But when a camera publication fails, the server forces the client to reconnect. Also, the server will continue to force reconnection around every 30 seconds from this moment. Then tracks are republished but in wrong order and the issue occurs again.

So far the only good workaround I found is to:

  1. Detect when camera is published after screen share,
  2. Store both tracks,
  3. Call room.disconnect(false) // disconnect but do not stop tracks,
  4. Call `room.connect(url, token),
  5. Publish track again in order that doesn't cause the issue:
await room.localParticipant.publishTrack(cameraTrack)
await room.localParticipant.publishTrack(screenShareTrack)

@davidzhao
Copy link
Member

Additional details in Slack thread: https://livekit-users.slack.com/archives/C01KVTJH6BX/p1680599433828099?thread_ts=1676902391.766349&cid=C01KVTJH6BX

@burzomir burzomir mentioned this issue Apr 12, 2023
Closed
@burzomir
Copy link
Author

@cnderrauber Could this one be fixed by #1621 too?

@boks1971
Copy link
Contributor

Doubt it. That is a different issue as far as I remember.

@davidzhao
Copy link
Member

This should be fixed upstream by pion/webrtc#2444

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@davidzhao @jibon57 @boks1971 @burzomir