2 users works fine , 3rd user come in and audio/video stop

do-d · April 3, 2020, 9:59am

Helllo

i installed jitsi from apt on ubuntu
i configured it as secure domain + LDAP authentication behind NAT
i will say right now that its not firewall issue because for testing i forwarding all ports to jitsi server .
im monitoring the bandwidth and i didn’t notice any bottleneck.

host creating a room, asked to enter user and password , sending room details to 2 more persons
2nd person connects successfully video and audio works good
3rd person connects and everyone lose video and audio

any ideas ?

Neil_Brown · April 3, 2020, 11:50am

Have you followed this?

github.com

jitsi/jitsi-meet/blob/master/doc/quick-install.md#advanced-configuration

# Jitsi Meet quick install

This guide helps you  ___host your own Jitsi server___. If you want to have a video conference without setting up any infrastructure, use https://meet.jit.si instead.

This document describes the required steps for a quick Jitsi Meet installation on a Debian based GNU/Linux system. Debian 9 (Stretch) or later, and Ubuntu 18.04 (Bionic Beaver) or later are supported out-of-the-box.

On Ubuntu systems, Jitsi requires dependencies from Ubuntu's `universe` package repository.  To ensure this is enabled, run `apt-add-repository universe` at the command-line.

_Note_: Many of the installation steps require elevated privileges. If you are logged in using a regular user account, you may need to temporarily increase your permissions (for example, by using `sudo` for individual commands).

## Basic Jitsi Meet install

### Set up the Fully Qualified Domain Name (FQDN) (optional)

