Issue with Unpublishing Tracks – Remote Participants Still See Tracks of unpublished user

[alirezafarahaniii]

Describe the bug

When the number of participants is small (e.g., 10 or fewer), if a participant unpublishes all their tracks, their videoTrack and audioTrack become null in the room.remoteParticipants list of other users. This works as expected, and new participants who join later cannot subscribe to the unpublished participant.

However, when the number of participants exceeds 20-30-40, …, unpublishing does not work as expected. The participant who unpublishes still has video and audio tracks in the room.remoteParticipants object for other users.

The unsubscribed signal does trigger for existing participants in the meeting, and they correctly unsubscribe from the unpublished participant.
However, when a new participant joins the room, they are still able to subscribe to the unpublished participant’s tracks, even though the tracks were unpublished earlier.
Why does this issue happen only when the participant count is high?
Why do existing participants correctly receive the unsubscribe event, but new participants can still subscribe to the unpublished user?
Is there a way to force all remote participants, including new joiners, to update and recognize when a user has unpublished all their tracks?
Could this be related to LiveKit’s track subscription management for scalability?
What’s the best way to ensure that a new participant does not subscribe an unpublished user?

Reproduction

1- Set up a LiveKit room with a large number of participants (e.g., 20-30 or more).

2- Publish more than 20 tracks: Ensure all participants publish both video and audio tracks.

3- Create a function to unpublish users randomly in a web app server(I do that by sending websocket)

4- When a new user joins the meeting, he can see the unpublished user! but existing users get a signal that the user is unpublished!

Logs

