Basic Query on multiple JVBs in single shard architecture

Hi Team,

Basic doubt with multiple JVBs in a single shard with single Jicofo, Prosody, etc. setup:

Does it take some time to let user join the correct JVB (conference)? For us, when a new user joins the ongoing meeting, new user sees red connectivity and can’t hear or see video of anyone till some time and then is able to do normal talk in the conference.

Checking the backend JVB logs - I see socket fails multiple times and then pair succeeds and that’s when user is able to do normal conferencing. Is this expected behavior?

Note: I have below K8s setup for above scenario:

  • Single Jicofo, Prosody, Jitsi Meet Web
  • 3 JVBs
JVB Logs

JVB 2023-03-09 15:04:31.559 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7] Agent.gatherCandidates#647: Gathering candidates for component stream-66c39f2d.RTP.
JVB 2023-03-09 15:04:31.561 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d] Endpoint.#344: Created new endpoint isUsingSourceNames=true, iceControlling=true
JVB 2023-03-09 15:04:32.890 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we’ll act as server
JVB 2023-03-09 15:04:32.890 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
JVB 2023-03-09 15:04:32.890 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
JVB 2023-03-09 15:04:32.890 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7] Agent.initCheckLists#972: Init checklist for stream stream-66c39f2d
JVB 2023-03-09 15:04:32.891 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7] Agent.setState#946: ICE state changed from Waiting to Running.
JVB 2023-03-09 15:04:32.891 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
JVB 2023-03-09 15:04:32.891 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
JVB 2023-03-09 15:04:32.892 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7 name=stream-66c39f2d componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-66c39f2d.RTP: 192.168.1.80:60935/udp
JVB 2023-03-09 15:04:32.892 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7 name=stream-66c39f2d componentId=1] Component.updateRemoteCandidates#482: new Pair added: 10.244.6.9:31000/udp/host → 192.168.1.80:60935/udp/host (stream-66c39f2d.RTP).
JVB 2023-03-09 15:04:32.911 INFO: [440] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7] ConnectivityCheckClient.startCheckForPair#350: Could not start connectivity check: No socket found for 10.244.6.9:31000/udp->192.168.1.80:60935/udp
JVB 2023-03-09 15:04:49.687 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] AbstractEndpoint.expire#311: Expiring.
JVB 2023-03-09 15:04:49.687 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Endpoint.expire#1140: Spent 0 seconds oversending
JVB 2023-03-09 15:04:49.687 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Transceiver.teardown#353: Tearing down
JVB 2023-03-09 15:04:49.687 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] RtpReceiverImpl.tearDown#347: Tearing down
JVB 2023-03-09 15:04:49.687 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] RtpSenderImpl.tearDown#318: Tearing down
JVB 2023-03-09 15:04:49.688 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] DtlsTransport.stop#186: Stopping
JVB 2023-03-09 15:04:49.688 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7] IceTransport.stop#252: Stopping
JVB 2023-03-09 15:04:49.689 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7] Agent.setState#946: ICE state changed from Running to Terminated.
JVB 2023-03-09 15:04:49.690 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=28djt1gr49sri7 ufrag=28djt1gr49sri7 name=stream-66c39f2d componentId=1] MergingDatagramSocket.close#142: Closing.
JVB 2023-03-09 15:04:49.690 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Endpoint.expire#1158: Expired.
JVB 2023-03-09 15:04:49.692 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s] Agent.gatherCandidates#647: Gathering candidates for component stream-66c39f2d.RTP.
JVB 2023-03-09 15:04:49.694 INFO: [474] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d] Endpoint.#344: Created new endpoint isUsingSourceNames=true, iceControlling=true
JVB 2023-03-09 15:04:50.438 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we’ll act as server
JVB 2023-03-09 15:04:50.438 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
JVB 2023-03-09 15:04:50.438 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
JVB 2023-03-09 15:04:50.438 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s] Agent.initCheckLists#972: Init checklist for stream stream-66c39f2d
JVB 2023-03-09 15:04:50.438 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s] Agent.setState#946: ICE state changed from Waiting to Running.
JVB 2023-03-09 15:04:50.439 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
JVB 2023-03-09 15:04:50.439 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
JVB 2023-03-09 15:04:50.440 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s name=stream-66c39f2d componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-66c39f2d.RTP: 192.168.1.80:52683/udp
JVB 2023-03-09 15:04:50.440 INFO: [495] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s name=stream-66c39f2d componentId=1] Component.updateRemoteCandidates#482: new Pair added: 10.244.6.9:31000/udp/host → 192.168.1.80:52683/udp/host (stream-66c39f2d.RTP).
JVB 2023-03-09 15:04:50.460 INFO: [440] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s] ConnectivityCheckClient.startCheckForPair#350: Could not start connectivity check: No socket found for 10.244.6.9:31000/udp->192.168.1.80:52683/udp
JVB 2023-03-09 15:05:07.231 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] AbstractEndpoint.expire#311: Expiring.
JVB 2023-03-09 15:05:07.232 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Endpoint.expire#1140: Spent 0 seconds oversending
JVB 2023-03-09 15:05:07.232 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Transceiver.teardown#353: Tearing down
JVB 2023-03-09 15:05:07.232 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] RtpReceiverImpl.tearDown#347: Tearing down
JVB 2023-03-09 15:05:07.232 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] RtpSenderImpl.tearDown#318: Tearing down
JVB 2023-03-09 15:05:07.233 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] DtlsTransport.stop#186: Stopping
JVB 2023-03-09 15:05:07.233 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s] IceTransport.stop#252: Stopping
JVB 2023-03-09 15:05:07.234 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s] Agent.setState#946: ICE state changed from Running to Terminated.
JVB 2023-03-09 15:05:07.234 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=fjq3l1gr49td8s ufrag=fjq3l1gr49td8s name=stream-66c39f2d componentId=1] MergingDatagramSocket.close#142: Closing.
JVB 2023-03-09 15:05:07.234 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Endpoint.expire#1158: Expired.
JVB 2023-03-09 15:05:07.237 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.gatherCandidates#647: Gathering candidates for component stream-66c39f2d.RTP.
JVB 2023-03-09 15:05:07.238 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d] Endpoint.#344: Created new endpoint isUsingSourceNames=true, iceControlling=true
JVB 2023-03-09 15:05:07.954 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we’ll act as server
JVB 2023-03-09 15:05:07.954 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
JVB 2023-03-09 15:05:07.955 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
JVB 2023-03-09 15:05:07.955 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.initCheckLists#972: Init checklist for stream stream-66c39f2d
JVB 2023-03-09 15:05:07.955 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.setState#946: ICE state changed from Waiting to Running.
JVB 2023-03-09 15:05:07.955 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
JVB 2023-03-09 15:05:07.955 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.startConnectivityEstablishment#758: Trigger checks for pairs that were received before running state
JVB 2023-03-09 15:05:07.955 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7961835276064522239):
LocalCandidate=candidate:1 1 udp 2130706431 10.244.6.9 31000 typ host
RemoteCandidate=candidate:10000 1 udp 1853759231 10.244.6.1 37384 typ prflx
JVB 2023-03-09 15:05:07.955 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
JVB 2023-03-09 15:05:07.956 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4 name=stream-66c39f2d componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-66c39f2d.RTP: 192.168.1.80:65379/udp
JVB 2023-03-09 15:05:07.956 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4 name=stream-66c39f2d componentId=1] Component.updateRemoteCandidates#482: new Pair added: 10.244.6.9:31000/udp/host → 192.168.1.80:65379/udp/host (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:07.996 INFO: [445] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.startCheckForPair#350: Could not start connectivity check: No socket found for 10.244.6.9:31000/udp->192.168.1.80:65379/udp
JVB 2023-03-09 15:05:07.996 INFO: [440] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.startCheckForPair#350: Could not start connectivity check: No socket found for 10.244.6.9:31000/udp->192.168.1.80:65379/udp
JVB 2023-03-09 15:05:08.035 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 10.244.6.9:31000/udp/host → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:08.035 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4 name=stream-66c39f2d componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 10.244.6.1:37384/udp
JVB 2023-03-09 15:05:08.035 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 132.226.149.205:31000/udp/srflx → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:08.035 INFO: [481] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 10.244.6.9:31000/udp/host → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:08.036 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 132.226.149.205:31000/udp/srflx → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP)
JVB 2023-03-09 15:05:08.036 INFO: [481] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 132.226.149.205:31000/udp/srflx → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:08.036 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.nominate#1810: verify if nominated pair answer again
JVB 2023-03-09 15:05:08.036 INFO: [481] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2023-03-09 15:05:08.036 WARNING: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4 name=stream-66c39f2d componentId=1] MergingDatagramSocket.initializeActive#599: Active socket already initialized.
JVB 2023-03-09 15:05:08.036 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2023-03-09 15:05:08.095 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 132.226.149.205:31000/udp/srflx → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:08.095 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 132.226.149.205:31000/udp/srflx → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:08.095 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
JVB 2023-03-09 15:05:08.095 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: 132.226.149.205:31000/udp/srflx → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP).
JVB 2023-03-09 15:05:08.095 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4 name=stream-66c39f2d] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-66c39f2d.RTP: 132.226.149.205:31000/udp/srflx → 10.244.6.1:37384/udp/prflx (stream-66c39f2d.RTP)
JVB 2023-03-09 15:05:08.096 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.checkListStatesUpdated#1901: CheckList of stream stream-66c39f2d is COMPLETED
JVB 2023-03-09 15:05:08.096 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.setState#946: ICE state changed from Running to Completed.
JVB 2023-03-09 15:05:08.096 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4] IceTransport.iceStateChanged#342: ICE state changed old=Running new=Completed
JVB 2023-03-09 15:05:08.096 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Endpoint$setupIceTransport$2.connected#394: ICE connected
JVB 2023-03-09 15:05:08.096 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@7fd6a713
JVB 2023-03-09 15:05:08.096 INFO: [470] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.logCandTypes#2009: Harvester used for selected pair for stream-66c39f2d.RTP: srflx
JVB 2023-03-09 15:05:08.097 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
JVB 2023-03-09 15:05:08.161 INFO: [447] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s] Endpoint$setupDtlsTransport$3.handshakeComplete#438: DTLS handshake complete
JVB 2023-03-09 15:05:08.509 INFO: [23] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com] EndpointConnectionStatusMonitor.monitorEndpointActivity#113: 66c39f2d has reconnected
JVB 2023-03-09 15:05:11.097 INFO: [413] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4 ufrag=1jb381gr49tud4] Agent.setState#946: ICE state changed from Completed to Terminated.
JVB 2023-03-09 15:05:11.097 INFO: [413] [confId=a73c4642ac5b2e7d conf_name=db78089d-db36-11ea-aaf6-0adbb24bf6fc@muc.meet.domain.com epId=66c39f2d stats_id=Terrance-w6s local_ufrag=1jb381gr49tud4] IceTransport.iceStateChanged#342: ICE state changed old=Completed new=Terminated

Nope, that is not normal. If you test with same client/same location on meet.jit.si do you experience the same?

What is the browser and its version?

Chrome 107.0.5304.87

Having said that, I don’t see it every-time. It’s random, sometimes I see new user is able to communicate right away vs some-times, it take some time for socket pairing to succeed.

Ok resolved - I had a single node service in between multiple JVBs causing this issue. Resolved now.