If the machine used to host the Jitsi Meet instance has a FQDN (for example `meet.example.org`) already set up in DNS, `/etc/hostname` must contain this FQDN; if this is not the case yet, [change the hostname](https://wiki.debian.org/HowTo/ChangeHostname).

Then add the same FQDN in the `/etc/hosts` file, associating it with the loopback address:

    127.0.0.1 localhost meet.example.org

This file has been truncated. show original

do-d · April 4, 2020, 9:54am

yes i followed this and
the secure domain guide
and the ldap guide

Toba · April 4, 2020, 10:53am

I had the same problem, I “solved” it by not commenting the
org.ice4j.ice.harvest.STUN_MAPPING_HARVESTER_ADDRESSES=meet-jit-si-turnrelay.jitsi.net:443
line in my /etc/jitsi/videobridge/sip-communicator.properties file:

New participant kicks first participant, strange connection error in jvb.log

however, I am not sure, whether this is the right solution, still waiting for answer from the devs. But maybe you can try it, and it helps?

do-d · April 5, 2020, 2:26pm

thanks i tried this , no change

do-d · April 5, 2020, 5:03pm

my jvb.log :

2020-04-05 14:50:27.739 INFO: [1] NewConfig$1.invoke#88: Loaded NewConfig with origin: merge of system properties,system properties,reference.conf @ jar:file:/usr/share/jitsi-videobridge/jitsi-videobridge.jar!/reference.conf: 1,reference.conf @ jar:file:/usr/share/jitsi-videobridge/lib/jitsi-media-transform-1.0-137-gde1b071.jar!/reference.conf: 1
2020-04-05 14:50:27.750 INFO: [1] LegacyConfigFileLoader$Companion.load#40: Attempting to load legacy config file at path /etc/jitsi, videobridge, sip-communicator.properties
2020-04-05 14:50:27.756 INFO: [1] LegacyConfigFileLoader$Companion.load#40: Attempting to load legacy config file at path /etc/jitsi, videobridge, sip-communicator.properties
2020-04-05 14:50:27.760 INFO: [1] JitsiConfig$Companion.reload#40: Reloading.
2020-04-05 14:50:27.822 INFO: [1] NewConfig$1.invoke#88: Loaded NewConfig with origin: merge of system properties,system properties,reference.conf @ jar:file:/usr/share/jitsi-videobridge/jitsi-videobridge.jar!/reference.conf: 1,reference.conf @ jar:file:/usr/share/jitsi-videobridge/lib/jitsi-media-transform-1.0-137-gde1b071.jar!/reference.conf: 1
2020-04-05 14:50:27.822 INFO: [1] LegacyConfigFileLoader$Companion.load#40: Attempting to load legacy config file at path /etc/jitsi, videobridge, sip-communicator.properties
2020-04-05 14:50:27.824 INFO: [1] LegacyConfigFileLoader$Companion.load#40: Attempting to load legacy config file at path /etc/jitsi, videobridge, sip-communicator.properties
2020-04-05 14:50:27.875 INFO: [12] ConfigurationActivator.start#45: Registered the LegacyConfigurationServiceShim in OSGi.
2020-04-05 14:50:27.880 INFO: [12] AbstractVersionActivator.start#91: VersionService registered: JVB 2.1.157-g389b69ff
2020-04-05 14:50:27.895 INFO: [12] AbstractJettyBundleActivator.start#613: Not starting the Jetty service for org.jitsi.videobridge.rest.RESTBundleActivator(port=8080)
2020-04-05 14:50:27.906 INFO: [12] AbstractJettyBundleActivator.start#613: Not starting the Jetty service for org.jitsi.videobridge.rest.PublicRESTBundleActivator(port=-1)
2020-04-05 14:50:27.908 INFO: [12] AbstractJettyBundleActivator.start#613: Not starting the Jetty service for org.jitsi.videobridge.rest.PublicClearPortRedirectBundleActivator(port=8080)
2020-04-05 14:50:28.009 INFO: [12] UlimitCheck.printUlimits#115: Running with open files limit 65000 (hard 65000), thread limit 65000 (hard 65000).
2020-04-05 14:50:28.013 INFO: [12] VideobridgeExpireThread.start#92: Starting with 60 second interval.
2020-04-05 14:50:28.022 WARNING: [12] Videobridge.start#918: No authorized source regexp configured. Will accept requests from any source.
2020-04-05 14:50:28.065 INFO: [18] Videobridge.createConference#326: create_conf, id=b9a77a3302c47be0 gid=null logging=false
2020-04-05 14:50:28.110 INFO: [18] TaskPools.#81: TaskPools detected 4 processors, creating the CPU pool with that many threads
2020-04-05 14:50:28.332 INFO: [12] JitsiConfig$Companion.reload#40: Reloading.
2020-04-05 14:50:28.358 INFO: [12] NewConfig$1.invoke#88: Loaded NewConfig with origin: merge of system properties,system properties,reference.conf @ jar:file:/usr/share/jitsi-videobridge/jitsi-videobridge.jar!/reference.conf: 1,reference.conf @ jar:file:/usr/share/jitsi-videobridge/lib/jitsi-media-transform-1.0-137-gde1b071.jar!/reference.conf: 1
2020-04-05 14:50:28.401 INFO: [12] LegacyConfigFileLoader$Companion.load#40: Attempting to load legacy config file at path /etc/jitsi, videobridge, sip-communicator.properties
2020-04-05 14:50:28.403 INFO: [12] LegacyConfigFileLoader$Companion.load#40: Attempting to load legacy config file at path /etc/jitsi, videobridge, sip-communicator.properties
2020-04-05 14:50:28.451 INFO: [12] OctoRelayService.start#62: Octo relay is disabled.
2020-04-05 14:50:28.575 INFO: [21] org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize: Using org.ice4j.ice.harvest.MappingCandidateHarvester, face=null, mask=null
2020-04-05 14:50:28.576 INFO: [21] org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize: Initialized mapping harvesters (delay=161ms). stunDiscoveryFailed=false
2020-04-05 14:50:28.584 INFO: [18] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address 10.0.0.18:10000/udp. Receive buffer size 10485760 (asked for 10485760)
2020-04-05 14:50:28.585 INFO: [18] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address 10.0.0.18:10000/udp
2020-04-05 14:50:29.271 INFO: [22] [hostname=localhost id=shard] MucClient$1.connected#266: Connected.
2020-04-05 14:50:29.271 INFO: [22] [hostname=localhost id=shard] MucClient.lambda$getConnectAndLoginCallable$7#648: Logging in.
2020-04-05 14:50:29.500 INFO: [22] [hostname=localhost id=shard] MucClient$MucWrapper.join#751: Joined MUC: jvbbrewery@internal.auth.meet.septier.com
SCTP JNI load: Linux OS detected
SCTP lib loaded
=====>: org_jitsi_modified_sctp4j_SctpJni.c calling init
=====>: org_jitsi_modified_sctp4j_SctpJni.c about to set SCTP_DEBUG_ALL
2020-04-05 14:50:30.646 INFO: [18] Health.doRun#294: Performed a successful health check in 2626ms. Sticky failure: false
2020-04-05 14:50:40.648 INFO: [18] Videobridge.createConference#326: create_conf, id=86788112013d20c0 gid=null logging=false
2020-04-05 14:50:40.673 INFO: [18] Health.doRun#294: Performed a successful health check in 26ms. Sticky failure: false
2020-04-05 14:50:50.674 INFO: [18] Videobridge.createConference#326: create_conf, id=af0b7673fe79c6d2 gid=null logging=false
2020-04-05 14:50:50.700 INFO: [18] Health.doRun#294: Performed a successful health check in 27ms. Sticky failure: false
2020-04-05 14:51:00.702 INFO: [18] Videobridge.createConference#326: create_conf, id=501002ed9dca3602 gid=null logging=false
2020-04-05 14:51:00.728 INFO: [18] Health.doRun#294: Performed a successful health check in 27ms. Sticky failure: false
2020-04-05 14:51:10.730 INFO: [18] Videobridge.createConference#326: create_conf, id=c56cd0cb12972454 gid=null logging=false
2020-04-05 14:51:10.757 INFO: [18] Health.doRun#294: Performed a successful health check in 28ms. Sticky failure: false
2020-04-05 14:51:11.462 INFO: [30] Videobridge.createConference#326: create_conf, id=1e43248be3d23d17 gid=fff0c1 logging=true
2020-04-05 14:51:11.471 INFO: [30] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 conf_name=test1 ufrag=3skb41e552sabe] Agent.gatherCandidates#660: Gathering candidates for component stream-968df43b.RTP. Local ufrag 3skb41e552sabe
2020-04-05 14:51:11.475 INFO: [32] [confId=1e43248be3d23d17 gid=fff0c1 conf_name=test1] Conference.dominantSpeakerChanged#446: ds_change ds_id=968df43b
2020-04-05 14:51:11.525 INFO: [30] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 conf_name=test1] DtlsTransport.lambda$startConnectivityEstablishment$1#181: Ignoring empty DtlsFingerprint extension:
2020-04-05 14:51:11.971 INFO: [30] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] DtlsTransport.startConnectivityEstablishment#195: The remote side is acting as DTLS server, we’ll act as client
2020-04-05 14:51:11.978 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd componentId=1 conf_name=test1 ufrag=3skb41e552sabe name=stream-968df43b epId=968df43b local_ufrag=3skb41e552sabe] Component.addRemoteCandidate#330: Add remote candidate for stream-968df43b.RTP: 10.0.0.88:54326/udp/host
2020-04-05 14:51:11.979 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd componentId=1 conf_name=test1 ufrag=3skb41e552sabe name=stream-968df43b epId=968df43b local_ufrag=3skb41e552sabe] Component.addRemoteCandidate#330: Add remote candidate for stream-968df43b.RTP: 192.168.56.1:54327/udp/host
2020-04-05 14:51:11.979 INFO: [42] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] IceTransport.startConnectivityEstablishment#404: Starting the agent with remote candidates.
2020-04-05 14:51:11.980 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.startConnectivityEstablishment#751: Start ICE connectivity establishment. Local ufrag 3skb41e552sabe
2020-04-05 14:51:11.981 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.initCheckLists#995: Init checklist for stream stream-968df43b
2020-04-05 14:51:11.987 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.setState#962: ICE state changed from Waiting to Running. Local ufrag 3skb41e552sabe
2020-04-05 14:51:11.989 INFO: [42] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] IceTransport.iceStateChange#605: ICE state changed old=Waiting new=Running
2020-04-05 14:51:11.989 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.startConnectivityEstablishment#774: Trigger checks for pairs that were received before running state
2020-04-05 14:51:11.990 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] ConnectivityCheckClient.startChecks#142: Start connectivity checks. Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.029 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] ConnectivityCheckClient.processSuccessResponse#629: Pair succeeded: 10.0.0.18:10000/udp/host -> 10.0.0.88:54326/udp/host (stream-968df43b.RTP). Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.030 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd componentId=1 conf_name=test1 ufrag=3skb41e552sabe name=stream-968df43b epId=968df43b local_ufrag=3skb41e552sabe] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 10.0.0.88:54326/udp
2020-04-05 14:51:12.031 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] ConnectivityCheckClient.processSuccessResponse#639: Pair validated: 10.0.0.18:10000/udp/host -> 10.0.0.88:54326/udp/host (stream-968df43b.RTP). Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.032 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] DefaultNominator.strategyNominateFirstValid#140: Nominate (first valid): 10.0.0.18:10000/udp/host -> 10.0.0.88:54326/udp/host (stream-968df43b.RTP). Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.032 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.nominate#1838: verify if nominated pair answer again
2020-04-05 14:51:12.033 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] ConnectivityCheckClient.processSuccessResponse#708: IsControlling: true USE-CANDIDATE:false. Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.053 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] ConnectivityCheckClient.processSuccessResponse#629: Pair succeeded: 10.0.0.18:10000/udp/host -> 10.0.0.88:54326/udp/host (stream-968df43b.RTP). Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.053 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] ConnectivityCheckClient.processSuccessResponse#708: IsControlling: true USE-CANDIDATE:true. Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.054 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] ConnectivityCheckClient.processSuccessResponse#723: Nomination confirmed for pair: 10.0.0.18:10000/udp/host -> 10.0.0.88:54326/udp/host (stream-968df43b.RTP). Loal ufrag 3skb41e552sabe
2020-04-05 14:51:12.055 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe name=stream-968df43b epId=968df43b local_ufrag=3skb41e552sabe] CheckList.handleNominationConfirmed#387: Selected pair for stream stream-968df43b.RTP: 10.0.0.18:10000/udp/host -> 10.0.0.88:54326/udp/host (stream-968df43b.RTP)
2020-04-05 14:51:12.057 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.checkListStatesUpdated#1917: CheckList of stream stream-968df43b is COMPLETED
2020-04-05 14:51:12.057 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.setState#962: ICE state changed from Running to Completed. Local ufrag 3skb41e552sabe
2020-04-05 14:51:12.058 INFO: [40] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] IceTransport.iceStateChange#605: ICE state changed old=Running new=Completed
2020-04-05 14:51:12.066 INFO: [32] PartitionedByteBufferPool.#88: Initialized a new PartitionedByteBufferPool with 8 partitions.
2020-04-05 14:51:12.068 INFO: [32] PartitionedByteBufferPool.#88: Initialized a new PartitionedByteBufferPool with 8 partitions.
2020-04-05 14:51:12.078 INFO: [40] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] DtlsTransport.onIceConnected#478: Starting DTLS.
2020-04-05 14:51:12.087 INFO: [32] PartitionedByteBufferPool.#88: Initialized a new PartitionedByteBufferPool with 8 partitions.
2020-04-05 14:51:12.094 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.logCandTypes#2026: Harvester used for selected pair for stream-968df43b.RTP (local ufrag 3skb41e552sabe): host
2020-04-05 14:51:12.165 INFO: [42] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 conf_name=test1 ufrag=1g23b1e552sb14] Agent.gatherCandidates#660: Gathering candidates for component stream-35d54701.RTP. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.186 INFO: [31] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 epId=968df43b local_ufrag=3skb41e552sabe] TlsClientImpl.notifyServerVersion#165: Negotiated DTLS version DTLS 1.2
2020-04-05 14:51:12.191 INFO: [42] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 conf_name=test1] DtlsTransport.lambda$startConnectivityEstablishment$1#181: Ignoring empty DtlsFingerprint extension:
2020-04-05 14:51:12.378 INFO: [31] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] DtlsTransport.lambda$new$0#145: DTLS handshake complete. Got SRTP profile 1
2020-04-05 14:51:12.394 INFO: [46] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] Endpoint.lambda$null$5#787: Attempting to establish SCTP socket connection
2020-04-05 14:51:12.475 INFO: [47] OpenSslWrapperLoader.#46: jitsisrtp successfully loaded
2020-04-05 14:51:12.606 INFO: [30] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H componentId=1 conf_name=test1 ufrag=1g23b1e552sb14 name=stream-35d54701 epId=35d54701 local_ufrag=1g23b1e552sb14] Component.addRemoteCandidate#330: Add remote candidate for stream-35d54701.RTP: 192.168.1.100:58246/udp/host
2020-04-05 14:51:12.607 INFO: [42] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] DtlsTransport.startConnectivityEstablishment#195: The remote side is acting as DTLS server, we’ll act as client
2020-04-05 14:51:12.608 INFO: [30] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H componentId=1 conf_name=test1 ufrag=1g23b1e552sb14 name=stream-35d54701 epId=35d54701 local_ufrag=1g23b1e552sb14] Component.addRemoteCandidate#330: Add remote candidate for stream-35d54701.RTP: 192.168.1.100:58246/udp/host
2020-04-05 14:51:12.609 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H componentId=1 conf_name=test1 ufrag=1g23b1e552sb14 name=stream-35d54701 epId=35d54701 local_ufrag=1g23b1e552sb14] Component.addRemoteCandidate#330: Add remote candidate for stream-35d54701.RTP: 192.168.1.100:58246/udp/host
2020-04-05 14:51:12.610 INFO: [30] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] IceTransport.startConnectivityEstablishment#404: Starting the agent with remote candidates.
2020-04-05 14:51:12.610 INFO: [42] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] IceTransport.startConnectivityEstablishment#404: Starting the agent with remote candidates.
2020-04-05 14:51:12.611 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.startConnectivityEstablishment#751: Start ICE connectivity establishment. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.612 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.initCheckLists#995: Init checklist for stream stream-35d54701
2020-04-05 14:51:12.613 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.setState#962: ICE state changed from Waiting to Running. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.613 INFO: [42] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] IceTransport.iceStateChange#605: ICE state changed old=Waiting new=Running
2020-04-05 14:51:12.614 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.startConnectivityEstablishment#774: Trigger checks for pairs that were received before running state
2020-04-05 14:51:12.615 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.triggerCheck#1765: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7926369428998979583):
LocalCandidate=candidate:1 1 udp 2130706431 10.0.0.18 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1845501695 10.0.0.1 58246 typ prflx
2020-04-05 14:51:12.616 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.startChecks#142: Start connectivity checks. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.616 INFO: [30] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.startConnectivityEstablishment#751: Start ICE connectivity establishment. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.617 INFO: [30] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.initCheckLists#995: Init checklist for stream stream-35d54701
2020-04-05 14:51:12.624 INFO: [30] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.startChecks#142: Start connectivity checks. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.647 INFO: [44] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.0.0.18:10000/udp/host -> 192.168.1.100:58246/udp/host (stream-35d54701.RTP)
2020-04-05 14:51:12.664 INFO: [44] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.0.0.18:10000/udp/host -> 192.168.1.100:58246/udp/host (stream-35d54701.RTP)
2020-04-05 14:51:12.670 INFO: [53] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.0.0.18:10000/udp/host -> 192.168.1.100:58246/udp/host (stream-35d54701.RTP)
2020-04-05 14:51:12.772 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.processSuccessResponse#629: Pair succeeded: 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP). Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.773 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H componentId=1 conf_name=test1 ufrag=1g23b1e552sb14 name=stream-35d54701 epId=35d54701 local_ufrag=1g23b1e552sb14] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 10.0.0.1:58246/udp
2020-04-05 14:51:12.773 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.processSuccessResponse#639: Pair validated: 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP). Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.774 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] DefaultNominator.strategyNominateFirstValid#140: Nominate (first valid): 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP). Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.775 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.nominate#1838: verify if nominated pair answer again
2020-04-05 14:51:12.776 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.processSuccessResponse#708: IsControlling: true USE-CANDIDATE:false. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.918 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.processSuccessResponse#629: Pair succeeded: 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP). Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.919 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.processSuccessResponse#639: Pair validated: 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP). Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.920 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] DefaultNominator.strategyNominateFirstValid#140: Nominate (first valid): 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP). Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.920 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.processSuccessResponse#708: IsControlling: true USE-CANDIDATE:true. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.920 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] ConnectivityCheckClient.processSuccessResponse#723: Nomination confirmed for pair: 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP). Loal ufrag 1g23b1e552sb14
2020-04-05 14:51:12.921 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 name=stream-35d54701 epId=35d54701 local_ufrag=1g23b1e552sb14] CheckList.handleNominationConfirmed#387: Selected pair for stream stream-35d54701.RTP: 10.0.0.18:10000/udp/host -> 10.0.0.1:58246/udp/prflx (stream-35d54701.RTP)
2020-04-05 14:51:12.921 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.checkListStatesUpdated#1917: CheckList of stream stream-35d54701 is COMPLETED
2020-04-05 14:51:12.922 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.setState#962: ICE state changed from Running to Completed. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:12.922 INFO: [40] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] IceTransport.iceStateChange#605: ICE state changed old=Running new=Completed
2020-04-05 14:51:12.923 INFO: [40] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] DtlsTransport.onIceConnected#478: Starting DTLS.
2020-04-05 14:51:12.923 INFO: [40] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.logCandTypes#2026: Harvester used for selected pair for stream-35d54701.RTP (local ufrag 1g23b1e552sb14): host
2020-04-05 14:51:13.065 INFO: [55] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 epId=35d54701 local_ufrag=1g23b1e552sb14] TlsClientImpl.notifyServerVersion#165: Negotiated DTLS version DTLS 1.2
2020-04-05 14:51:13.212 INFO: [55] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] DtlsTransport.lambda$new$0#145: DTLS handshake complete. Got SRTP profile 1
2020-04-05 14:51:13.214 INFO: [56] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] Endpoint.lambda$null$5#787: Attempting to establish SCTP socket connection
Got sctp association state update: 1
sctp is now up. was ready? false
2020-04-05 14:51:13.416 INFO: [56] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] Endpoint$2.onReady#727: SCTP connection is ready, creating the Data channel stack
2020-04-05 14:51:13.423 INFO: [56] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] Endpoint$2.onReady#754: Will wait for the remote side to open the data channel.
2020-04-05 14:51:13.429 INFO: [58] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] DataChannelStack.onIncomingDataChannelPacket#62: Received data channel open message
2020-04-05 14:51:13.432 INFO: [58] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] Endpoint$2.lambda$onReady$1#735: Remote side opened a data channel.
2020-04-05 14:51:15.094 INFO: [43] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 ufrag=3skb41e552sabe epId=968df43b local_ufrag=3skb41e552sabe] Agent.setState#962: ICE state changed from Completed to Terminated. Local ufrag 3skb41e552sabe
2020-04-05 14:51:15.095 INFO: [43] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] IceTransport.iceStateChange#605: ICE state changed old=Completed new=Terminated
Got sctp association state update: 1
sctp is now up. was ready? false
2020-04-05 14:51:15.703 INFO: [46] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] Endpoint$2.onReady#727: SCTP connection is ready, creating the Data channel stack
2020-04-05 14:51:15.704 INFO: [46] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] Endpoint$2.onReady#754: Will wait for the remote side to open the data channel.
2020-04-05 14:51:15.705 INFO: [56] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] DataChannelStack.onIncomingDataChannelPacket#62: Received data channel open message
2020-04-05 14:51:15.706 INFO: [56] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] Endpoint$2.lambda$onReady$1#735: Remote side opened a data channel.
2020-04-05 14:51:15.924 INFO: [43] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1 ufrag=1g23b1e552sb14 epId=35d54701 local_ufrag=1g23b1e552sb14] Agent.setState#962: ICE state changed from Completed to Terminated. Local ufrag 1g23b1e552sb14
2020-04-05 14:51:15.925 INFO: [43] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] IceTransport.iceStateChange#605: ICE state changed old=Completed new=Terminated
2020-04-05 14:51:20.759 INFO: [18] Videobridge.createConference#326: create_conf, id=98c6993ee62298a1 gid=null logging=false
2020-04-05 14:51:20.780 INFO: [18] Health.doRun#294: Performed a successful health check in 23ms. Sticky failure: false
2020-04-05 14:51:28.013 INFO: [17] VideobridgeExpireThread.expire#144: Running expire()
2020-04-05 14:51:30.781 INFO: [18] Videobridge.createConference#326: create_conf, id=7ec48d06effe3f6f gid=null logging=false
2020-04-05 14:51:30.797 INFO: [18] Health.doRun#294: Performed a successful health check in 17ms. Sticky failure: false
2020-04-05 14:51:34.462 WARNING: [58] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] AbstractEndpointMessageTransport.onClientEndpointMessage#219: Unable to find endpoint 67cc6233 to send EndpointMessage
2020-04-05 14:51:34.591 WARNING: [58] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] AbstractEndpointMessageTransport.onClientEndpointMessage#219: Unable to find endpoint 67cc6233 to send EndpointMessage
2020-04-05 14:51:35.411 INFO: [30] [confId=1e43248be3d23d17 epId=67cc6233 gid=fff0c1 conf_name=test1 ufrag=5qtvt1e552t1ni] Agent.gatherCandidates#660: Gathering candidates for component stream-67cc6233.RTP. Local ufrag 5qtvt1e552t1ni
2020-04-05 14:51:35.417 INFO: [30] [confId=1e43248be3d23d17 epId=67cc6233 local_ufrag=5qtvt1e552t1ni gid=fff0c1 conf_name=test1] DtlsTransport.lambda$startConnectivityEstablishment$1#181: Ignoring empty DtlsFingerprint extension:
2020-04-05 14:51:36.234 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV componentId=1 conf_name=test1 ufrag=5qtvt1e552t1ni name=stream-67cc6233 epId=67cc6233 local_ufrag=5qtvt1e552t1ni] Component.addRemoteCandidate#330: Add remote candidate for stream-67cc6233.RTP: 10.47.18.107:41252/udp/host
2020-04-05 14:51:36.234 INFO: [42] [confId=1e43248be3d23d17 epId=67cc6233 local_ufrag=5qtvt1e552t1ni gid=fff0c1 stats_id=Jordi-8AV conf_name=test1] IceTransport.startConnectivityEstablishment#404: Starting the agent with remote candidates.
2020-04-05 14:51:36.235 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1 ufrag=5qtvt1e552t1ni epId=67cc6233 local_ufrag=5qtvt1e552t1ni] Agent.startConnectivityEstablishment#751: Start ICE connectivity establishment. Local ufrag 5qtvt1e552t1ni
2020-04-05 14:51:36.235 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1 ufrag=5qtvt1e552t1ni epId=67cc6233 local_ufrag=5qtvt1e552t1ni] Agent.initCheckLists#995: Init checklist for stream stream-67cc6233
2020-04-05 14:51:36.238 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1 ufrag=5qtvt1e552t1ni epId=67cc6233 local_ufrag=5qtvt1e552t1ni] Agent.setState#962: ICE state changed from Waiting to Running. Local ufrag 5qtvt1e552t1ni
2020-04-05 14:51:36.240 INFO: [42] [confId=1e43248be3d23d17 epId=67cc6233 local_ufrag=5qtvt1e552t1ni gid=fff0c1 stats_id=Jordi-8AV conf_name=test1] IceTransport.iceStateChange#605: ICE state changed old=Waiting new=Running
2020-04-05 14:51:36.241 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1 ufrag=5qtvt1e552t1ni epId=67cc6233 local_ufrag=5qtvt1e552t1ni] ConnectivityCheckClient.startChecks#142: Start connectivity checks. Local ufrag 5qtvt1e552t1ni
2020-04-05 14:51:36.254 INFO: [30] [confId=1e43248be3d23d17 epId=67cc6233 local_ufrag=5qtvt1e552t1ni gid=fff0c1 stats_id=Jordi-8AV conf_name=test1] DtlsTransport.startConnectivityEstablishment#195: The remote side is acting as DTLS server, we’ll act as client
2020-04-05 14:51:36.264 INFO: [53] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1 ufrag=5qtvt1e552t1ni epId=67cc6233 local_ufrag=5qtvt1e552t1ni] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.0.0.18:10000/udp/host -> 10.47.18.107:41252/udp/host (stream-67cc6233.RTP)
2020-04-05 14:51:40.799 INFO: [18] Videobridge.createConference#326: create_conf, id=ba3e7c42fcca5fe3 gid=null logging=false
2020-04-05 14:51:40.839 INFO: [18] Health.doRun#294: Performed a successful health check in 41ms. Sticky failure: false
2020-04-05 14:51:50.841 INFO: [18] Videobridge.createConference#326: create_conf, id=4b5a1d71d0da7587 gid=null logging=false
2020-04-05 14:51:50.858 INFO: [18] Health.doRun#294: Performed a successful health check in 17ms. Sticky failure: false
2020-04-05 14:52:00.859 INFO: [18] Videobridge.createConference#326: create_conf, id=6d4811b2e6dfa9d7 gid=null logging=false
2020-04-05 14:52:00.872 INFO: [18] Health.doRun#294: Performed a successful health check in 14ms. Sticky failure: false
2020-04-05 14:52:07.348 INFO: [30] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] AbstractEndpoint.expire#301: Expiring.
2020-04-05 14:52:07.361 INFO: [30] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H componentId=1 conf_name=test1 ufrag=1g23b1e552sb14 name=stream-35d54701 epId=35d54701 local_ufrag=1g23b1e552sb14] MergingDatagramSocket.close#142: Closing.
2020-04-05 14:52:07.361 INFO: [31] [confId=1e43248be3d23d17 epId=35d54701 local_ufrag=1g23b1e552sb14 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] DtlsTransport.lambda$installIncomingPacketReader$3#453: Socket closed, stopping reader.
2020-04-05 14:52:07.362 INFO: [49] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Tracy-l4H componentId=1 conf_name=test1 ufrag=1g23b1e552sb14 name=stream-35d54701 epId=35d54701 local_ufrag=1g23b1e552sb14] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
2020-04-05 14:52:07.363 INFO: [30] [confId=1e43248be3d23d17 epId=35d54701 gid=fff0c1 stats_id=Tracy-l4H conf_name=test1] Endpoint.expire#649: Expired.
2020-04-05 14:52:10.873 INFO: [18] Videobridge.createConference#326: create_conf, id=905385587d6b436e gid=null logging=false
2020-04-05 14:52:10.885 INFO: [18] Health.doRun#294: Performed a successful health check in 13ms. Sticky failure: false
2020-04-05 14:52:20.886 INFO: [18] Videobridge.createConference#326: create_conf, id=e1f6702de6844ed gid=null logging=false
2020-04-05 14:52:20.899 INFO: [18] Health.doRun#294: Performed a successful health check in 13ms. Sticky failure: false
2020-04-05 14:52:24.531 INFO: [42] [confId=1e43248be3d23d17 epId=67cc6233 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1] AbstractEndpoint.expire#301: Expiring.
2020-04-05 14:52:24.535 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1 ufrag=5qtvt1e552t1ni epId=67cc6233 local_ufrag=5qtvt1e552t1ni] Agent.setState#962: ICE state changed from Running to Terminated. Local ufrag 5qtvt1e552t1ni
2020-04-05 14:52:24.536 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Jordi-8AV componentId=1 conf_name=test1 ufrag=5qtvt1e552t1ni name=stream-67cc6233 epId=67cc6233 local_ufrag=5qtvt1e552t1ni] MergingDatagramSocket.close#142: Closing.
2020-04-05 14:52:24.536 INFO: [42] [confId=1e43248be3d23d17 epId=67cc6233 gid=fff0c1 stats_id=Jordi-8AV conf_name=test1] Endpoint.expire#649: Expired.
2020-04-05 14:52:24.654 INFO: [58] [confId=1e43248be3d23d17 gid=fff0c1 conf_name=test1] Conference.dominantSpeakerChanged#446: ds_change ds_id=968df43b
2020-04-05 14:52:28.013 INFO: [17] VideobridgeExpireThread.expire#144: Running expire()
2020-04-05 14:52:28.069 INFO: [42] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] AbstractEndpoint.expire#301: Expiring.
2020-04-05 14:52:28.069 INFO: [58] [confId=1e43248be3d23d17 gid=fff0c1 conf_name=test1] Conference.dominantSpeakerChanged#446: ds_change ds_id=null
2020-04-05 14:52:28.081 INFO: [39] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd componentId=1 conf_name=test1 ufrag=3skb41e552sabe name=stream-968df43b epId=968df43b local_ufrag=3skb41e552sabe] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
2020-04-05 14:52:28.081 INFO: [42] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd componentId=1 conf_name=test1 ufrag=3skb41e552sabe name=stream-968df43b epId=968df43b local_ufrag=3skb41e552sabe] MergingDatagramSocket.close#142: Closing.
2020-04-05 14:52:28.082 WARNING: [39] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd componentId=1 conf_name=test1 ufrag=3skb41e552sabe name=stream-968df43b epId=968df43b local_ufrag=3skb41e552sabe] MergingDatagramSocket.doRemove#349: Removing the active socket. Won’t be able to send until a new one is elected.
2020-04-05 14:52:28.083 INFO: [42] [confId=1e43248be3d23d17 epId=968df43b gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] Endpoint.expire#649: Expired.
2020-04-05 14:52:28.083 INFO: [32] [confId=1e43248be3d23d17 gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1 epId=968df43b local_ufrag=3skb41e552sabe] TlsClientImpl.notifyAlertReceived#211: close_notify received, connection closing
2020-04-05 14:52:28.083 SEVERE: [32] [confId=1e43248be3d23d17 epId=968df43b local_ufrag=3skb41e552sabe gid=fff0c1 stats_id=Kenneth-kWd conf_name=test1] DtlsTransport$SocketSenderNode.consume#653: Error sending packet: java.io.IOException: No active socket.
2020-04-05 14:52:30.900 INFO: [18] Videobridge.createConference#326: create_conf, id=3af5b37fbb6f2e4f gid=null logging=false
2020-04-05 14:52:30.913 INFO: [18] Health.doRun#294: Performed a successful health check in 14ms. Sticky failure: false
2020-04-05 14:52:40.915 INFO: [18] Videobridge.createConference#326: create_conf, id=8d9f2acc37be0e71 gid=null logging=false
2020-04-05 14:52:40.932 INFO: [18] Health.doRun#294: Performed a successful health check in 18ms. Sticky failure: false
2020-04-05 14:52:50.933 INFO: [18] Videobridge.createConference#326: create_conf, id=bc58c27afee1a8d1 gid=null logging=false
2020-04-05 14:52:50.948 INFO: [18] Health.doRun#294: Performed a successful health check in 16ms. Sticky failure: false