ound for fe80::f816:3eff:fe79:4961%ens3 {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "remote": false, "transport": "PUBLISHER"}
2025-03-06T09:58:37.673Z INFO livekit.transport rtc/transport.go:546 ice reconnected or switched pair {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "remote": false, "transport": "PUBLISHER", "existingPair": {"localProtocol": "udp", "localCandidateType": "host", "localAdddress": "87.247.171.231", "localPort": 59327, "remoteProtocol": "udp", "remoteCandidateType": "relay", "remoteAdddress": "87.247.171....", "remotePort": 46950, "relatedAdddress": "2.177.85...", "relatedPort": 49419}, "newPair": {"localProtocol": "udp", "localCandidateType": "host", "localAdddress": "87.247.171.231", "localPort": 59327, "remoteProtocol": "udp", "remoteCandidateType": "prflx", "remoteAdddress": "2.177.85...", "remotePort": 43315}}
2025-03-06T09:58:38.540Z INFO livekit rtc/room.go:472 participant active {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "remote": false, "publisherCandidates": ["[local][selected:2][trickle] udp4 host 87.247.171.231:59327 (resolved: 87.247.171.231:59327)", "[local][trickle] tcp4 host 87.247.171.231:7881 (resolved: 87.247.171.231:7881)", "[local][trickle] udp4 host 87.247.171.231:50681 (resolved: 87.247.171.231:50681)", "[remote][trickle] udp4 host 172.21.224.1:49418", "[remote][trickle] udp4 host 192.168.7.58:49419", "[remote][trickle] udp4 srflx 2.177.85...:49419 related 192.168.7.58:49419", "[remote][selected:1][trickle] udp4 relay 87.247.171....:46950 related 2.177.85...:49419", "[remote][trickle] tcp4 host 172.21.224.1:9", "[remote][trickle] tcp4 host 192.168.7.58:9", "[remote][selected:2][trickle] udp4 prflx 2.177.85...:43315"], "subscriberCandidates": ["[local][selected:1][trickle] udp4 host 87.247.171.231:51179 (resolved: 87.247.171.231:51179)", "[local][trickle] tcp4 host 87.247.171.231:7881 (resolved: 87.247.171.231:7881)", "[local][trickle] udp4 host 87.247.171.231:54715 (resolved: 87.247.171.231:54715)", "[remote][trickle] udp4 host 172.21.224.1:49416", "[remote][trickle] udp4 host 192.168.7.58:49417", "[remote][trickle] udp4 srflx 2.177.85...:49417 related 192.168.7.58:49417", "[remote][trickle] udp4 relay 87.247.171....:48260 related 2.177.85...:49417", "[remote][selected:1][trickle] udp4 prflx 2.177.85...:39861"], "connectionType": "udp"}
2025-03-06T09:58:38.554Z INFO livekit.webhook webhook/url_notifier.go:124 sent webhook {"event": "participant_joined", "id": "EV_PireUP7AVSyF", "webhookTime": 1741255118, "room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "url": "https://openvidu.shooka.com/shooka/livekit/webhook", "queueDuration": "91.797µs", "sendDuration": "14.150758ms"}
2025-03-06T09:58:38.700Z INFO livekit.pub rtc/participant.go:1797 mediaTrack published {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "remote": false, "kind": "video", "trackID": "TR_VChkjapSVTvm6p", "webrtcTrackID": "a22cbaa6-c4f5-4fdb-b547-7623c65ecf71", "rid": "", "SSRC": 4066708971, "mime": "video/VP8", "trackInfo": {"sid": "TR_VChkjapSVTvm6p", "type": "VIDEO", "width": 320, "height": 240, "source": "CAMERA", "layers": [{"quality": "HIGH", "width": 320, "height": 240, "bitrate": 150000, "ssrc": 4066708971}], "mimeType": "video/VP8", "mid": "1", "codecs": [{"mimeType": "video/VP8", "mid": "1", "cid": "a22cbaa6-c4f5-4fdb-b547-7623c65ecf71", "layers": [{"quality": "HIGH", "width": 320, "height": 240, "bitrate": 150000, "ssrc": 4066708971}]}], "encryption": "NONE", "stream": "camera", "version": {"unixMicro": 1741255118700116}}, "fromSdp": true}
2025-03-06T09:58:38.711Z INFO livekit.webhook webhook/url_notifier.go:124 sent webhook {"event": "track_published", "id": "EV_ebugdJ3uyKxj", "webhookTime": 1741255118, "room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "url": "https://openvidu.shooka.com/shooka/livekit/webhook", "queueDuration": "50.435µs", "sendDuration": "8.890406ms"}
2025-03-06T09:58:38.756Z INFO livekit.pub rtc/participant.go:1797 mediaTrack published {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "remote": false, "kind": "audio", "trackID": "TR_AMwGbinhLPvBSC", "webrtcTrackID": "21e11878-befb-45b8-8a80-849d046ae2e9", "rid": "", "SSRC": 1596252099, "mime": "audio/opus", "trackInfo": {"sid": "TR_AMwGbinhLPvBSC", "type": "AUDIO", "source": "MICROPHONE", "mimeType": "audio/opus", "mid": "2", "disableRed": true, "encryption": "NONE", "stream": "camera", "version": {"unixMicro": 1741255118756486}}, "fromSdp": true}
2025-03-06T09:58:38.768Z INFO livekit.webhook webhook/url_notifier.go:124 sent webhook {"event": "track_published", "id": "EV_abUttAVgHLnL", "webhookTime": 1741255118, "room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "شششش (مهمان)&&14178558", "pID": "PA_aZZyJENnN3z9", "url": "https://openvidu.shooka.com/shooka/livekit/webhook", "queueDuration": "16.996µs", "sendDuration": "11.099791ms"}
2025-03-06T09:58:58.869Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "fNjzoII (مهمان)&&17483322", "pID": "PA_bM6FsNDVfpbd", "remote": false, "trackID": "TR_VCyqwq4vzj4NX9", "error": "track cannot be found"}
2025-03-06T09:58:58.912Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "سپهر (مهمان)&&12038593", "pID": "PA_xXpZpyU7eknM", "remote": false, "trackID": "TR_VCyqwq4vzj4NX9", "error": "track cannot be found"}
2025-03-06T09:58:59.225Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "eDebbkT (مهمان)&&14885654", "pID": "PA_KdMu37fw5swc", "remote": false, "trackID": "TR_AMTWguFwyn4AUL", "error": "track cannot be found"}
2025-03-06T09:58:59.226Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "eDebbkT (مهمان)&&14885654", "pID": "PA_KdMu37fw5swc", "remote": false, "trackID": "TR_VCyqwq4vzj4NX9", "error": "track cannot be found"}
2025-03-06T09:58:59.650Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_vbuNGMEvYj9v", "remote": false, "trackID": "TR_AMTWguFwyn4AUL", "error": "track cannot be found"}
2025-03-06T09:58:59.650Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_vbuNGMEvYj9v", "remote": false, "trackID": "TR_VCyqwq4vzj4NX9", "error": "track cannot be found"}
2025-03-06T09:58:59.764Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "راهبر شوک&&2", "pID": "PA_H8VPMoFebuiG", "remote": false, "trackID": "TR_VCyqwq4vzj4NX9", "error": "track cannot be found"}
2025-03-06T09:58:59.764Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "راهبر شوک&&2", "pID": "PA_H8VPMoFebuiG", "remote": false, "trackID": "TR_AMTWguFwyn4AUL", "error": "track cannot be found"}
2025-03-06T09:59:00.027Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "9NuJVGd (مهمان)&&14722836", "pID": "PA_zgSs6fJPXP4u", "remote": false, "trackID": "TR_AMTWguFwyn4AUL", "error": "track cannot be found"}
2025-03-06T09:59:00.027Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "9NuJVGd (مهمان)&&14722836", "pID": "PA_zgSs6fJPXP4u", "remote": false, "trackID": "TR_VCyqwq4vzj4NX9", "error": "track cannot be found"}
2025-03-06T09:59:01.868Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "fNjzoII (مهمان)&&17483322", "pID": "PA_bM6FsNDVfpbd", "remote": false, "trackID": "TR_AMTWguFwyn4AUL", "error": "track cannot be found"}
2025-03-06T09:59:01.912Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "سپهر (مهمان)&&12038593", "pID": "PA_xXpZpyU7eknM", "remote": false, "trackID": "TR_AMTWguFwyn4AUL", "error": "track cannot be found"}
2025-03-06T09:59:05.650Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_vbuNGMEvYj9v", "remote": false, "trackID": "TR_VCiKzGeWTVoib2", "error": "track cannot be found"}
2025-03-06T09:59:05.764Z INFO livekit.sub rtc/subscriptionmanager.go:386 unsubscribing from track after notFoundTimeout {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "راهبر شوک&&2", "pID": "PA_H8VPMoFebuiG", "remote": false, "trackID": "TR_VCiKzGeWTVoib2", "error": "track cannot be found"}

