Jitsi not sending video

avrutg · October 20, 2022, 4:49pm

Hello! I am running Windows. I have a Hyper-V Debian virtual machine, in which I set up Jitsi according to quick setup guide. The virtual machine is behind NAT. In this virtual machine I also set up tailscale, which is a VPN service, in order to circumvent the NAT. Tailscale also gives you SSL certificates signed by Let’s Encrypt, which allows you to host HTTPS-sites still accessible only inside your VPN. My iOS devices can connect to the Jitsi server set up this way. My problem is that I can’t send any video (screen sharing, camera) from Jitsi running in browser on Windows PC to iOS devices (I didn’t test with Android). iOS devices can send video both between themselves and to the browser client. It only doesn’t work one way. What can I do to investigate this problem? All three devices (two iOS devices and the browser client) are in the same virtual tailscale network AND same physical network

Freddie · October 20, 2022, 4:53pm

Welcome to the forum.

Does that VPN allow UDP traffic?

avrutg · October 20, 2022, 4:56pm

It uses WireGuard internally, so yeah

avrutg · October 20, 2022, 5:09pm

Now I also tested with the electron client and the issue still persists. I think it may have to do with both server and browser client sharing the same IP address in the physical network. What would be the way for the Jitsi server to announce it has a different public IP address (the one given by the VPN)?

damencho · October 20, 2022, 5:35pm

You can manually specify the public ip of the bridge Self-Hosting Guide - Debian/Ubuntu server | Jitsi Meet

avrutg · October 20, 2022, 6:10pm

Thank you for help! What would org.ice4j.ice.harvest.NAT_HARVESTER_LOCAL_ADDRESS=<Local.IP.Address> be in my case? I did some further testing and even if I connect the virtual machine directly to the network interface (it’s not NATed within the local network), the issue still persists. So now I don’t know if it has to do with NAT. Do I specify the IP address given by my router?

avrutg · October 20, 2022, 6:24pm

I did exactly what’s written in the guide (local address as gived by router, public address as given by VPN) and it didn’t change anything. So it probably doesn’t have to do with NAT

damencho · October 20, 2022, 7:12pm

I don’t understand your topology … but when it is automatic the jvb advertises its public IP address and you need to make sure you have port forwarding of UDP 10000 to the jvb the same way you have port 443 TCP that reaches nginx.
To test use 3 tabs in the browser opening the same link.
Make sure you have these settings turned on in your config.js

as missing those may cause issues on mobile. That is fixed and will be available on the next stable release.

emrah · October 21, 2022, 7:27am

What are

Jitsi server IP
VPN IP
External IP (don’t forget to mask it)
Is there any other IP?

avrutg · October 21, 2022, 3:57pm

I have the following topology:

A windows PC with Edge browser
A Debian virtual machine on the same windows PC
Two iOS devices

All of them are on the same local network and additionally on the same VPN network (the VPN doesn’t route all traffic, only when told to so, eg by trying to access another device by using its VPN IP address)

Initially, when I made this post, the Debian virtual machine was running behind Hyper-V’s NAT. Later on, I decided to exclude this factor connected it directly to the physical network (but sharing it with Windows). Now, all the devices have different IP addresses given by router, so there is no NAT involved.

The VPN I use isn’t supposed to connect to the internet, only between the devices themselves.

The VPN also provides DNS service, which gives all the devices URL addresses, which only work inside of it. It can also give certificates for these URL addresses. So inside this VPN network I can host HTTPS resources.

Now, voice chat works between all the devices. The problems are with video. What doesn’t work:

iPhone<>iPad (works both ways)
iPhone>Edge on Windows PC (only works one way. I can send video from iPhone to the PC. I can’t do it vice-versa)
iPad>Edge on Windows PC (the same situation)

All 4 devices (3 physical and 1 virtual) have 3 IP addresses:

Local (given by Router)
Given by VPN
Router’s IP address (seen in the Internet). The router is also behind NAT

I hope you will be able to help me

P.S. I also tested with an android device. It can also send and receive video streams from other devices. Now I am even more convinced that the issue is not with the server’s configuration, but with the browser client)

avrutg · October 21, 2022, 3:58pm

Can you please look at the reply I gave to another person?

damencho · October 21, 2022, 4:42pm

You need to open and forward udp 10000 from your router to the jvb (the virtual machine), as this is on windows I guess you need to take care and of any firewall there and also any firewall on the debian machine.

avrutg · October 21, 2022, 5:23pm

I’m sorry, but I don’t understand you. Forward from where? It all happens in LAN. So for example, I make all connections to the port 10000 on my router go to the port 10000 of the virtual machine. But would that make sense? Since all the clients can connect directly to the virtual machine. Also all the clients can talk between each other without issues, except for the windows browser client.

Also, now I thought, why not test the browser client on other devices? I tested it on an iPad and I also can’t see myself from other devices. Native Jitsi app on the same iPad can send video to other devices with no problems.

Now the situation is that the browser client can’t send video to other devices, while native clients on iOS and Android can.

I don’t think it’s an issue with firewalls What further information can I provide?

damencho · October 21, 2022, 5:35pm

cat /var/log/jitsi/jvb.log | grep MappingCandidateHarvester
What is the local address you see? Do clients have access to it? Are you sure windows is not blocking access to that address on port 10000?
No video or audio is a problem when clients are not able to access port 10000 of jvb. This is normally a firewall or port forwarding issue.

avrutg · October 21, 2022, 6:08pm

Weirdly, there are no logs from today. Maybe it’s because I didn’t set the settings you told me to?

“org.ice4j.ice.harvest.NAT_HARVESTER_LOCAL_ADDRESS=<Local.IP.Address>
org.ice4j.ice.harvest.NAT_HARVESTER_PUBLIC_ADDRESS=<Public.IP.Address>”

These ones. I tried setting them, but it didn’t help, so I returned to the stock configuration. Maybe I set them wrong, but then it means I don’t understand which address should go where in this case.

Also, now I did some more additional testing. This time a person in another country, connected to the same VPN, joined my Jitsi instance. They were able to share video and I could see them do it. I also could share my video, from Jitsi mobile app, but I couldn’t from the browser! So it works even across different countries and the only problem is sending video from browser client

damencho · October 21, 2022, 6:46pm

The thing I wanted you to check is printed on jvb start when it reads and configures the IP addresses to use. If those change, you need to restart jvb.

avrutg · October 21, 2022, 7:56pm

I deleted the log, to make sure there’s no extra info, restarted the virtual machine, tried doing a test meeting and here’s the output of cat /var/log/jitsi/jvb.log | grep MappingCandidateHarvester after these manipulations

at org.ice4j.ice.harvest.MappingCandidateHarvesters.createStunHarvesters(MappingCandidateHarvesters.java:283)
at org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize(MappingCandidateHarvesters.java:141)

damencho · October 21, 2022, 8:00pm

Open the file and share it here, there is some error and it cannot discover its addresses …

avrutg · October 21, 2022, 8:06pm