Gu1ll0me · April 5, 2020, 5:51pm

Same problem here on a Jitsi installation using the latest stable. Our server are not behind NAT and we’re experiencing Pair failed messages in the jvb.log file as soon as a third user join the room. I tried a lot of solutions found on the forum without success. We actually run on old snapshot (JVBv1) and it works great. I hope that we could use JVBv2 soon.

Even with these problems with the latest version, I would like to thank the Jitsi team for his extraordinary work in this particular context. Jitsi Meet is experiencing an extraordinary boom in use and it’s impressive to see all the team on the forum trying to answer all these posts and also working on the code in the same time. We all owe you a pint of your favorite beer!

tuo.mas · April 11, 2020, 9:11am

Hello,

I am having also the same issue. I have installed a clean setup on a Debian 10 VPS with a public IP address and Let’s Encrypt certificate.

Initially I had problems only with some clients connecting from corporate network but the problem can be reproduced to all clients by blocking the udp/10000 on the server firewall. Turnserver does not seem to kick in.

Anyone got this solved?

damencho · April 11, 2020, 1:49pm

@tuo.mas By any chance you have secure domain configured? And the problem appear for the guests?

tuo.mas · April 11, 2020, 11:33pm

For this test instance I wanted to make completely clean fresh install without any customisations. No authentication, just everything from quick start guide plus udp/10000 block in the firewall. End result is that when 3rd joins, the audio and video is not working.