System Info

We use  "livekit-client": "2.9.5" and livekit single node 3.1.0

Severity

blocking all usage of LiveKit

Additional Information

No response

[lukasIO]

thanks for the report, this is rather unexpected, in order to investigate this further please post debug logs from

• user who's track is being unpublished
• new user that joins and still sees the unpublished track
• server logs

[alirezafarahaniii]

I have updated the issue and added server logs

[alirezafarahaniii]

use this code for unpublish tracks :
async unPublishTrack() {
if (this.videoLocalTrack) {
await this.room.localParticipant.unpublishTrack(this.videoLocalTrack,true);
this.videoLocalTrack = null;
}
if (this.audioLocalTrack) {
await this.room.localParticipant.unpublishTrack(this.audioLocalTrack);
this.audioLocalTrack = null;
}

  this.publishStatus = false;

  // Remove from remote participants' track map
  if (this.remoteTracksMap.has(this.$store.getters.userId + '')) {
    this.remoteTracksMap.delete(this.$store.getters.userId + '');
  }
},

[alirezafarahaniii]

I will provide two parts of logs. The first one is for when I unpublish a user and the Seconf one is when new user join and can see the unpublished user:

Log1 Unpublish User :
2025-03-06T10:49:22.101Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 21903, "count": 11200}
2025-03-06T10:49:26.290Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 22003, "count": 11300}
2025-03-06T10:49:26.310Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1685168, "new(bps)": 1614024, "lastReceived(bps)": 1614024, "expectedUsage(bps)": 2232151, "commitThreshold(bps)": 2120543, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m18.84249588s", "numSamples": 5493, "samples": [{"value": 1749632, "sinceFirst": "0s"}, {"value": 1750632, "sinceFirst": "405.241694ms"}, {"value": 1751632, "sinceFirst": "859.810475ms"}, {"value": 1752632, "sinceFirst": "1.329352621s"}, {"value": 1785912, "sinceFirst": "1.573297791s"}, {"value": 1814200, "sinceFirst": "1.777370109s"}, {"value": 1815200, "sinceFirst": "2.25913256s"}, {"value": 1843496, "sinceFirst": "2.460012713s"}, {"value": 1610904, "sinceFirst": "2.869025344s"}, {"value": 1614024, "sinceFirst": "3.325629925s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 0.28888888888888886, "direction": "INCONCLUSIVE"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:24.096Z", "windowDuration": "2.213953474s", "packets": 805, "repeatedNacks": 122, "nackRatio": 0.1515527950310559}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:26.310Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "NONE", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1614024}
2025-03-06T10:49:26.310Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1685168, "new(bps)": 1614024, "expectedUsage(bps)": 2232151}
2025-03-06T10:49:26.520Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1614024, "new(bps)": 1615024, "lastReceived(bps)": 1615024, "expectedUsage(bps)": 2230853, "commitThreshold(bps)": 2119310, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m19.052535925s", "numSamples": 5497, "samples": [{"value": 1749632, "sinceFirst": "0s"}, {"value": 1750632, "sinceFirst": "405.241694ms"}, {"value": 1751632, "sinceFirst": "859.810475ms"}, {"value": 1752632, "sinceFirst": "1.329352621s"}, {"value": 1785912, "sinceFirst": "1.573297791s"}, {"value": 1814200, "sinceFirst": "1.777370109s"}, {"value": 1815200, "sinceFirst": "2.25913256s"}, {"value": 1843496, "sinceFirst": "2.460012713s"}, {"value": 1610904, "sinceFirst": "2.869025344s"}, {"value": 1614024, "sinceFirst": "3.325629925s"}, {"value": 1614024, "sinceFirst": "3.851984102s"}, {"value": 1615024, "sinceFirst": "3.997231601s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 0.015384615384615385, "direction": "UPWARD"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:24.096Z", "windowDuration": "2.423999023s", "packets": 888, "repeatedNacks": 126, "nackRatio": 0.14189189189189189}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:26.520Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1615024}
2025-03-06T10:49:26.520Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1614024, "new(bps)": 1615024, "expectedUsage(bps)": 2230853}
2025-03-06T10:49:27.175Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "NONE", "reason": "NONE", "committedChannelCapacity": 1615024}
2025-03-06T10:49:29.242Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1615024, "new(bps)": 1650416, "lastReceived(bps)": 1650416, "expectedUsage(bps)": 2140283, "commitThreshold(bps)": 2033268, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m21.774658395s", "numSamples": 5561, "samples": [{"value": 1843496, "sinceFirst": "0s"}, {"value": 1610904, "sinceFirst": "409.012631ms"}, {"value": 1614024, "sinceFirst": "865.617212ms"}, {"value": 1614024, "sinceFirst": "1.391971389s"}, {"value": 1615024, "sinceFirst": "1.537218888s"}, {"value": 1615024, "sinceFirst": "2.094402864s"}, {"value": 1616024, "sinceFirst": "2.191916796s"}, {"value": 1617024, "sinceFirst": "2.656089864s"}, {"value": 1617024, "sinceFirst": "3.171853053s"}, {"value": 1617024, "sinceFirst": "3.792787353s"}, {"value": 1618024, "sinceFirst": "4.000778503s"}, {"value": 1650416, "sinceFirst": "4.259316967s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 0.639344262295082, "direction": "UPWARD"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:27.175Z", "windowDuration": "2.067485195s", "packets": 748, "repeatedNacks": 188, "nackRatio": 0.25133689839572193}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:29.242Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "NONE", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1650416}
2025-03-06T10:49:29.242Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1615024, "new(bps)": 1650416, "expectedUsage(bps)": 2140283}
2025-03-06T10:49:29.653Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1650416, "new(bps)": 1651416, "lastReceived(bps)": 1651416, "expectedUsage(bps)": 2168408, "commitThreshold(bps)": 2059987, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m22.1851679s", "numSamples": 5565, "samples": [{"value": 1610904, "sinceFirst": "0s"}, {"value": 1614024, "sinceFirst": "456.604581ms"}, {"value": 1614024, "sinceFirst": "982.958758ms"}, {"value": 1615024, "sinceFirst": "1.128206257s"}, {"value": 1615024, "sinceFirst": "1.685390233s"}, {"value": 1616024, "sinceFirst": "1.782904165s"}, {"value": 1617024, "sinceFirst": "2.247077233s"}, {"value": 1617024, "sinceFirst": "2.762840422s"}, {"value": 1617024, "sinceFirst": "3.383774722s"}, {"value": 1618024, "sinceFirst": "3.591765872s"}, {"value": 1650416, "sinceFirst": "3.850304336s"}, {"value": 1651416, "sinceFirst": "4.260823487s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 1, "direction": "UPWARD"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:27.175Z", "windowDuration": "2.477994429s", "packets": 870, "repeatedNacks": 198, "nackRatio": 0.22758620689655173}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:29.653Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1651416}
2025-03-06T10:49:29.653Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1650416, "new(bps)": 1651416, "expectedUsage(bps)": 2168408}
2025-03-06T10:49:30.120Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1651416, "new(bps)": 1652416, "lastReceived(bps)": 1652416, "expectedUsage(bps)": 2281148, "commitThreshold(bps)": 2167090, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m22.652537989s", "numSamples": 5569, "samples": [{"value": 1614024, "sinceFirst": "0s"}, {"value": 1615024, "sinceFirst": "145.247499ms"}, {"value": 1615024, "sinceFirst": "702.431475ms"}, {"value": 1616024, "sinceFirst": "799.945407ms"}, {"value": 1617024, "sinceFirst": "1.264118475s"}, {"value": 1617024, "sinceFirst": "1.779881664s"}, {"value": 1617024, "sinceFirst": "2.400815964s"}, {"value": 1618024, "sinceFirst": "2.608807114s"}, {"value": 1650416, "sinceFirst": "2.867345578s"}, {"value": 1651416, "sinceFirst": "3.277864729s"}, {"value": 1652416, "sinceFirst": "3.745244459s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 1, "direction": "INCONCLUSIVE"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:27.175Z", "windowDuration": "2.945369161s", "packets": 1019, "repeatedNacks": 199, "nackRatio": 0.19528949950932287}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:30.120Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1652416}
2025-03-06T10:49:30.120Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1651416, "new(bps)": 1652416, "expectedUsage(bps)": 2281148}
2025-03-06T10:49:30.248Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "NONE", "reason": "NONE", "committedChannelCapacity": 1652416}
2025-03-06T10:49:30.275Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 22103, "count": 11400}
2025-03-06T10:49:31.653Z INFO livekit.webhook webhook/url_notifier.go:124 sent webhook {"event": "track_unpublished", "id": "EV_sBisahMrRYTY", "webhookTime": 1741258162, "room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "url": "https://openvidu.shooka.com/shooka/livekit/webhook", "queueDuration": "90.68µs", "sendDuration": "8.683441351s"}
2025-03-06T10:49:34.417Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 22203, "count": 11500}
2025-03-06T10:49:35.583Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1652416, "new(bps)": 1648984, "lastReceived(bps)": 1648984, "expectedUsage(bps)": 2158002, "commitThreshold(bps)": 2050101, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m28.114949883s", "numSamples": 5659, "samples": [{"value": 1715912, "sinceFirst": "0s"}, {"value": 1743472, "sinceFirst": "207.155748ms"}, {"value": 1770968, "sinceFirst": "410.671226ms"}, {"value": 1799072, "sinceFirst": "615.356804ms"}, {"value": 1834064, "sinceFirst": "865.483412ms"}, {"value": 1615440, "sinceFirst": "1.472462251s"}, {"value": 1615440, "sinceFirst": "2.031265687s"}, {"value": 1616440, "sinceFirst": "2.399668189s"}, {"value": 1642768, "sinceFirst": "2.609874745s"}, {"value": 1642768, "sinceFirst": "3.111474094s"}, {"value": 1647984, "sinceFirst": "3.671097616s"}, {"value": 1648984, "sinceFirst": "4.007843619s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": -0.09375, "direction": "INCONCLUSIVE"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:33.458Z", "windowDuration": "2.124284488s", "packets": 775, "repeatedNacks": 97, "nackRatio": 0.12516129032258064}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:35.583Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "NONE", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1648984}
2025-03-06T10:49:35.583Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1652416, "new(bps)": 1648984, "expectedUsage(bps)": 2158002}
2025-03-06T10:49:35.692Z INFO livekit.webhook webhook/url_notifier.go:124 sent webhook {"event": "track_unpublished", "id": "EV_fdBJ4m7qXW3D", "webhookTime": 1741258163, "room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "url": "https://openvidu.shooka.com/shooka/livekit/webhook", "queueDuration": "8.454879443s", "sendDuration": "4.039490179s"}
2025-03-06T10:49:36.513Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "NONE", "reason": "NONE", "committedChannelCapacity": 1648984}
2025-03-06T10:49:38.513Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 22303, "count": 11600}
2025-03-06T10:49:38.583Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1648984, "new(bps)": 1686928, "lastReceived(bps)": 1686928, "expectedUsage(bps)": 2250113, "commitThreshold(bps)": 2137607, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m31.115370144s", "numSamples": 5709, "samples": [{"value": 1642768, "sinceFirst": "0s"}, {"value": 1647984, "sinceFirst": "559.623522ms"}, {"value": 1648984, "sinceFirst": "896.369525ms"}, {"value": 1648984, "sinceFirst": "1.559580449s"}, {"value": 1648984, "sinceFirst": "2.153031753s"}, {"value": 1649984, "sinceFirst": "2.359358366s"}, {"value": 1676344, "sinceFirst": "2.565988001s"}, {"value": 1703816, "sinceFirst": "2.777117031s"}, {"value": 1704816, "sinceFirst": "3.227446184s"}, {"value": 1732472, "sinceFirst": "3.436671051s"}, {"value": 1686928, "sinceFirst": "3.686980487s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 0.8846153846153846, "direction": "INCONCLUSIVE"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:36.513Z", "windowDuration": "2.070299694s", "packets": 717, "repeatedNacks": 126, "nackRatio": 0.17573221757322174}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:38.583Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "NONE", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1686928}
2025-03-06T10:49:38.583Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1648984, "new(bps)": 1686928, "expectedUsage(bps)": 2250113}
2025-03-06T10:49:39.324Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1686928, "new(bps)": 1690832, "lastReceived(bps)": 1690832, "expectedUsage(bps)": 2216256, "commitThreshold(bps)": 2105443, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m31.856904908s", "numSamples": 5721, "samples": [{"value": 1647984, "sinceFirst": "0s"}, {"value": 1648984, "sinceFirst": "336.746003ms"}, {"value": 1648984, "sinceFirst": "999.956927ms"}, {"value": 1648984, "sinceFirst": "1.593408231s"}, {"value": 1649984, "sinceFirst": "1.799734844s"}, {"value": 1676344, "sinceFirst": "2.006364479s"}, {"value": 1703816, "sinceFirst": "2.217493509s"}, {"value": 1704816, "sinceFirst": "2.667822662s"}, {"value": 1732472, "sinceFirst": "2.877047529s"}, {"value": 1686928, "sinceFirst": "3.127356965s"}, {"value": 1686928, "sinceFirst": "3.828895293s"}, {"value": 1690832, "sinceFirst": "4.078675385s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 0.7096774193548387, "direction": "UPWARD"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:36.513Z", "windowDuration": "2.811835642s", "packets": 951, "repeatedNacks": 183, "nackRatio": 0.19242902208201892}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:39.325Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1690832}
2025-03-06T10:49:39.325Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1686928, "new(bps)": 1690832, "expectedUsage(bps)": 2216256}
2025-03-06T10:49:39.567Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "NONE", "reason": "NONE", "committedChannelCapacity": 1690832}
2025-03-06T10:49:41.718Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1690832, "new(bps)": 1760424, "lastReceived(bps)": 1760424, "expectedUsage(bps)": 2449561, "commitThreshold(bps)": 2327082, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m34.250338083s", "numSamples": 5763, "samples": [{"value": 1676344, "sinceFirst": "0s"}, {"value": 1703816, "sinceFirst": "211.12903ms"}, {"value": 1704816, "sinceFirst": "661.458183ms"}, {"value": 1732472, "sinceFirst": "870.68305ms"}, {"value": 1686928, "sinceFirst": "1.120992486s"}, {"value": 1686928, "sinceFirst": "1.822530814s"}, {"value": 1690832, "sinceFirst": "2.072310906s"}, {"value": 1722688, "sinceFirst": "2.314728904s"}, {"value": 1722688, "sinceFirst": "2.872210662s"}, {"value": 1726920, "sinceFirst": "3.601047423s"}, {"value": 1726920, "sinceFirst": "4.120154492s"}, {"value": 1760424, "sinceFirst": "4.257681978s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 0.5873015873015873, "direction": "UPWARD"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:39.567Z", "windowDuration": "2.15109993s", "packets": 748, "repeatedNacks": 115, "nackRatio": 0.1537433155080214}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:41.718Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "NONE", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1760424}
2025-03-06T10:49:41.718Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1690832, "new(bps)": 1760424, "expectedUsage(bps)": 2449561}
2025-03-06T10:49:42.454Z INFO livekit.transport remotebwe/remote_bwe.go:208 remote bwe: channel congestion detected, applying channel capacity update {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "reason": "LOSS", "old(bps)": 1760424, "new(bps)": 1761424, "lastReceived(bps)": 1761424, "expectedUsage(bps)": 2522073, "commitThreshold(bps)": 2395969, "channel": {"name": "non-probe", "estimate": {"name": "non-probe-estimate", "startTime": "2025-03-06T10:35:07.468Z", "elapsed": "14m34.986743611s", "numSamples": 5775, "samples": [{"value": 1704816, "sinceFirst": "0s"}, {"value": 1732472, "sinceFirst": "209.224867ms"}, {"value": 1686928, "sinceFirst": "459.534303ms"}, {"value": 1686928, "sinceFirst": "1.161072631s"}, {"value": 1690832, "sinceFirst": "1.410852723s"}, {"value": 1722688, "sinceFirst": "1.653270721s"}, {"value": 1722688, "sinceFirst": "2.210752479s"}, {"value": 1726920, "sinceFirst": "2.93958924s"}, {"value": 1726920, "sinceFirst": "3.458696309s"}, {"value": 1760424, "sinceFirst": "3.596223795s"}, {"value": 1760424, "sinceFirst": "4.270242174s"}, {"value": 1761424, "sinceFirst": "4.54071971s"}], "lowestValue": 458080, "highestValue": 4050944, "kendallsTau": 0.6774193548387096, "direction": "UPWARD"}, "nack": {"name": "non-probe-nack", "windowStartTime": "2025-03-06T10:49:39.567Z", "windowDuration": "2.887504248s", "packets": 969, "repeatedNacks": 132, "nackRatio": 0.13622291021671826}, "channelTrend": "CONGESTING", "channelCongestionReason": "LOSS"}}
2025-03-06T10:49:42.454Z INFO livekit.transport remotebwe/remote_bwe.go:223 remote bwe: congestion state change {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "from": "CONGESTED", "to": "CONGESTED", "reason": "LOSS", "committedChannelCapacity": 1761424}
2025-03-06T10:49:42.454Z INFO livekit.transport.cc streamallocator/streamallocator.go:852 stream allocator: channel congestion detected, updating channel capacity {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "transport": "SUBSCRIBER", "old(bps)": 1760424, "new(bps)": 1761424, "expectedUsage(bps)": 2522073}
2025-03-06T10:49:42.561Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 22403, "count": 11700}