JVB 2022-10-21 22:49:09.051 INFO: [1] JitsiConfig.#47: Initialized newConfig: merge of /etc/jitsi/videobridge/jvb.conf: 1,application.conf @ jar:file:/usr/share/jitsi-videobridge/jitsi-videobridge.jar!/application.conf: 1,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/ice4j-3.0-58-gf41542d.jar!/reference.conf: 1,reference.conf @ jar:file:/usr/share/jitsi-videobridge/lib/jitsi-media-transform-2.2-45-ge8b20f06.jar!/reference.conf: 1
JVB 2022-10-21 22:49:09.090 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#51: loading config file at path /etc/jitsi/videobridge/sip-communicator.properties
JVB 2022-10-21 22:49:09.104 INFO: [1] JitsiConfig.#68: Initialized legacyConfig: sip communicator props (no description provided)
JVB 2022-10-21 22:49:09.127 INFO: [1] JitsiConfig$Companion.reloadNewConfig#94: Reloading the Typesafe config source (previously reloaded 0 times).
JVB 2022-10-21 22:49:09.169 INFO: [1] MainKt.main#90: Starting jitsi-videobridge version 2.2.45-ge8b20f06
Exception in thread “Thread-0” java.lang.IllegalArgumentException
at java.base/java.util.concurrent.ThreadPoolExecutor.(ThreadPoolExecutor.java:1293)
at java.base/java.util.concurrent.ThreadPoolExecutor.(ThreadPoolExecutor.java:1215)
at org.jitsi.utils.concurrent.ExecutorFactory.createFixedThreadPool(ExecutorFactory.java:133)
at org.ice4j.ice.harvest.MappingCandidateHarvesters.createStunHarvesters(MappingCandidateHarvesters.java:283)
at org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize(MappingCandidateHarvesters.java:141)
at org.jitsi.videobridge.MainKt$startIce4j$1.invoke(Main.kt:239)
at org.jitsi.videobridge.MainKt$startIce4j$1.invoke(Main.kt:238)
at kotlin.concurrent.ThreadsKt$thread$thread$1.run(Thread.kt:30)
JVB 2022-10-21 22:49:10.206 INFO: [14] [hostname=localhost id=shard] MucClient.initializeConnectAndJoin#280: Initializing a new MucClient for [ org.jitsi.xmpp.mucclient.MucClientConfiguration id=shard domain=auth.vafajitsi.tailb5240.ts.net hostname=localhost port=null username=jvb mucs=[JvbBrewery@internal.auth.vafajitsi.tailb5240.ts.net] mucNickname=eae05a57-7b90-4358-9d07-571eb85600e1 disableCertificateVerification=false]
JVB 2022-10-21 22:49:10.280 INFO: [1] LastNReducer.#65: LastNReducer with reductionScale: 0.75 recoverScale: 1.25 impactTime: PT1M minLastN: 1 maxEnforcedLastN: 40
JVB 2022-10-21 22:49:10.309 INFO: [1] TaskPools.#87: TaskPools detected 3 processors, creating the CPU pool with that many threads
JVB 2022-10-21 22:49:10.337 INFO: [1] HealthChecker.start#117: Started with interval=60000, timeout=PT1M30S, maxDuration=PT3S, stickyFailures=false.
JVB 2022-10-21 22:49:10.422 INFO: [1] UlimitCheck.printUlimits#116: Running with open files limit 65000 (hard 65000), thread limit 65000 (hard 65000).
JVB 2022-10-21 22:49:10.426 INFO: [1] VideobridgeExpireThread.start#88: Starting with 60 second interval.
JVB 2022-10-21 22:49:10.505 INFO: [1] MainKt.main#120: Not starting CallstatsService, disabled in configuration.
JVB 2022-10-21 22:49:10.508 INFO: [1] MainKt.main#129: Starting public http server
JVB 2022-10-21 22:49:10.635 INFO: [1] ColibriWebSocketService.#46: Base URL: wss://vafajitsi.tailb5240.ts.net:443/colibri-ws/default-id Relay URL: null
JVB 2022-10-21 22:49:10.683 INFO: [14] [hostname=localhost id=shard] MucClient.initializeConnectAndJoin#342: Dispatching a thread to connect and login.
JVB 2022-10-21 22:49:10.917 INFO: [1] ColibriWebSocketService.registerServlet#85: Registering servlet with baseUrl = wss://vafajitsi.tailb5240.ts.net:443/colibri-ws/default-id, relayUrl = null
JVB 2022-10-21 22:49:10.960 INFO: [1] org.eclipse.jetty.server.Server.doStart: jetty-11.0.10; built: 2022-06-16T20:42:17.891Z; git: d21dded5817960ec3c753a7ba02ef86f7c9ed89e; jvm 11.0.16+8-post-Debian-1deb11u1
JVB 2022-10-21 22:49:11.122 INFO: [14] [hostname=localhost id=shard] MucClient$2.connected#313: Connected.
JVB 2022-10-21 22:49:11.122 INFO: [14] [hostname=localhost id=shard] MucClient.lambda$getConnectAndLoginCallable$9#638: Logging in.
JVB 2022-10-21 22:49:11.173 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@78f9ed3e{/,null,AVAILABLE}
JVB 2022-10-21 22:49:11.214 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@39c11e6c{HTTP/1.1, (http/1.1)}{0.0.0.0:9090}
JVB 2022-10-21 22:49:11.253 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@3fc08eec{STARTING}[11.0.10,sto=0] @3176ms
JVB 2022-10-21 22:49:11.254 INFO: [1] MainKt.main#147: Starting private http server
JVB 2022-10-21 22:49:11.322 INFO: [14] [hostname=localhost id=shard] MucClient$2.authenticated#319: Authenticated, b=false
JVB 2022-10-21 22:49:11.442 INFO: [1] org.eclipse.jetty.server.Server.doStart: jetty-11.0.10; built: 2022-06-16T20:42:17.891Z; git: d21dded5817960ec3c753a7ba02ef86f7c9ed89e; jvm 11.0.16+8-post-Debian-1deb11u1
JVB 2022-10-21 22:49:11.476 INFO: [14] [hostname=localhost id=shard] MucClient$MucWrapper.join#763: Joined MUC: jvbbrewery@internal.auth.vafajitsi.tailb5240.ts.net
JVB 2022-10-21 22:49:11.959 WARNING: [1] org.glassfish.jersey.server.wadl.WadlFeature.configure: JAXBContext implementation could not be found. WADL feature is disabled.
JVB 2022-10-21 22:49:12.108 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.Health registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.Health will be ignored.
JVB 2022-10-21 22:49:12.108 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.Version registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.Version will be ignored.
JVB 2022-10-21 22:49:12.108 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.prometheus.Prometheus registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.prometheus.Prometheus will be ignored.
JVB 2022-10-21 22:49:12.620 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@712c5463{/,null,AVAILABLE}
JVB 2022-10-21 22:49:12.622 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@47c64cfe{HTTP/1.1, (http/1.1)}{127.0.0.1:8080}
JVB 2022-10-21 22:49:12.622 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@175acfb2{STARTING}[11.0.10,sto=0] @4545ms
JVB 2022-10-21 22:52:56.000 INFO: [43] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net] EndpointConnectionStatusMonitor.start#58: Starting connection status monitor
JVB 2022-10-21 22:52:56.001 INFO: [43] Videobridge.createConference#302: create_conf, id=d8a458178edec4db meetingId=3dda7d06-ad53-4093-8d46-6773bbf1fea1
JVB 2022-10-21 22:52:56.060 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address [fd7a:115c:a1e0:ab12:4843:cd96:6245:ea5c]:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:52:56.061 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address [fd7a:115c:a1e0:ab12:4843:cd96:6245:ea5c]:10000/udp
JVB 2022-10-21 22:52:56.061 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address 100.69.234.92:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:52:56.064 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address 100.69.234.92:10000/udp
JVB 2022-10-21 22:52:56.065 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:52:56.066 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp
JVB 2022-10-21 22:52:56.066 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address 192.168.1.170:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:52:56.066 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address 192.168.1.170:10000/udp
JVB 2022-10-21 22:52:56.085 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.gatherCandidates#647: Gathering candidates for component stream-d16aa9db.RTP.
JVB 2022-10-21 22:52:56.877 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db] Endpoint.#344: Created new endpoint isUsingSourceNames=false, iceControlling=true
JVB 2022-10-21 22:52:56.890 INFO: [44] SctpManager.#62: Initializing Sctp4j
SCTP JNI load: Linux OS detected
SCTP lib loaded
=====>: org_jitsi_modified_sctp4j_SctpJni.c calling init
JVB 2022-10-21 22:52:56.944 WARNING: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net] Conference.lambda$new$0#258: Took 937 ms to process an IQ (total delay 965 ms):
JVB 2022-10-21 22:52:57.182 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.gatherCandidates#647: Gathering candidates for component stream-a5c43ac4.RTP.
JVB 2022-10-21 22:52:57.185 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4] Endpoint.#344: Created new endpoint isUsingSourceNames=true, iceControlling=true
JVB 2022-10-21 22:52:57.828 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we’ll act as server
JVB 2022-10-21 22:52:57.849 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
JVB 2022-10-21 22:52:57.849 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
JVB 2022-10-21 22:52:57.850 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.initCheckLists#972: Init checklist for stream stream-a5c43ac4
JVB 2022-10-21 22:52:57.853 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.setState#946: ICE state changed from Waiting to Running.
JVB 2022-10-21 22:52:57.854 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
JVB 2022-10-21 22:52:57.854 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.startConnectivityEstablishment#758: Trigger checks for pairs that were received before running state
JVB 2022-10-21 22:52:57.854 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7961835276030973439):
LocalCandidate=candidate:4 1 udp 2113932031 192.168.1.170 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1853759231 192.168.1.100 52999 typ prflx
JVB 2022-10-21 22:52:57.855 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):
LocalCandidate=candidate:2 1 udp 2130706431 100.69.234.92 10000 typ host
RemoteCandidate=candidate:10003 1 udp 1853824767 100.97.130.11 52998 typ prflx
JVB 2022-10-21 22:52:57.855 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
JVB 2022-10-21 22:52:57.897 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 192.168.1.170:10000/udp/host → 192.168.1.100:52999/udp/prflx (stream-a5c43ac4.RTP).
JVB 2022-10-21 22:52:57.897 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu name=stream-a5c43ac4 componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 192.168.1.100:52999/udp
JVB 2022-10-21 22:52:57.897 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 192.168.1.170:10000/udp/host → 192.168.1.100:52999/udp/prflx (stream-a5c43ac4.RTP).
JVB 2022-10-21 22:52:57.898 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 192.168.1.170:10000/udp/host → 192.168.1.100:52999/udp/prflx (stream-a5c43ac4.RTP)
JVB 2022-10-21 22:52:57.898 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.nominate#1810: verify if nominated pair answer again
JVB 2022-10-21 22:52:57.900 WARNING: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu name=stream-a5c43ac4 componentId=1] MergingDatagramSocket.initializeActive#599: Active socket already initialized.
JVB 2022-10-21 22:52:57.900 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 100.69.234.92:10000/udp/host → 100.97.130.11:52998/udp/prflx (stream-a5c43ac4.RTP).
JVB 2022-10-21 22:52:57.907 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2022-10-21 22:52:57.908 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu name=stream-a5c43ac4 componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 100.97.130.11:52998/udp
JVB 2022-10-21 22:52:57.908 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 100.69.234.92:10000/udp/host → 100.97.130.11:52998/udp/prflx (stream-a5c43ac4.RTP).
JVB 2022-10-21 22:52:57.910 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2022-10-21 22:52:57.922 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 192.168.1.170:10000/udp/host → 192.168.1.100:52999/udp/prflx (stream-a5c43ac4.RTP).
JVB 2022-10-21 22:52:57.922 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
JVB 2022-10-21 22:52:57.922 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: 192.168.1.170:10000/udp/host → 192.168.1.100:52999/udp/prflx (stream-a5c43ac4.RTP).
JVB 2022-10-21 22:52:57.922 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu name=stream-a5c43ac4] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-a5c43ac4.RTP: 192.168.1.170:10000/udp/host → 192.168.1.100:52999/udp/prflx (stream-a5c43ac4.RTP)
JVB 2022-10-21 22:52:57.923 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.checkListStatesUpdated#1901: CheckList of stream stream-a5c43ac4 is COMPLETED
JVB 2022-10-21 22:52:57.923 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.setState#946: ICE state changed from Running to Completed.
JVB 2022-10-21 22:52:57.923 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu] IceTransport.iceStateChanged#342: ICE state changed old=Running new=Completed
JVB 2022-10-21 22:52:57.923 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] Endpoint$setupIceTransport$2.connected#394: ICE connected
JVB 2022-10-21 22:52:57.926 INFO: [65] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@cb954e7
JVB 2022-10-21 22:52:57.927 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.logCandTypes#2009: Harvester used for selected pair for stream-a5c43ac4.RTP: host
JVB 2022-10-21 22:52:57.930 INFO: [50] PartitionedByteBufferPool.#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
JVB 2022-10-21 22:52:57.931 INFO: [50] PartitionedByteBufferPool.#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
JVB 2022-10-21 22:52:57.931 INFO: [50] PartitionedByteBufferPool.#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
JVB 2022-10-21 22:52:57.935 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu name=stream-a5c43ac4 componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-a5c43ac4.RTP: 100.97.130.11:52998/udp
JVB 2022-10-21 22:52:57.935 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu name=stream-a5c43ac4 componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-a5c43ac4.RTP: 192.168.1.100:52999/udp
JVB 2022-10-21 22:52:57.935 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu name=stream-a5c43ac4 componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-a5c43ac4.RTP: 172.31.16.1:53000/udp
JVB 2022-10-21 22:52:57.951 INFO: [65] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
JVB 2022-10-21 22:52:57.971 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we’ll act as server
JVB 2022-10-21 22:52:57.972 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
JVB 2022-10-21 22:52:57.972 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
JVB 2022-10-21 22:52:57.972 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.initCheckLists#972: Init checklist for stream stream-d16aa9db
JVB 2022-10-21 22:52:57.972 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.setState#946: ICE state changed from Waiting to Running.
JVB 2022-10-21 22:52:57.972 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
JVB 2022-10-21 22:52:57.972 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.startConnectivityEstablishment#758: Trigger checks for pairs that were received before running state
JVB 2022-10-21 22:52:57.972 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962138741240254975):
LocalCandidate=candidate:3 1 udp 2113939711 fd44:b6aa:5134:0:215:5dff:fe01:6401 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1853829887 fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3 62435 typ prflx
JVB 2022-10-21 22:52:57.973 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7961835276030973439):
LocalCandidate=candidate:4 1 udp 2113932031 192.168.1.170 10000 typ host
RemoteCandidate=candidate:10001 1 udp 1853759231 192.168.1.197 62482 typ prflx
JVB 2022-10-21 22:52:57.973 INFO: [44] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
JVB 2022-10-21 22:52:58.002 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 192.168.1.170:10000/udp/host → 192.168.1.197:62482/udp/prflx (stream-d16aa9db.RTP).
JVB 2022-10-21 22:52:58.002 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7 name=stream-d16aa9db componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 192.168.1.197:62482/udp
JVB 2022-10-21 22:52:58.002 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 192.168.1.170:10000/udp/host → 192.168.1.197:62482/udp/prflx (stream-d16aa9db.RTP).
JVB 2022-10-21 22:52:58.002 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 192.168.1.170:10000/udp/host → 192.168.1.197:62482/udp/prflx (stream-d16aa9db.RTP)
JVB 2022-10-21 22:52:58.002 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.nominate#1810: verify if nominated pair answer again
JVB 2022-10-21 22:52:58.003 WARNING: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7 name=stream-d16aa9db componentId=1] MergingDatagramSocket.initializeActive#599: Active socket already initialized.
JVB 2022-10-21 22:52:58.004 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2022-10-21 22:52:58.022 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 192.168.1.170:10000/udp/host → 192.168.1.197:62482/udp/prflx (stream-d16aa9db.RTP).
JVB 2022-10-21 22:52:58.023 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
JVB 2022-10-21 22:52:58.023 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: 192.168.1.170:10000/udp/host → 192.168.1.197:62482/udp/prflx (stream-d16aa9db.RTP).
JVB 2022-10-21 22:52:58.023 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7 name=stream-d16aa9db] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-d16aa9db.RTP: 192.168.1.170:10000/udp/host → 192.168.1.197:62482/udp/prflx (stream-d16aa9db.RTP)
JVB 2022-10-21 22:52:58.023 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.checkListStatesUpdated#1901: CheckList of stream stream-d16aa9db is COMPLETED
JVB 2022-10-21 22:52:58.024 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.setState#946: ICE state changed from Running to Completed.
JVB 2022-10-21 22:52:58.025 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7 name=stream-d16aa9db componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:62435/udp
JVB 2022-10-21 22:52:58.025 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7] IceTransport.iceStateChanged#342: ICE state changed old=Running new=Completed
JVB 2022-10-21 22:52:58.025 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] Endpoint$setupIceTransport$2.connected#394: ICE connected
JVB 2022-10-21 22:52:58.030 INFO: [68] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@110ea9de
JVB 2022-10-21 22:52:58.036 INFO: [68] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
JVB 2022-10-21 22:52:58.038 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.logCandTypes#2009: Harvester used for selected pair for stream-d16aa9db.RTP: host
JVB 2022-10-21 22:52:58.092 INFO: [65] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] Endpoint$setupDtlsTransport$3.handshakeComplete#438: DTLS handshake complete
JVB 2022-10-21 22:52:58.092 INFO: [68] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] Endpoint$setupDtlsTransport$3.handshakeComplete#438: DTLS handshake complete
JVB 2022-10-21 22:52:58.108 INFO: [70] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] Endpoint.acceptSctpConnection$lambda-23#670: Attempting to establish SCTP socket connection
JVB 2022-10-21 22:52:58.115 INFO: [72] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] Endpoint.acceptSctpConnection$lambda-23#670: Attempting to establish SCTP socket connection
Got sctp association state update: 1
Got sctp association state update: 1
sctp is now up. was ready? false
sctp is now up. was ready? false
JVB 2022-10-21 22:52:58.134 INFO: [62] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7 name=stream-d16aa9db componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: [fd7a:115c:a1e0:ab12:4843:cd96:6275:7539]:53559/udp
JVB 2022-10-21 22:52:58.150 INFO: [65] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net] Conference.recentSpeakersChanged#513: Recent speakers changed: [d16aa9db], dominant speaker changed: true silence:false
JVB 2022-10-21 22:52:58.196 INFO: [54] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7 name=stream-d16aa9db componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 100.117.117.57:58989/udp
JVB 2022-10-21 22:52:58.214 INFO: [70] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] Endpoint$createSctpConnection$3.onReady#618: SCTP connection is ready, creating the Data channel stack
JVB 2022-10-21 22:52:58.218 INFO: [70] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz] Endpoint$createSctpConnection$3.onReady#642: Will wait for the remote side to open the data channel.
JVB 2022-10-21 22:52:58.231 INFO: [72] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] Endpoint$createSctpConnection$3.onReady#618: SCTP connection is ready, creating the Data channel stack
JVB 2022-10-21 22:52:58.233 INFO: [72] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC] Endpoint$createSctpConnection$3.onReady#642: Will wait for the remote side to open the data channel.
JVB 2022-10-21 22:52:58.254 INFO: [74] JitsiOpenSslProvider.#52: jitsisrtp successfully loaded for OpenSSL 1.1
JVB 2022-10-21 22:52:58.272 INFO: [74] Aes.createCipher#433: Will employ AES implemented by OpenSSL for AES/CTR/NoPadding.
JVB 2022-10-21 22:52:58.273 INFO: [79] Aes.createCipher#433: Will employ AES implemented by OpenSSL for AES/GCM/NoPadding.
JVB 2022-10-21 22:53:00.928 INFO: [58] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu ufrag=78han1gfu1m5iu] Agent.setState#946: ICE state changed from Completed to Terminated.
JVB 2022-10-21 22:53:00.928 INFO: [58] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=a5c43ac4 stats_id=Dustin-IXz local_ufrag=78han1gfu1m5iu] IceTransport.iceStateChanged#342: ICE state changed old=Completed new=Terminated
JVB 2022-10-21 22:53:01.030 INFO: [58] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7 ufrag=1il0m1gfu1m4f7] Agent.setState#946: ICE state changed from Completed to Terminated.
JVB 2022-10-21 22:53:01.031 INFO: [58] [confId=d8a458178edec4db conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d16aa9db stats_id=Mittie-txC local_ufrag=1il0m1gfu1m4f7] IceTransport.iceStateChanged#342: ICE state changed old=Completed new=Terminated
JVB 2022-10-21 22:56:33.704 WARNING: [88] org.jivesoftware.smack.AbstractXMPPConnection.callConnectionClosedOnErrorListener: Connection XMPPTCPConnection[jvb@auth.vafajitsi.tailb5240.ts.net/PYeQcvDVst-N] (0) closed with error
org.jivesoftware.smack.XMPPException$StreamErrorException: system-shutdown You can read more about the meaning of this stream error at Extensible Messaging and Presence Protocol (XMPP): Core
stream:errorReceived SIGTERM</stream:error>
at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.parsePackets(XMPPTCPConnection.java:984)
at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.access$700(XMPPTCPConnection.java:916)
at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader$1.run(XMPPTCPConnection.java:939)
at java.base/java.lang.Thread.run(Thread.java:829)
JVB 2022-10-21 22:56:47.816 INFO: [1] JitsiConfig.#47: Initialized newConfig: merge of /etc/jitsi/videobridge/jvb.conf: 1,application.conf @ jar:file:/usr/share/jitsi-videobridge/jitsi-videobridge.jar!/application.conf: 1,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/ice4j-3.0-58-gf41542d.jar!/reference.conf: 1,reference.conf @ jar:file:/usr/share/jitsi-videobridge/lib/jitsi-media-transform-2.2-45-ge8b20f06.jar!/reference.conf: 1
JVB 2022-10-21 22:56:47.872 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#51: loading config file at path /etc/jitsi/videobridge/sip-communicator.properties
JVB 2022-10-21 22:56:47.880 INFO: [1] JitsiConfig.#68: Initialized legacyConfig: sip communicator props (no description provided)
JVB 2022-10-21 22:56:47.897 INFO: [1] JitsiConfig$Companion.reloadNewConfig#94: Reloading the Typesafe config source (previously reloaded 0 times).
JVB 2022-10-21 22:56:47.931 INFO: [1] MainKt.main#90: Starting jitsi-videobridge version 2.2.45-ge8b20f06
Exception in thread “Thread-0” java.lang.IllegalArgumentException
at java.base/java.util.concurrent.ThreadPoolExecutor.(ThreadPoolExecutor.java:1293)
at java.base/java.util.concurrent.ThreadPoolExecutor.(ThreadPoolExecutor.java:1215)
at org.jitsi.utils.concurrent.ExecutorFactory.createFixedThreadPool(ExecutorFactory.java:133)
at org.ice4j.ice.harvest.MappingCandidateHarvesters.createStunHarvesters(MappingCandidateHarvesters.java:283)
at org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize(MappingCandidateHarvesters.java:141)
at org.jitsi.videobridge.MainKt$startIce4j$1.invoke(Main.kt:239)
at org.jitsi.videobridge.MainKt$startIce4j$1.invoke(Main.kt:238)
at kotlin.concurrent.ThreadsKt$thread$thread$1.run(Thread.kt:30)
JVB 2022-10-21 22:56:49.016 INFO: [14] [hostname=localhost id=shard] MucClient.initializeConnectAndJoin#280: Initializing a new MucClient for [ org.jitsi.xmpp.mucclient.MucClientConfiguration id=shard domain=auth.vafajitsi.tailb5240.ts.net hostname=localhost port=null username=jvb mucs=[JvbBrewery@internal.auth.vafajitsi.tailb5240.ts.net] mucNickname=eae05a57-7b90-4358-9d07-571eb85600e1 disableCertificateVerification=false]
JVB 2022-10-21 22:56:49.129 INFO: [1] LastNReducer.#65: LastNReducer with reductionScale: 0.75 recoverScale: 1.25 impactTime: PT1M minLastN: 1 maxEnforcedLastN: 40
JVB 2022-10-21 22:56:49.135 INFO: [1] TaskPools.#87: TaskPools detected 3 processors, creating the CPU pool with that many threads
JVB 2022-10-21 22:56:49.165 INFO: [1] HealthChecker.start#117: Started with interval=60000, timeout=PT1M30S, maxDuration=PT3S, stickyFailures=false.
JVB 2022-10-21 22:56:49.242 INFO: [1] UlimitCheck.printUlimits#116: Running with open files limit 65000 (hard 65000), thread limit 65000 (hard 65000).
JVB 2022-10-21 22:56:49.266 INFO: [1] VideobridgeExpireThread.start#88: Starting with 60 second interval.
JVB 2022-10-21 22:56:49.372 INFO: [1] MainKt.main#120: Not starting CallstatsService, disabled in configuration.
JVB 2022-10-21 22:56:49.400 INFO: [1] MainKt.main#129: Starting public http server
JVB 2022-10-21 22:56:49.524 INFO: [14] [hostname=localhost id=shard] MucClient.initializeConnectAndJoin#342: Dispatching a thread to connect and login.
JVB 2022-10-21 22:56:49.597 INFO: [1] ColibriWebSocketService.#46: Base URL: wss://vafajitsi.tailb5240.ts.net:443/colibri-ws/default-id Relay URL: null
JVB 2022-10-21 22:56:49.880 INFO: [1] ColibriWebSocketService.registerServlet#85: Registering servlet with baseUrl = wss://vafajitsi.tailb5240.ts.net:443/colibri-ws/default-id, relayUrl = null
JVB 2022-10-21 22:56:49.982 INFO: [1] org.eclipse.jetty.server.Server.doStart: jetty-11.0.10; built: 2022-06-16T20:42:17.891Z; git: d21dded5817960ec3c753a7ba02ef86f7c9ed89e; jvm 11.0.16+8-post-Debian-1deb11u1
JVB 2022-10-21 22:56:50.080 INFO: [14] [hostname=localhost id=shard] MucClient$2.connected#313: Connected.
JVB 2022-10-21 22:56:50.081 INFO: [14] [hostname=localhost id=shard] MucClient.lambda$getConnectAndLoginCallable$9#638: Logging in.
JVB 2022-10-21 22:56:50.313 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@faa3fed{/,null,AVAILABLE}
JVB 2022-10-21 22:56:50.341 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@4d4d48a6{HTTP/1.1, (http/1.1)}{0.0.0.0:9090}
JVB 2022-10-21 22:56:50.385 INFO: [14] [hostname=localhost id=shard] MucClient$2.authenticated#319: Authenticated, b=false
JVB 2022-10-21 22:56:50.401 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@669d2b1b{STARTING}[11.0.10,sto=0] @3642ms
JVB 2022-10-21 22:56:50.402 INFO: [1] MainKt.main#147: Starting private http server
JVB 2022-10-21 22:56:50.471 INFO: [14] [hostname=localhost id=shard] MucClient$MucWrapper.join#763: Joined MUC: jvbbrewery@internal.auth.vafajitsi.tailb5240.ts.net
JVB 2022-10-21 22:56:50.525 INFO: [1] org.eclipse.jetty.server.Server.doStart: jetty-11.0.10; built: 2022-06-16T20:42:17.891Z; git: d21dded5817960ec3c753a7ba02ef86f7c9ed89e; jvm 11.0.16+8-post-Debian-1deb11u1
JVB 2022-10-21 22:56:51.124 WARNING: [1] org.glassfish.jersey.server.wadl.WadlFeature.configure: JAXBContext implementation could not be found. WADL feature is disabled.
JVB 2022-10-21 22:56:51.320 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.Health registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.Health will be ignored.
JVB 2022-10-21 22:56:51.321 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.Version registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.Version will be ignored.
JVB 2022-10-21 22:56:51.321 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.prometheus.Prometheus registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.prometheus.Prometheus will be ignored.
JVB 2022-10-21 22:56:51.695 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@30ec7d21{/,null,AVAILABLE}
JVB 2022-10-21 22:56:51.696 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@53093491{HTTP/1.1, (http/1.1)}{127.0.0.1:8080}
JVB 2022-10-21 22:56:51.696 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@41aaedaa{STARTING}[11.0.10,sto=0] @4937ms
JVB 2022-10-21 22:58:22.475 INFO: [34] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] EndpointConnectionStatusMonitor.start#58: Starting connection status monitor
JVB 2022-10-21 22:58:22.475 INFO: [34] Videobridge.createConference#302: create_conf, id=843e1e8e51c26074 meetingId=9ea3cbd6-edd1-411a-83c8-fc93d769b289
JVB 2022-10-21 22:58:22.540 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address [fd7a:115c:a1e0:ab12:4843:cd96:6245:ea5c]:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:58:22.541 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address [fd7a:115c:a1e0:ab12:4843:cd96:6245:ea5c]:10000/udp
JVB 2022-10-21 22:58:22.542 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address 100.69.234.92:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:58:22.542 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address 100.69.234.92:10000/udp
JVB 2022-10-21 22:58:22.544 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:58:22.545 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp
JVB 2022-10-21 22:58:22.546 INFO: [44] org.ice4j.ice.harvest.AbstractUdpListener.: Initialized AbstractUdpListener with address 192.168.1.170:10000/udp. Receive buffer size 10485760 (asked for 10485760)
JVB 2022-10-21 22:58:22.546 INFO: [44] org.ice4j.ice.harvest.SinglePortUdpHarvester.: Initialized SinglePortUdpHarvester with address 192.168.1.170:10000/udp
JVB 2022-10-21 22:58:22.557 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.gatherCandidates#647: Gathering candidates for component stream-23b9cc3d.RTP.
JVB 2022-10-21 22:58:23.540 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d] Endpoint.#344: Created new endpoint isUsingSourceNames=true, iceControlling=true
JVB 2022-10-21 22:58:23.567 INFO: [44] SctpManager.#62: Initializing Sctp4j
SCTP JNI load: Linux OS detected
SCTP lib loaded
=====>: org_jitsi_modified_sctp4j_SctpJni.c calling init
JVB 2022-10-21 22:58:23.673 WARNING: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] Conference.lambda$new$0#258: Took 1186 ms to process an IQ (total delay 1218 ms):
JVB 2022-10-21 22:58:23.676 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.gatherCandidates#647: Gathering candidates for component stream-d469f96a.RTP.
JVB 2022-10-21 22:58:23.685 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a] Endpoint.#344: Created new endpoint isUsingSourceNames=false, iceControlling=true
JVB 2022-10-21 22:58:23.971 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we’ll act as server
JVB 2022-10-21 22:58:24.017 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
JVB 2022-10-21 22:58:24.018 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
JVB 2022-10-21 22:58:24.018 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.initCheckLists#972: Init checklist for stream stream-23b9cc3d
JVB 2022-10-21 22:58:24.022 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.setState#946: ICE state changed from Waiting to Running.
JVB 2022-10-21 22:58:24.023 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
JVB 2022-10-21 22:58:24.023 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
JVB 2022-10-21 22:58:24.127 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7961835276030973439):
LocalCandidate=candidate:4 1 udp 2113932031 192.168.1.170 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1853759231 192.168.1.100 61251 typ prflx
JVB 2022-10-21 22:58:24.132 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):
LocalCandidate=candidate:2 1 udp 2130706431 100.69.234.92 10000 typ host
RemoteCandidate=candidate:10001 1 udp 1853824767 100.97.130.11 61250 typ prflx
JVB 2022-10-21 22:58:24.166 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 192.168.1.170:10000/udp/host → 192.168.1.100:61251/udp/prflx (stream-23b9cc3d.RTP).
JVB 2022-10-21 22:58:24.166 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 192.168.1.100:61251/udp
JVB 2022-10-21 22:58:24.167 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 192.168.1.170:10000/udp/host → 192.168.1.100:61251/udp/prflx (stream-23b9cc3d.RTP).
JVB 2022-10-21 22:58:24.168 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 192.168.1.170:10000/udp/host → 192.168.1.100:61251/udp/prflx (stream-23b9cc3d.RTP)
JVB 2022-10-21 22:58:24.169 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.nominate#1810: verify if nominated pair answer again
JVB 2022-10-21 22:58:24.176 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 100.69.234.92:10000/udp/host → 100.97.130.11:61250/udp/prflx (stream-23b9cc3d.RTP).
JVB 2022-10-21 22:58:24.177 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 100.97.130.11:61250/udp
JVB 2022-10-21 22:58:24.177 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: 100.69.234.92:10000/udp/host → 100.97.130.11:61250/udp/prflx (stream-23b9cc3d.RTP).
JVB 2022-10-21 22:58:24.177 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2022-10-21 22:58:24.184 WARNING: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] MergingDatagramSocket.initializeActive#599: Active socket already initialized.
JVB 2022-10-21 22:58:24.185 WARNING: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] Conference.lambda$new$0#258: Took 214 ms to process an IQ (total delay 214 ms): B9:4B:B5:A9:A4:88:A5:8D:F3:B1:81:2F:BE:32:97:D0:23:F9:70:08:4F:21:CB:CE:E4:9D:54:85:A8:6E:0F:4C
JVB 2022-10-21 22:58:24.188 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: 192.168.1.170:10000/udp/host → 192.168.1.100:61251/udp/prflx (stream-23b9cc3d.RTP).
JVB 2022-10-21 22:58:24.188 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
JVB 2022-10-21 22:58:24.188 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: 192.168.1.170:10000/udp/host → 192.168.1.100:61251/udp/prflx (stream-23b9cc3d.RTP).
JVB 2022-10-21 22:58:24.189 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-23b9cc3d.RTP: 192.168.1.170:10000/udp/host → 192.168.1.100:61251/udp/prflx (stream-23b9cc3d.RTP)
JVB 2022-10-21 22:58:24.209 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2022-10-21 22:58:24.209 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.checkListStatesUpdated#1901: CheckList of stream stream-23b9cc3d is COMPLETED
JVB 2022-10-21 22:58:24.209 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.setState#946: ICE state changed from Running to Completed.
JVB 2022-10-21 22:58:24.211 INFO: [63] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.checkListStatesUpdated#1901: CheckList of stream stream-23b9cc3d is COMPLETED
JVB 2022-10-21 22:58:24.211 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o] IceTransport.iceStateChanged#342: ICE state changed old=Running new=Completed
JVB 2022-10-21 22:58:24.212 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Endpoint$setupIceTransport$2.connected#394: ICE connected
JVB 2022-10-21 22:58:24.224 INFO: [60] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.checkListStatesUpdated#1901: CheckList of stream stream-23b9cc3d is COMPLETED
JVB 2022-10-21 22:58:24.225 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.logCandTypes#2009: Harvester used for selected pair for stream-23b9cc3d.RTP: host
JVB 2022-10-21 22:58:24.225 INFO: [50] PartitionedByteBufferPool.#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
JVB 2022-10-21 22:58:24.226 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@34a74346
JVB 2022-10-21 22:58:24.231 INFO: [50] PartitionedByteBufferPool.#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
JVB 2022-10-21 22:58:24.231 INFO: [50] PartitionedByteBufferPool.#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
JVB 2022-10-21 22:58:24.257 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-23b9cc3d.RTP: 100.97.130.11:61250/udp
JVB 2022-10-21 22:58:24.257 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-23b9cc3d.RTP: 192.168.1.100:61251/udp
JVB 2022-10-21 22:58:24.258 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] Component.addUpdateRemoteCandidates#345: Update remote candidate for stream-23b9cc3d.RTP: 172.31.16.1:61252/udp
JVB 2022-10-21 22:58:24.310 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
JVB 2022-10-21 22:58:24.529 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Endpoint$setupDtlsTransport$3.handshakeComplete#438: DTLS handshake complete
JVB 2022-10-21 22:58:24.547 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Endpoint.acceptSctpConnection$lambda-23#670: Attempting to establish SCTP socket connection
Got sctp association state update: 1
sctp is now up. was ready? false
JVB 2022-10-21 22:58:24.648 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we’ll act as server
JVB 2022-10-21 22:58:24.649 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
JVB 2022-10-21 22:58:24.649 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
JVB 2022-10-21 22:58:24.649 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.initCheckLists#972: Init checklist for stream stream-d469f96a
JVB 2022-10-21 22:58:24.649 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.setState#946: ICE state changed from Waiting to Running.
JVB 2022-10-21 22:58:24.650 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
JVB 2022-10-21 22:58:24.650 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.startConnectivityEstablishment#758: Trigger checks for pairs that were received before running state
JVB 2022-10-21 22:58:24.650 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962138741240254975):
LocalCandidate=candidate:3 1 udp 2113939711 fd44:b6aa:5134:0:215:5dff:fe01:6401 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1853829887 fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3 57310 typ prflx
JVB 2022-10-21 22:58:24.650 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
JVB 2022-10-21 22:58:24.651 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Endpoint$createSctpConnection$3.onReady#618: SCTP connection is ready, creating the Data channel stack
JVB 2022-10-21 22:58:24.654 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Endpoint$createSctpConnection$3.onReady#642: Will wait for the remote side to open the data channel.
JVB 2022-10-21 22:58:24.676 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp/host → [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp/prflx (stream-d469f96a.RTP).
JVB 2022-10-21 22:58:24.676 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp
JVB 2022-10-21 22:58:24.676 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp/host → [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp/prflx (stream-d469f96a.RTP).
JVB 2022-10-21 22:58:24.677 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp/host → [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp/prflx (stream-d469f96a.RTP)
JVB 2022-10-21 22:58:24.677 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.nominate#1810: verify if nominated pair answer again
JVB 2022-10-21 22:58:24.677 WARNING: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] MergingDatagramSocket.initializeActive#599: Active socket already initialized.
JVB 2022-10-21 22:58:24.677 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2022-10-21 22:58:24.684 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp/host → [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp/prflx (stream-d469f96a.RTP).
JVB 2022-10-21 22:58:24.685 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
JVB 2022-10-21 22:58:24.699 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp/host → [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp/prflx (stream-d469f96a.RTP).
JVB 2022-10-21 22:58:24.700 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
JVB 2022-10-21 22:58:24.700 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp/host → [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp/prflx (stream-d469f96a.RTP).
JVB 2022-10-21 22:58:24.700 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-d469f96a.RTP: [fd44:b6aa:5134:0:215:5dff:fe01:6401]:10000/udp/host → [fd44:b6aa:5134:0:cc1:1f69:3f62:ccd3]:57310/udp/prflx (stream-d469f96a.RTP)
JVB 2022-10-21 22:58:24.703 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.checkListStatesUpdated#1901: CheckList of stream stream-d469f96a is COMPLETED
JVB 2022-10-21 22:58:24.703 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.setState#946: ICE state changed from Running to Completed.
JVB 2022-10-21 22:58:24.703 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr] IceTransport.iceStateChanged#342: ICE state changed old=Running new=Completed
JVB 2022-10-21 22:58:24.704 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Endpoint$setupIceTransport$2.connected#394: ICE connected
JVB 2022-10-21 22:58:24.705 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@4ce3585f
JVB 2022-10-21 22:58:24.706 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
JVB 2022-10-21 22:58:24.708 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.logCandTypes#2009: Harvester used for selected pair for stream-d469f96a.RTP: host
JVB 2022-10-21 22:58:24.720 INFO: [65] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Endpoint$setupDtlsTransport$3.handshakeComplete#438: DTLS handshake complete
JVB 2022-10-21 22:58:24.726 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Endpoint.acceptSctpConnection$lambda-23#670: Attempting to establish SCTP socket connection
JVB 2022-10-21 22:58:24.732 INFO: [68] JitsiOpenSslProvider.#52: jitsisrtp successfully loaded for OpenSSL 1.1
JVB 2022-10-21 22:58:24.739 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 192.168.1.197:54936/udp
Got sctp association state update: 1
sctp is now up. was ready? false
JVB 2022-10-21 22:58:24.782 INFO: [68] Aes.createCipher#433: Will employ AES implemented by OpenSSL for AES/GCM/NoPadding.
JVB 2022-10-21 22:58:24.784 INFO: [68] Aes.createCipher#433: Will employ AES implemented by OpenSSL for AES/CTR/NoPadding.
JVB 2022-10-21 22:58:24.801 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: [fd7a:115c:a1e0:ab12:4843:cd96:6275:7539]:50507/udp
JVB 2022-10-21 22:58:24.827 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Endpoint$createSctpConnection$3.onReady#618: SCTP connection is ready, creating the Data channel stack
JVB 2022-10-21 22:58:24.827 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Endpoint$createSctpConnection$3.onReady#642: Will wait for the remote side to open the data channel.
JVB 2022-10-21 22:58:24.849 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] Conference.recentSpeakersChanged#513: Recent speakers changed: [d469f96a], dominant speaker changed: true silence:false
JVB 2022-10-21 22:58:24.865 INFO: [55] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 100.117.117.57:51209/udp
JVB 2022-10-21 22:58:27.222 INFO: [54] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o] Agent.setState#946: ICE state changed from Completed to Terminated.
JVB 2022-10-21 22:58:27.222 INFO: [54] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o] IceTransport.iceStateChanged#342: ICE state changed old=Completed new=Terminated
JVB 2022-10-21 22:58:27.709 INFO: [54] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr] Agent.setState#946: ICE state changed from Completed to Terminated.
JVB 2022-10-21 22:58:27.709 INFO: [54] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr] IceTransport.iceStateChanged#342: ICE state changed old=Completed new=Terminated
JVB 2022-10-21 22:58:30.551 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] AbstractEndpoint.expire#311: Expiring.
JVB 2022-10-21 22:58:30.559 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Endpoint.expire#1140: Spent 0 seconds oversending
JVB 2022-10-21 22:58:30.560 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Transceiver.teardown#353: Tearing down
JVB 2022-10-21 22:58:30.560 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] RtpReceiverImpl.tearDown#347: Tearing down
JVB 2022-10-21 22:58:30.564 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] RtpSenderImpl.tearDown#318: Tearing down
JVB 2022-10-21 22:58:30.566 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] DtlsTransport.stop#186: Stopping
JVB 2022-10-21 22:58:30.567 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr] IceTransport.stop#252: Stopping
JVB 2022-10-21 22:58:30.575 INFO: [80] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
JVB 2022-10-21 22:58:30.578 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] MergingDatagramSocket.close#142: Closing.
JVB 2022-10-21 22:58:30.579 INFO: [71] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
JVB 2022-10-21 22:58:30.579 INFO: [69] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr] IceTransport.startReadingData#218: Socket closed, stopping reader
JVB 2022-10-21 22:58:30.579 INFO: [67] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
JVB 2022-10-21 22:58:30.579 INFO: [69] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr] IceTransport.startReadingData#230: No longer running, stopped reading packets
JVB 2022-10-21 22:58:30.580 INFO: [76] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC local_ufrag=3ilme1gfu204dr ufrag=3ilme1gfu204dr name=stream-d469f96a componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
JVB 2022-10-21 22:58:30.581 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=d469f96a stats_id=Mittie-txC] Endpoint.expire#1158: Expired.
JVB 2022-10-21 22:58:34.599 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] Colibri2ConferenceHandler.handleConferenceModifyIQ#69: Received request to expire conference.
JVB 2022-10-21 22:58:34.599 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] Conference.expire#618: Expiring.
JVB 2022-10-21 22:58:34.600 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] EndpointConnectionStatusMonitor.stop#66: Stopped
JVB 2022-10-21 22:58:34.601 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] AbstractEndpoint.expire#311: Expiring.
JVB 2022-10-21 22:58:34.603 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Endpoint.expire#1140: Spent 0 seconds oversending
JVB 2022-10-21 22:58:34.603 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Transceiver.teardown#353: Tearing down
JVB 2022-10-21 22:58:34.603 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] RtpReceiverImpl.tearDown#347: Tearing down
JVB 2022-10-21 22:58:34.604 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] RtpSenderImpl.tearDown#318: Tearing down
JVB 2022-10-21 22:58:34.615 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] DtlsTransport.stop#186: Stopping
JVB 2022-10-21 22:58:34.616 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o] IceTransport.stop#252: Stopping
JVB 2022-10-21 22:58:34.620 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] MergingDatagramSocket.close#142: Closing.
JVB 2022-10-21 22:58:34.621 INFO: [50] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o] IceTransport.startReadingData#218: Socket closed, stopping reader
JVB 2022-10-21 22:58:34.621 INFO: [50] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o] IceTransport.startReadingData#230: No longer running, stopped reading packets
JVB 2022-10-21 22:58:34.621 INFO: [53] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
JVB 2022-10-21 22:58:34.622 INFO: [59] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz local_ufrag=7ojc61gfu2039o ufrag=7ojc61gfu2039o name=stream-23b9cc3d componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
JVB 2022-10-21 22:58:34.622 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net epId=23b9cc3d stats_id=Dustin-IXz] Endpoint.expire#1158: Expired.
JVB 2022-10-21 22:58:34.626 INFO: [44] [confId=843e1e8e51c26074 conf_name=test@conference.vafajitsi.tailb5240.ts.net] Conference.updateStatisticsOnExpire#681: expire_conf,duration=12,has_failed=false,has_partially_failed=false
JVB 2022-10-21 23:01:33.544 INFO: [1] JitsiConfig.#47: Initialized newConfig: merge of /etc/jitsi/videobridge/jvb.conf: 1,application.conf @ jar:file:/usr/share/jitsi-videobridge/jitsi-videobridge.jar!/application.conf: 1,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/ice4j-3.0-58-gf41542d.jar!/reference.conf: 1,reference.conf @ jar:file:/usr/share/jitsi-videobridge/lib/jitsi-media-transform-2.2-45-ge8b20f06.jar!/reference.conf: 1
JVB 2022-10-21 23:01:33.570 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#51: loading config file at path /etc/jitsi/videobridge/sip-communicator.properties
JVB 2022-10-21 23:01:33.575 INFO: [1] JitsiConfig.#68: Initialized legacyConfig: sip communicator props (no description provided)
JVB 2022-10-21 23:01:33.583 INFO: [1] JitsiConfig$Companion.reloadNewConfig#94: Reloading the Typesafe config source (previously reloaded 0 times).
JVB 2022-10-21 23:01:33.619 INFO: [1] MainKt.main#90: Starting jitsi-videobridge version 2.2.45-ge8b20f06
Exception in thread “Thread-0” java.lang.IllegalArgumentException
at java.base/java.util.concurrent.ThreadPoolExecutor.(ThreadPoolExecutor.java:1293)
at java.base/java.util.concurrent.ThreadPoolExecutor.(ThreadPoolExecutor.java:1215)
at org.jitsi.utils.concurrent.ExecutorFactory.createFixedThreadPool(ExecutorFactory.java:133)
at org.ice4j.ice.harvest.MappingCandidateHarvesters.createStunHarvesters(MappingCandidateHarvesters.java:283)
at org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize(MappingCandidateHarvesters.java:141)
at org.jitsi.videobridge.MainKt$startIce4j$1.invoke(Main.kt:239)
at org.jitsi.videobridge.MainKt$startIce4j$1.invoke(Main.kt:238)
at kotlin.concurrent.ThreadsKt$thread$thread$1.run(Thread.kt:30)
JVB 2022-10-21 23:01:34.798 INFO: [14] [hostname=localhost id=shard] MucClient.initializeConnectAndJoin#280: Initializing a new MucClient for [ org.jitsi.xmpp.mucclient.MucClientConfiguration id=shard domain=auth.vafajitsi.tailb5240.ts.net hostname=localhost port=null username=jvb mucs=[JvbBrewery@internal.auth.vafajitsi.tailb5240.ts.net] mucNickname=eae05a57-7b90-4358-9d07-571eb85600e1 disableCertificateVerification=false]
JVB 2022-10-21 23:01:34.871 INFO: [1] LastNReducer.#65: LastNReducer with reductionScale: 0.75 recoverScale: 1.25 impactTime: PT1M minLastN: 1 maxEnforcedLastN: 40
JVB 2022-10-21 23:01:34.881 INFO: [1] TaskPools.#87: TaskPools detected 3 processors, creating the CPU pool with that many threads
JVB 2022-10-21 23:01:34.928 INFO: [1] HealthChecker.start#117: Started with interval=60000, timeout=PT1M30S, maxDuration=PT3S, stickyFailures=false.
JVB 2022-10-21 23:01:34.984 INFO: [1] UlimitCheck.printUlimits#116: Running with open files limit 65000 (hard 65000), thread limit 65000 (hard 65000).
JVB 2022-10-21 23:01:35.004 INFO: [1] VideobridgeExpireThread.start#88: Starting with 60 second interval.
JVB 2022-10-21 23:01:35.076 INFO: [1] MainKt.main#120: Not starting CallstatsService, disabled in configuration.
JVB 2022-10-21 23:01:35.083 INFO: [1] MainKt.main#129: Starting public http server
JVB 2022-10-21 23:01:35.200 INFO: [14] [hostname=localhost id=shard] MucClient.initializeConnectAndJoin#342: Dispatching a thread to connect and login.
JVB 2022-10-21 23:01:35.239 INFO: [1] ColibriWebSocketService.#46: Base URL: wss://vafajitsi.tailb5240.ts.net:443/colibri-ws/default-id Relay URL: null
JVB 2022-10-21 23:01:35.497 INFO: [1] ColibriWebSocketService.registerServlet#85: Registering servlet with baseUrl = wss://vafajitsi.tailb5240.ts.net:443/colibri-ws/default-id, relayUrl = null
JVB 2022-10-21 23:01:35.531 INFO: [1] org.eclipse.jetty.server.Server.doStart: jetty-11.0.10; built: 2022-06-16T20:42:17.891Z; git: d21dded5817960ec3c753a7ba02ef86f7c9ed89e; jvm 11.0.16+8-post-Debian-1deb11u1
JVB 2022-10-21 23:01:35.707 INFO: [14] [hostname=localhost id=shard] MucClient$2.connected#313: Connected.
JVB 2022-10-21 23:01:35.708 INFO: [14] [hostname=localhost id=shard] MucClient.lambda$getConnectAndLoginCallable$9#638: Logging in.
JVB 2022-10-21 23:01:35.784 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@15515c51{/,null,AVAILABLE}
JVB 2022-10-21 23:01:35.808 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@70e0accd{HTTP/1.1, (http/1.1)}{0.0.0.0:9090}
JVB 2022-10-21 23:01:35.857 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@1e53135d{STARTING}[11.0.10,sto=0] @3314ms
JVB 2022-10-21 23:01:35.858 INFO: [1] MainKt.main#147: Starting private http server
JVB 2022-10-21 23:01:35.889 INFO: [14] [hostname=localhost id=shard] MucClient$2.authenticated#319: Authenticated, b=false
JVB 2022-10-21 23:01:36.019 INFO: [14] [hostname=localhost id=shard] MucClient$MucWrapper.join#763: Joined MUC: jvbbrewery@internal.auth.vafajitsi.tailb5240.ts.net
JVB 2022-10-21 23:01:36.073 INFO: [1] org.eclipse.jetty.server.Server.doStart: jetty-11.0.10; built: 2022-06-16T20:42:17.891Z; git: d21dded5817960ec3c753a7ba02ef86f7c9ed89e; jvm 11.0.16+8-post-Debian-1deb11u1
JVB 2022-10-21 23:01:36.690 WARNING: [1] org.glassfish.jersey.server.wadl.WadlFeature.configure: JAXBContext implementation could not be found. WADL feature is disabled.
JVB 2022-10-21 23:01:36.841 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.Health registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.Health will be ignored.
JVB 2022-10-21 23:01:36.842 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.Version registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.Version will be ignored.
JVB 2022-10-21 23:01:36.842 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime: A provider org.jitsi.rest.prometheus.Prometheus registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.rest.prometheus.Prometheus will be ignored.
JVB 2022-10-21 23:01:37.376 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@2db2a05f{/,null,AVAILABLE}
JVB 2022-10-21 23:01:37.377 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@312afbc7{HTTP/1.1, (http/1.1)}{127.0.0.1:8080}
JVB 2022-10-21 23:01:37.377 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@2c779e5{STARTING}[11.0.10,sto=0] @4834ms

damencho · October 21, 2022, 8:21pm

Which java version do you run there?