Gu1ll0me · April 11, 2020, 6:41pm

We are on the same boat. Tried with last stable today and same Pair failed error. It seems to be the only problematic message in the jvb.log. I tried all kinds of solutions in the forum but without success. It must be an insignificant detail but I can’t find it.

We run an old version of Jitsi here (JVBv1) and everything works well.

Wavex · April 17, 2020, 5:02am

Hi good people, anyone found a solution to “ConnectivityCheckClient $PaceMaker.run#919: Pair failed:” which leads to 3rd+ video calls loosing audio and video?
I’ve setup secure domain and my server is directly on the internet so I find it strange this behavior.
Kindly advise.

t212 · April 19, 2020, 1:17pm

@damencho Yes I have secure domain configured. The problem appears for guest and the one moderator in the room.

do-d · April 19, 2020, 1:51pm

i configured secure domain
host is LDAP user
2nd user is guest
3rd user is guest

host and 1 guest- ok
host + 2 guests - all 3 losing AV

MBU · April 19, 2020, 4:05pm

Hi all,
I’m here the 1st time
installed on a German Strato VServer
used the actual “Quick Install Guide” from Github
after some trouble with the hostname (hxxxxxxx.stratoserver.net is bad for LetsEncrypt, changing hostname of server is officially impossible) it workes - but only with 2 users.
Reason is this text in the quick install guide:
…
Open ports in your firewall
Open the following ports in your firewall, to allow traffic to the machine running jitsi:
80 TCP
443 TCP
10000 UDP
…
here, the port 4443 is missing !!!
adding it, reboot, and it works with 3 users, too…
kind regards, keep healthy
Michael

t212 · April 20, 2020, 2:39am

This worked for me. Thanks @MBU

Ferran_Munoz · May 19, 2020, 4:13pm

This solution didn’t work for me…

EDIT: Sorry, I looked the advanced configuration of install, I put the ports correctly and work like a charm.