[alirezafarahaniii]

Log 2 New user join:
2025-03-06T10:52:22.607Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 26303, "count": 15600}
2025-03-06T10:52:22.892Z INFO livekit service/roommanager.go:422 starting RTC session {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "room": "3810", "nodeID": "ND_vBHqKfL3eiWK", "numParticipants": 5, "participantInit": {"Identity": "111 (مهمان)&&14178558", "Reconnect": false, "ReconnectReason": "RR_UNKNOWN", "AutoSubscribe": true, "Client": {"sdk": "JS", "version": "2.9.5", "protocol": 15, "os": "Windows", "osVersion": "10", "deviceModel": "Other", "browser": "Chrome", "browserVersion": "133.0.0"}, "Grants": {"Identity": "", "Kind": "", "Video": {"RoomCreate": false, "RoomList": false, "RoomRecord": false, "RoomAdmin": false, "RoomJoin": true, "Room": "3810", "CanPublish": "not-set", "CanSubscribe": "not-set", "CanPublishData": "not-set", "CanPublishSources": [], "CanUpdateOwnMetadata": "not-set", "IngressAdmin": false, "Hidden": false, "Recorder": false, "Agent": false, "CanSubscribeMetrics": "not-set"}, "SIP": {"Admin": false, "Call": false}, "RoomConfig": {}, "RoomPreset": ""}, "Region": "", "AdaptiveStream": false, "ID": "", "SubscriberAllowPause": "not-set", "DisableICELite": false, "CreateRoom": {"name": "3810"}}}
2025-03-06T10:52:22.893Z INFO livekit service/roommanager.go:955 created TURN password {"username": "hNTRXpHUIFEZZZjbfKIo4jGtuxKZIDp2kCvxOKJoCC", "password": "qWMSeffXnRbKeOfEvhiJuKqTxn0CQttXbKAYdIoDRsKC"}
2025-03-06T10:52:22.894Z WARN livekit.transport.pion.ice [email protected]/gather.go:150 1:1 NAT mapping is enabled but no external IP is found for fe80::f816:3eff:fe79:4961%ens3 {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "transport": "SUBSCRIBER"}
2025-03-06T10:52:22.923Z WARN livekit.transport.pion.ice [email protected]/agent.go:468 Failed to ping without candidate pairs. Connection is not possible yet. {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "transport": "SUBSCRIBER"}
2025-03-06T10:52:22.934Z WARN livekit.transport.pion.ice [email protected]/agent.go:468 Failed to ping without candidate pairs. Connection is not possible yet. {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "transport": "PUBLISHER"}
2025-03-06T10:52:22.934Z WARN livekit.transport.pion.ice [email protected]/gather.go:150 1:1 NAT mapping is enabled but no external IP is found for fe80::f816:3eff:fe79:4961%ens3 {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "transport": "PUBLISHER"}
2025-03-06T10:52:22.934Z WARN livekit.transport.pion.ice [email protected]/agent.go:468 Failed to ping without candidate pairs. Connection is not possible yet. {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "transport": "PUBLISHER"}
2025-03-06T10:52:23.270Z INFO livekit.transport rtc/transport.go:546 ice reconnected or switched pair {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "transport": "PUBLISHER", "existingPair": {"localProtocol": "udp", "localCandidateType": "host", "localAdddress": "87.247.171.231", "localPort": 55383, "remoteProtocol": "udp", "remoteCandidateType": "relay", "remoteAdddress": "87.247.171....", "remotePort": 47344, "relatedAdddress": "2.177.85...", "relatedPort": 56361}, "newPair": {"localProtocol": "udp", "localCandidateType": "host", "localAdddress": "87.247.171.231", "localPort": 55383, "remoteProtocol": "udp", "remoteCandidateType": "prflx", "remoteAdddress": "2.177.85...", "remotePort": 50061}}
2025-03-06T10:52:23.990Z INFO livekit rtc/room.go:472 participant active {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "publisherCandidates": ["[local][selected:2][trickle] udp4 host 87.247.171.231:55383 (resolved: 87.247.171.231:55383)", "[local][trickle] tcp4 host 87.247.171.231:7881 (resolved: 87.247.171.231:7881)", "[local][trickle] udp4 host 87.247.171.231:55030 (resolved: 87.247.171.231:55030)", "[remote][trickle] udp4 host 172.21.224.1:56360", "[remote][trickle] udp4 host 192.168.7.58:56361", "[remote][trickle] udp4 srflx 2.177.85...:56361 related 192.168.7.58:56361", "[remote][selected:1][trickle] udp4 relay 87.247.171....:47344 related 2.177.85...:56361", "[remote][trickle] tcp4 host 172.21.224.1:9", "[remote][trickle] tcp4 host 192.168.7.58:9", "[remote][selected:2][trickle] udp4 prflx 2.177.85...:50061"], "subscriberCandidates": ["[local][selected:1][trickle] udp4 host 87.247.171.231:57311 (resolved: 87.247.171.231:57311)", "[local][trickle] tcp4 host 87.247.171.231:7881 (resolved: 87.247.171.231:7881)", "[local][trickle] udp4 host 87.247.171.231:59022 (resolved: 87.247.171.231:59022)", "[remote][trickle] udp4 host 172.21.224.1:56358", "[remote][trickle] udp4 host 192.168.7.58:56359", "[remote][trickle] udp4 srflx 2.177.85...:56359 related 192.168.7.58:56359", "[remote][trickle] udp4 relay 87.247.171....:43783 related 2.177.85...:56359", "[remote][trickle] tcp4 host 172.21.224.1:9", "[remote][trickle] tcp4 host 192.168.7.58:9", "[remote][selected:1][trickle] udp4 prflx 2.177.85...:38409"], "connectionType": "udp"}
2025-03-06T10:52:24.151Z INFO livekit.pub rtc/participant.go:1797 mediaTrack published {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "kind": "video", "trackID": "TR_VCRYEUHXnfUMNC", "webrtcTrackID": "61c40bea-cb34-4097-ba4a-7386a13379b0", "rid": "", "SSRC": 2440055894, "mime": "video/VP8", "trackInfo": {"sid": "TR_VCRYEUHXnfUMNC", "type": "VIDEO", "width": 320, "height": 240, "source": "CAMERA", "layers": [{"quality": "HIGH", "width": 320, "height": 240, "bitrate": 150000, "ssrc": 2440055894}], "mimeType": "video/VP8", "mid": "1", "codecs": [{"mimeType": "video/VP8", "mid": "1", "cid": "61c40bea-cb34-4097-ba4a-7386a13379b0", "layers": [{"quality": "HIGH", "width": 320, "height": 240, "bitrate": 150000, "ssrc": 2440055894}]}], "encryption": "NONE", "stream": "camera", "version": {"unixMicro": 1741258344151236}}, "fromSdp": true}
2025-03-06T10:52:24.234Z INFO livekit.pub rtc/participant.go:1797 mediaTrack published {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "remote": false, "kind": "audio", "trackID": "TR_AMu4oXeZpTFdQF", "webrtcTrackID": "c8ff5b26-aed7-486b-b00e-1508a0562fd0", "rid": "", "SSRC": 3391208386, "mime": "audio/opus", "trackInfo": {"sid": "TR_AMu4oXeZpTFdQF", "type": "AUDIO", "source": "MICROPHONE", "mimeType": "audio/opus", "mid": "2", "disableRed": true, "encryption": "NONE", "stream": "camera", "version": {"unixMicro": 1741258344234776}}, "fromSdp": true}
2025-03-06T10:52:26.951Z WARN livekit.pub.pub.sfu buffer/dependencydescriptorparser.go:81 dependency descriptor extension is not present {"room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "darush (مهمان)&&16492040", "pID": "PA_9qmxMrzeCc85", "remote": false, "trackID": "TR_VCnY7iGeujC4YY", "relayed": false, "mime": "video/vp8", "layer": 0, "ssrc": 722166934, "seq": 26403, "count": 15700}
2025-03-06T10:52:27.115Z INFO livekit.webhook webhook/url_notifier.go:124 sent webhook {"event": "participant_joined", "id": "EV_UVP2myt9wUUU", "webhookTime": 1741258343, "room": "3810", "roomID": "RM_zhgyobEj32c7", "participant": "111 (مهمان)&&14178558", "pID": "PA_n6YdAHPzWE3a", "url": "https://openvidu.shooka.com/shooka/livekit/webhook", "queueDuration": "113.657µs", "sendDuration": "3.125086956s"}