Jitsi Videobridge update not working with Jigasi

Developers
,
1

We run a jitsi shard with two videobridges and one jicofo,prosody server together with a separat jigasi server. Before updating the videobridge to the new version, jigasi was able to call into a conference and the caller could talk and hear others.

After the update, after the caller joint the conference, jigasi stoped the stream and hang up after appr. 5 seconds, hence the caller is leving the meeting.

  • Jigasi is still running in an old version, however upgrading it to the latest release does not change anything.
  • The configuration of all services stayed the same. And when I downgrade back only the videobridge, jigasi can join the meeting and stays inside.

The last working release of videobridge together with jigasi is jitsi-videobridge2=2.2-45*
Releaes that are NOT working:
jitsi-videobridge2=2.2-61*
jitsi-videobridge2=2.2-63*

(Logs and Configs are in the Github issue → Jigasi is not working after Updating from jitsi-videobridge2=2.2-45* to jitsi-videobridge2_2.2-61* · Issue #1973 · jitsi/jitsi-videobridge · GitHub)

2

Which jicofo version do you run?

3

When upgrading jvb you need to upgrade and jicofo and jitsi-meet.

4

Hi @damencho,

I’m running from all the latest release. Currently only the videobridge is the old one because with all newer version it is not working.

jitsi-meet-web 1.0.6854-1
jitsi-videobridge2 2.2-45-ge8b20f06-1
prosody 0.11.9-2+deb11u2
jicofo 1.0-968-1
nginx 1.18.0-6.1+deb11u3

5

Can you get some logs from non-working case from jigasi, jvb, and jicofo and upload them?
The logs from the issue(the gist) are unreadable with some HTML inside…

6

Hi @damencho

I was in vacation so I will install everything with the lates release and generate some logs.

7

I updated now all applications and tried to start the audio call via jigasi but still the caller get lost and is leaving the conference.

Additional infos:

  • I have the token_moderator prosodoy plugin enabled. Therefore the jicofo error that no focus ownership could be assigned should be ok.

Jicofo Logs

Jicofo 2023-01-05 18:16:05.585 INFO: [1] Main.main#47: Starting Jicofo.
Jicofo 2023-01-05 18:16:05.752 INFO: [1] JitsiConfig.<clinit>#47: Initialized newConfig: merge of /etc/jitsi/jicofo/jicofo.conf: 1,system properties,reference.conf @ jar:file:/usr/share/jicofo/lib/jicofo-selector-1.0-968.jar!/reference.conf: 1
Jicofo 2023-01-05 18:16:05.756 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#51: loading config file at path /etc/jitsi/jicofo/sip-communicator.properties
Jicofo 2023-01-05 18:16:05.757 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#56: Error loading config file: java.io.FileNotFoundException: /etc/jitsi/jicofo/sip-communicator.properties (No such file or directory)
Jicofo 2023-01-05 18:16:05.758 INFO: [1] JitsiConfig.<clinit>#68: Initialized legacyConfig: sip communicator props (no description provided)
Jicofo 2023-01-05 18:16:05.764 INFO: [1] JitsiConfig$Companion.reloadNewConfig#94: Reloading the Typesafe config source (previously reloaded 0 times).
Jicofo 2023-01-05 18:16:06.331 INFO: [1] JicofoServices.createAuthenticationAuthority#186: Authentication service disabled.
Jicofo 2023-01-05 18:16:06.388 WARNING: [1] [xmpp_connection=client] XmppProviderImpl.createXmppConnection#161: Disabling TLS certificate verification!
Jicofo 2023-01-05 18:16:06.494 INFO: [1] XmppServices.<init>#47: No dedicated Service XMPP connection configured, re-using the client XMPP connection.
Jicofo 2023-01-05 18:16:06.501 INFO: [1] XmppServices.<init>#62: No Jigasi detector configured.
Jicofo 2023-01-05 18:16:06.542 INFO: [1] BridgeSelector.<init>#57: Using org.jitsi.jicofo.bridge.IntraRegionBridgeSelectionStrategy
Jicofo 2023-01-05 18:16:06.550 INFO: [1] [type=bridge brewery=jvbbrewery] BaseBrewery.<init>#101: Initialized with JID=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx
Jicofo 2023-01-05 18:16:06.552 INFO: [1] JicofoServices.<init>#108: No Jibri detector configured.
Jicofo 2023-01-05 18:16:06.553 INFO: [1] JicofoServices.<init>#114: No SIP Jibri detector configured.
Jicofo 2023-01-05 18:16:06.571 INFO: [1] JicofoServices.<init>#128: Starting HTTP server with config: host=null, port=9002, tlsPort=8843, isTls=false, keyStorePath=null, sendServerVersion=true.
Jicofo 2023-01-05 18:16:06.698 INFO: [17] [xmpp_connection=client] XmppProviderImpl.doConnect#198: Connected, JID=null
Jicofo 2023-01-05 18:16:06.727 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
Jicofo 2023-01-05 18:16:06.795 INFO: [17] [xmpp_connection=client] XmppProviderImpl.fireRegistrationStateChanged#295: Set replyTimeout=PT15S
Jicofo 2023-01-05 18:16:06.817 INFO: [31] ConferenceIqHandler.registrationChanged#193: Using breakout room component address: breakout.xxxxxxxxxxxxxxxxxx
Jicofo 2023-01-05 18:16:06.818 INFO: [30] AvModerationHandler.registrationChanged#121: Did not discover av_moderation component.
Jicofo 2023-01-05 18:16:06.846 INFO: [35] [type=bridge brewery=jvbbrewery] BaseBrewery.addInstance#342: Added brewery instance: jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ
Jicofo 2023-01-05 18:16:06.856 INFO: [35] BridgeSelector.addJvbAddress#92: Added new videobridge: Bridge[jid=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ, version=2.2.63-g252d14bc, relayId=xxxxxxxx, region=germany, stress=0.00]
Jicofo 2023-01-05 18:16:06.861 INFO: [37] JvbDoctor.bridgeAdded#131: Scheduled health-check task for: Bridge[jid=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ, version=2.2.63-g252d14bc, relayId=xxxxxxxx, region=germany, stress=0.00]
Jicofo 2023-01-05 18:16:06.938 INFO: [33] [type=bridge brewery=jvbbrewery] BaseBrewery.start#172: Joined the room.
Jicofo 2023-01-05 18:16:07.050 WARNING: [1] org.glassfish.jersey.server.wadl.WadlFeature.configure: JAXBContext implementation could not be found. WADL feature is disabled.
Jicofo 2023-01-05 18:16:07.127 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. 
Jicofo 2023-01-05 18:16:07.128 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. 
Jicofo 2023-01-05 18:16:07.320 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@52226e57{/,null,AVAILABLE}
Jicofo 2023-01-05 18:16:07.328 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@16073fa8{HTTP/1.1, (http/1.1)}{0.0.0.0:9002}
Jicofo 2023-01-05 18:16:07.336 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@18eec010{STARTING}[11.0.10,sto=0] @1912ms
Jicofo 2023-01-05 18:17:01.309 INFO: [1] Main.main#47: Starting Jicofo.
Jicofo 2023-01-05 18:17:01.471 INFO: [1] JitsiConfig.<clinit>#47: Initialized newConfig: merge of /etc/jitsi/jicofo/jicofo.conf: 1,system properties,reference.conf @ jar:file:/usr/share/jicofo/lib/jicofo-selector-1.0-968.jar!/reference.conf: 1
Jicofo 2023-01-05 18:17:01.474 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#51: loading config file at path /etc/jitsi/jicofo/sip-communicator.properties
Jicofo 2023-01-05 18:17:01.475 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#56: Error loading config file: java.io.FileNotFoundException: /etc/jitsi/jicofo/sip-communicator.properties (No such file or directory)
Jicofo 2023-01-05 18:17:01.476 INFO: [1] JitsiConfig.<clinit>#68: Initialized legacyConfig: sip communicator props (no description provided)
Jicofo 2023-01-05 18:17:01.482 INFO: [1] JitsiConfig$Companion.reloadNewConfig#94: Reloading the Typesafe config source (previously reloaded 0 times).
Jicofo 2023-01-05 18:17:02.033 INFO: [1] JicofoServices.createAuthenticationAuthority#186: Authentication service disabled.
Jicofo 2023-01-05 18:17:02.088 WARNING: [1] [xmpp_connection=client] XmppProviderImpl.createXmppConnection#161: Disabling TLS certificate verification!
Jicofo 2023-01-05 18:17:02.192 INFO: [1] XmppServices.<init>#47: No dedicated Service XMPP connection configured, re-using the client XMPP connection.
Jicofo 2023-01-05 18:17:02.203 INFO: [1] XmppServices.<init>#62: No Jigasi detector configured.
Jicofo 2023-01-05 18:17:02.252 INFO: [1] BridgeSelector.<init>#57: Using org.jitsi.jicofo.bridge.IntraRegionBridgeSelectionStrategy
Jicofo 2023-01-05 18:17:02.260 INFO: [1] [type=bridge brewery=jvbbrewery] BaseBrewery.<init>#101: Initialized with JID=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx
Jicofo 2023-01-05 18:17:02.262 INFO: [1] JicofoServices.<init>#108: No Jibri detector configured.
Jicofo 2023-01-05 18:17:02.262 INFO: [1] JicofoServices.<init>#114: No SIP Jibri detector configured.
Jicofo 2023-01-05 18:17:02.281 INFO: [1] JicofoServices.<init>#128: Starting HTTP server with config: host=null, port=9002, tlsPort=8843, isTls=false, keyStorePath=null, sendServerVersion=true.
Jicofo 2023-01-05 18:17:02.417 INFO: [17] [xmpp_connection=client] XmppProviderImpl.doConnect#198: Connected, JID=null
Jicofo 2023-01-05 18:17:02.428 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
Jicofo 2023-01-05 18:17:02.509 INFO: [17] [xmpp_connection=client] XmppProviderImpl.fireRegistrationStateChanged#295: Set replyTimeout=PT15S
Jicofo 2023-01-05 18:17:02.530 INFO: [31] ConferenceIqHandler.registrationChanged#193: Using breakout room component address: breakout.xxxxxxxxxxxxxxxxxx
Jicofo 2023-01-05 18:17:02.532 INFO: [30] AvModerationHandler.registrationChanged#121: Did not discover av_moderation component.
Jicofo 2023-01-05 18:17:02.563 INFO: [34] [type=bridge brewery=jvbbrewery] BaseBrewery.addInstance#342: Added brewery instance: jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ
Jicofo 2023-01-05 18:17:02.572 INFO: [34] BridgeSelector.addJvbAddress#92: Added new videobridge: Bridge[jid=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ, version=2.2.63-g252d14bc, relayId=xxxxxxxx, region=germany, stress=0.00]
Jicofo 2023-01-05 18:17:02.577 INFO: [37] JvbDoctor.bridgeAdded#131: Scheduled health-check task for: Bridge[jid=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ, version=2.2.63-g252d14bc, relayId=xxxxxxxx, region=germany, stress=0.00]
Jicofo 2023-01-05 18:17:02.636 INFO: [33] [type=bridge brewery=jvbbrewery] BaseBrewery.start#172: Joined the room.
Jicofo 2023-01-05 18:17:02.724 WARNING: [1] org.glassfish.jersey.server.wadl.WadlFeature.configure: JAXBContext implementation could not be found. WADL feature is disabled.
Jicofo 2023-01-05 18:17:02.812 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. 
Jicofo 2023-01-05 18:17:02.813 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. 
Jicofo 2023-01-05 18:17:03.015 INFO: [1] org.eclipse.jetty.server.handler.ContextHandler.doStart: Started o.e.j.s.ServletContextHandler@9dada78{/,null,AVAILABLE}
Jicofo 2023-01-05 18:17:03.025 INFO: [1] org.eclipse.jetty.server.AbstractConnector.doStart: Started ServerConnector@f3fcd59{HTTP/1.1, (http/1.1)}{0.0.0.0:9002}
Jicofo 2023-01-05 18:17:03.033 INFO: [1] org.eclipse.jetty.server.Server.doStart: Started Server@3cec79d3{STARTING}[11.0.10,sto=0] @1882ms
Jicofo 2023-01-05 18:17:06.611 INFO: [33] ConferenceIqHandler.handleConferenceIq#69: Focus request for room: neuer_test-644@conference.xxxxxxxxxxxxxxxxxx
Jicofo 2023-01-05 18:17:06.645 INFO: [33] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx] JitsiMeetConferenceImpl.<init>#249: Created new conference.
Jicofo 2023-01-05 18:17:06.649 INFO: [33] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx] JitsiMeetConferenceImpl.joinTheRoom#429: Joining neuer_test-644@conference.xxxxxxxxxxxxxxxxxx
Jicofo 2023-01-05 18:17:06.757 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.onMemberJoined#589: Member joined:8d5f4428 stats-id=Mekhi-ymm region=germany audioMuted=true videoMuted=true role=PARTICIPANT isJibri=false isJigasi=false
Jicofo 2023-01-05 18:17:06.758 INFO: [34] AutoOwnerRoleManager.electNewOwner#105: Electing new owner: ChatMember[8d5f4428]
Jicofo 2023-01-05 18:17:06.768 SEVERE: [34] ChatRoomRoleManager.grantOwner#45: Failed to grant owner status to 8d5f4428-18bd-49c7-a629-507d648c327a@xxxxxxxxxxxxxxxxxx/a97J-BzT
java.lang.RuntimeException: Failed to grant owner: <iq xmlns='jabber:client' to='focus@auth.xxxxxxxxxxxxxxxxxx/focus' from='neuer_test-644@conference.xxxxxxxxxxxxxxxxxx' id='VQCHQ-15' type='error'><error type='modify'><not-acceptable xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></iq>
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.grantOwnership(ChatRoomImpl.java:523)
	at org.jitsi.jicofo.xmpp.muc.ChatRoomRoleManager.grantOwner(ChatRoomRoleManager.kt:42)
	at org.jitsi.jicofo.xmpp.muc.AutoOwnerRoleManager.electNewOwner(ChatRoomRoleManager.kt:106)
	at org.jitsi.jicofo.xmpp.muc.AutoOwnerRoleManager.memberJoined(ChatRoomRoleManager.kt:68)
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.lambda$processOtherPresence$12(ChatRoomImpl.java:850)
	at org.jitsi.utils.event.SyncEventEmitter$fireEvent$1$1.invoke(EventEmitter.kt:64)
	at org.jitsi.utils.event.SyncEventEmitter$fireEvent$1$1.invoke(EventEmitter.kt:64)
	at org.jitsi.utils.event.BaseEventEmitter.wrap(EventEmitter.kt:49)
	at org.jitsi.utils.event.SyncEventEmitter.fireEvent(EventEmitter.kt:64)
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.processOtherPresence(ChatRoomImpl.java:849)
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.processPresence(ChatRoomImpl.java:903)
	at org.jivesoftware.smackx.muc.MultiUserChat$3.processStanza(MultiUserChat.java:309)
	at org.jivesoftware.smack.AbstractXMPPConnection.lambda$invokeStanzaCollectorsAndNotifyRecvListeners$8(AbstractXMPPConnection.java:1619)
	at org.jivesoftware.smack.AsyncButOrdered$Handler.run(AsyncButOrdered.java:151)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
	at java.base/java.lang.Thread.run(Thread.java:829)
Jicofo 2023-01-05 18:17:23.428 INFO: [33] ConferenceIqHandler.handleConferenceIq#69: Focus request for room: neuer_test-644@conference.xxxxxxxxxxxxxxxxxx
Jicofo 2023-01-05 18:17:23.451 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.onMemberJoined#589: Member joined:0b0e54b0 stats-id=null region=null audioMuted=true videoMuted=true role=PARTICIPANT isJibri=false isJigasi=false
Jicofo 2023-01-05 18:17:23.585 INFO: [34] DiscoveryUtil.discoverParticipantFeatures#164: Discovered features for neuer_test-644@conference.xxxxxxxxxxxxxxxxxx/8d5f4428 in 124 ms.
Jicofo 2023-01-05 18:17:23.601 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.<init>#79: Using org.jitsi.jicofo.bridge.SingleMeshTopologyStrategy
Jicofo 2023-01-05 18:17:23.614 INFO: [34] DiscoveryUtil.discoverParticipantFeatures#164: Discovered features for neuer_test-644@conference.xxxxxxxxxxxxxxxxxx/0b0e54b0 in 12 ms.
Jicofo 2023-01-05 18:17:23.645 INFO: [30] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.allocate#277: Allocating for 0b0e54b0
Jicofo 2023-01-05 18:17:23.653 WARNING: [33] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=8d5f4428] ParticipantInviteRunnable.lambda$doRun$0#194: Failed to convert ContentPacketExtension to Media: <content creator='initiator' name='data' senders='both'><transport xmlns='urn:xmpp:jingle:transports:ice-udp:1'><fingerprint xmlns='urn:xmpp:jingle:apps:dtls:0'/></transport><description xmlns='urn:xmpp:jingle:apps:rtp:1' media='application'/></content>
Jicofo 2023-01-05 18:17:23.654 INFO: [33] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.allocate#277: Allocating for 8d5f4428
Jicofo 2023-01-05 18:17:23.659 INFO: [30] BridgeSelectionStrategy.select#114: Selected initial bridge Bridge[jid=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ, version=2.2.63-g252d14bc, relayId=xxxxxxxx, region=germany, stress=0.00] with reported stress=0.0 for participantProperties=ParticipantProperties(region=null, visitor=false) using strategy IntraRegionBridgeSelectionStrategy
Jicofo 2023-01-05 18:17:23.663 INFO: [30] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.allocate#323: Selected lmPcFCdmYJ, session exists: false
Jicofo 2023-01-05 18:17:23.675 INFO: [33] BridgeSelectionStrategy.select#132: Selected bridge Bridge[jid=jvbbrewery@internal.auth.xxxxxxxxxxxxxxxxxx/lmPcFCdmYJ, version=2.2.63-g252d14bc, relayId=xxxxxxxx, region=germany, stress=0.01] with stress=0.0 for participantProperties=ParticipantProperties(region=germany, visitor=false)
Jicofo 2023-01-05 18:17:23.675 INFO: [33] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.allocate#323: Selected lmPcFCdmYJ, session exists: true
Jicofo 2023-01-05 18:17:24.460 INFO: [30] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=0b0e54b0] ParticipantInviteRunnable.doInviteOrReinvite#392: Sending session-initiate to: neuer_test-644@conference.xxxxxxxxxxxxxxxxxx/0b0e54b0 sources={jvb=[audio=[3713552699], video=[], groups=[]]}
Jicofo 2023-01-05 18:17:24.523 WARNING: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=0b0e54b0] Participant$JingleRequestHandlerImpl.onTransportInfo#511: Received an early or stale transport-info from non-jigasi.
Jicofo 2023-01-05 18:17:24.525 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.updateParticipant#523: Updating 0b0e54b0 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@5e6e551a, sources=[audio=[], video=[], groups=[]]
Jicofo 2023-01-05 18:17:24.525 INFO: [33] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=8d5f4428] ParticipantInviteRunnable.doInviteOrReinvite#392: Sending session-initiate to: neuer_test-644@conference.xxxxxxxxxxxxxxxxxx/8d5f4428 sources={jvb=[audio=[3713552699], video=[509827285], groups=[]]}
Jicofo 2023-01-05 18:17:24.661 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=0b0e54b0] Participant$JingleRequestHandlerImpl.onSessionOrTransportAccept#437: Received session-accept
Jicofo 2023-01-05 18:17:24.666 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.updateParticipant#523: Updating 0b0e54b0 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@621254aa, sources=[audio=[479545440], video=[], groups=[]]
Jicofo 2023-01-05 18:17:24.674 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.acceptSession#1103: Accepted initial sources from 0b0e54b0: [audio=[479545440], video=[], groups=[]]
Jicofo 2023-01-05 18:17:24.731 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=8d5f4428] Participant$JingleRequestHandlerImpl.onSessionOrTransportAccept#437: Received session-accept
Jicofo 2023-01-05 18:17:24.733 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.updateParticipant#523: Updating 8d5f4428 with transport=org.jitsi.xmpp.extensions.jingle.IceUdpTransportPacketExtension@12178888, sources=[audio=[3279601021], video=[2704933179, 1717760460, 1840205901, 742995564, 479763643, 1068558367], groups=[FID[2704933179, 1717760460], SIM[2704933179, 1840205901, 742995564], FID[1840205901, 479763643], FID[742995564, 1068558367]]]
Jicofo 2023-01-05 18:17:24.735 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.acceptSession#1103: Accepted initial sources from 8d5f4428: [audio=[3279601021], video=[2704933179, 1717760460, 1840205901, 742995564, 479763643, 1068558367], groups=[FID[2704933179, 1717760460], SIM[2704933179, 1840205901, 742995564], FID[1840205901, 479763643], FID[742995564, 1068558367]]]
Jicofo 2023-01-05 18:17:24.739 INFO: [39] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=0b0e54b0] Participant.sendQueuedRemoteSources#329: Sending a queued source-add, sources={8d5f4428=[audio=[3279601021], video=[], groups=[]]}
Jicofo 2023-01-05 18:17:24.740 INFO: [34] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2 participant=8d5f4428] Participant.sendQueuedRemoteSources#329: Sending a queued source-add, sources={0b0e54b0=[audio=[479545440], video=[], groups=[]]}
Jicofo 2023-01-05 18:17:29.701 INFO: [36] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.onMemberLeft#782: Member left:0b0e54b0
Jicofo 2023-01-05 18:17:29.703 INFO: [36] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.terminateParticipant#824: Terminating 0b0e54b0, reason: gone, send session-terminate: false
Jicofo 2023-01-05 18:17:29.714 INFO: [36] [remoteJid=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx/0b0e54b0 sid=calfip2fg5f4] JingleSession.terminate#93: Terminating session with neuer_test-644@conference.xxxxxxxxxxxxxxxxxx/0b0e54b0, reason=gone, sendTerminate=false
Jicofo 2023-01-05 18:17:29.715 INFO: [36] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.updateParticipant#523: Updating 0b0e54b0 with transport=null, sources=[audio=[], video=[], groups=[]]
Jicofo 2023-01-05 18:17:29.715 INFO: [36] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.terminateParticipant#843: Removed participant 0b0e54b0 removed=true
Jicofo 2023-01-05 18:17:29.716 INFO: [36] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] ColibriV2SessionManager.removeParticipant#122: Removing 0b0e54b0
Jicofo 2023-01-05 18:17:29.720 INFO: [36] [room=neuer_test-644@conference.xxxxxxxxxxxxxxxxxx meeting_id=2650f5e4-4b68-4779-985e-9f99b5a756b2] JitsiMeetConferenceImpl.rescheduleSingleParticipantTimeout#1544: Scheduled single person timeout.

Jigasi

2023-01-05 18:17:25.853 INFO: [118177] org.jitsi.jigasi.SipGateway.incomingCallReceived().216 [ctx=1672939045853468908259] Incoming call received...
2023-01-05 18:17:25.854 INFO: [118179] org.jitsi.jigasi.SipGatewaySession.run().1552 [ctx=1672939045853468908259] Wait thread cancelled
2023-01-05 18:17:25.854 INFO: [118177] org.jitsi.jigasi.JvbConference.start().471 [ctx=1672939045853468908259] Starting JVB conference room: neuer_test-644@conference.xxxxxxxx
2023-01-05 18:17:25.854 INFO: [118177] org.jitsi.jigasi.JvbConference.createAccountPropertiesForCallId().1575 [ctx=1672939045853468908259] Using bosh url:http://10.0.0.101:5280/http-bind?room=neuer_test-644&token=xxxxxxxxxxxxxxxxxx
2023-01-05 18:17:25.855 INFO: [118177] org.jitsi.jigasi.JvbConference.setXmppProvider().607 [ctx=1672939045853468908259] Using ProtocolProviderServiceJabberImpl(Jabber:44844be6@xxxxxxxx/44844be6)
2023-01-05 18:17:25.862 INFO: [118181] org.igniterealtime.jbosh.BOSHClient.init() Starting with 1 request processors
2023-01-05 18:17:25.868 INFO: [118181] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().127 Jingle : ON 
2023-01-05 18:17:25.873 INFO: [118181] org.jitsi.jigasi.JvbConference.registrationStateChanged().666 [ctx=1672939045853468908259] Registering XMPP.
2023-01-05 18:17:25.892 INFO: [118181] impl.protocol.jabber.ProtocolProviderServiceJabberImpl.authenticated().2423 Authenticated: false
2023-01-05 18:17:25.893 INFO: [118181] org.jitsi.jigasi.JvbConference.joinConferenceRoom().752 [ctx=1672939045853468908259] Joining JVB conference room: neuer_test-644@conference.xxxxxxxx
2023-01-05 18:17:25.919 INFO: [118187] impl.protocol.jabber.ChatRoomJabberImpl.joined().1369 neuer_test-644@conference.xxxxxxxx/8d5f4428 has joined the neuer_test-644@conference.xxxxxxxx chat room.
2023-01-05 18:17:25.920 INFO: [118187] impl.protocol.jabber.ChatRoomJabberImpl.joined().1369 neuer_test-644@conference.xxxxxxxx/focus has joined the neuer_test-644@conference.xxxxxxxx chat room.
2023-01-05 18:17:25.921 SEVERE: [118181] org.jitsi.jigasi.JvbConference.registrationStateChanged().655 [ctx=1672939045853468908259] Registered bosh sid: 2f9b72fc-0ab9-4ffa-9ec1-0e1254ada2b9
2023-01-05 18:17:26.949 INFO: [118199] impl.protocol.jabber.IceUdpTransportManager.createIceAgent().347 End gathering harvester within 1 ms
2023-01-05 18:17:27.017 INFO: [118199] impl.protocol.jabber.CallPeerMediaHandlerJabberImpl.harvestCandidates().1198 End candidate harvest within 68 ms
2023-01-05 18:17:27.020 INFO: [118199] org.jitsi.jigasi.JvbConference.incomingCallReceived().1328 [ctx=1672939045853468908259] Got invite from focus
2023-01-05 18:17:27.025 INFO: [118076] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 101=rtpmap:-1 telephone-event/8000; 
2023-01-05 18:17:27.025 INFO: [118076] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides []
2023-01-05 18:17:27.036 INFO: [118076] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1486 [ctx=1672939045853468908259] SIP peer state: Connecting*
2023-01-05 18:17:27.037 INFO: [118208] org.jitsi.jigasi.SipGatewaySession.handleCallState().1413 [ctx=1672939045853468908259] Sip call IN_PROGRESS: Call: id=1672939045853285905811 peers=1
2023-01-05 18:17:27.037 INFO: [118208] org.jitsi.jigasi.SipGatewaySession.handleCallState().1415 [ctx=1672939045853468908259] SIP call format used: rtpmap:0 PCMU/8000
2023-01-05 18:17:27.037 INFO: [118208] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1486 [ctx=1672939045853468908259] SIP peer state: Connected
2023-01-05 18:17:27.038 INFO: [118208] service.protocol.media.CallPeerMediaHandler.start().1961 Starting
2023-01-05 18:17:27.092 INFO: [118205] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 126=rtpmap:-1 telephone-event/8000; 111=rtpmap:-1 opus/48000/2 fmtp:useinbandfec=1;minptime=10; 103=rtpmap:-1 unknown/90000; 
2023-01-05 18:17:27.093 INFO: [118205] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides [103->104 ]
2023-01-05 18:17:27.096 INFO: [118205] service.protocol.media.CallPeerMediaHandler.start().1961 Starting
2023-01-05 18:17:27.121 INFO: [118208] service.protocol.media.TransportManager.sendHolePunchPacket().552 Send NAT hole punch packets
2023-01-05 18:17:27.142 SEVERE: [118271] net.sf.fmj.media.Log.error()   Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
2023-01-05 18:17:27.142 SEVERE: [118271] net.sf.fmj.media.Log.error() Failed to prefetch: net.sf.fmj.media.ProcessEngine@43c7a113
2023-01-05 18:17:27.143 SEVERE: [118268] net.sf.fmj.media.Log.error() Error: Unable to prefetch net.sf.fmj.media.ProcessEngine@43c7a113

2023-01-05 18:17:27.151 INFO: [118205] org.jitsi.jigasi.JvbConference.callStateChanged().1431 [ctx=1672939045853468908259] JVB conference call IN_PROGRESS.
2023-01-05 18:17:32.151 SEVERE: [54] org.jitsi.jigasi.JvbConference.dropCall().2044 [ctx=1672939045853468908259] No activity on JVB conference call will stop
2023-01-05 18:17:32.153 INFO: [54] org.jitsi.jigasi.JvbConference.memberPresenceChanged().1142 [ctx=1672939045853468908259] Member left : OWNER focus@auth.xxxxxxxx/focus
2023-01-05 18:17:32.153 INFO: [54] org.jitsi.jigasi.JvbConference.memberPresenceChanged().1142 [ctx=1672939045853468908259] Member left : GUEST 44844be6@xxxxxxxx
2023-01-05 18:17:32.154 INFO: [54] org.jitsi.jigasi.JvbConference.memberPresenceChanged().1142 [ctx=1672939045853468908259] Member left : OWNER 8d5f4428-18bd-49c7-a629-507d648c327a@xxxxxxxx/a97J-BzT
2023-01-05 18:17:32.171 INFO: [118076] org.jitsi.jigasi.SipGatewaySession.handleCallState().1425 [ctx=1672939045853468908259] SIP call ended: CallPeerChangeEvent: type=CallPeerStatusChange oldV=net.java.sip.communicator.service.protocol.CallPeerState:Connected newV=net.java.sip.communicator.service.protocol.CallPeerState:Failed for peer=0xxxxxxxx <0xxxxxxxx@10.0.0.170>;status=Failed
2023-01-05 18:17:32.172 INFO: [118076] org.jitsi.jigasi.SipGatewaySession.sipCallEnded().680 [ctx=1672939045853468908259] Sip call ended: Call: id=1672939045853285905811 peers=0
2023-01-05 18:17:32.172 INFO: [118076] org.jitsi.jigasi.AbstractGateway.notifyCallEnded().129 [ctx=1672939045853468908259] Removed session for call. Sessions:0
2023-01-05 18:17:32.172 INFO: [118076] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1486 null SIP peer state: Failed
2023-01-05 18:17:32.181 INFO: [118205] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().134 Jingle : OFF 
2023-01-05 18:17:32.181 INFO: [118205] org.jitsi.jigasi.CallManager$HangupCallThread.run().660 [ctx=1672939045853468908259] Removing account Jabber:44844be6@xxxxxxxx/44844be6
2023-01-05 18:17:32.181 INFO: [118205] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().134 Jingle : OFF

Videobridge

2023-01-05 18:17:23.693 INFO: [46] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4] EndpointConnectionStatusMonitor.start#58: Starting connection status monitor
2023-01-05 18:17:23.694 INFO: [46] Videobridge.createConference#282: create_conf, id=48f1c5848cf473f0 meetingId=2650f5e4-4b68-4779-985e-9f99b5a756b2
2023-01-05 18:17:23.737 INFO: [48] org.ice4j.ice.harvest.AbstractUdpListener.<init>: Initialized AbstractUdpListener with address xxxxxxxx:10000/udp. Receive buffer size 10485760 (asked for 10485760)
2023-01-05 18:17:23.737 INFO: [48] org.ice4j.ice.harvest.SinglePortUdpHarvester.<init>: Initialized SinglePortUdpHarvester with address xxxxxxxx:10000/udp
2023-01-05 18:17:23.743 WARNING: [48] Harvesters.initializeStaticConfiguration#103: Failed to initialize TCP harvester on port 443
2023-01-05 18:17:23.751 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.gatherCandidates#647: Gathering candidates for component stream-0b0e54b0.RTP.
2023-01-05 18:17:24.416 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] Endpoint.<init>#328: Created new endpoint isUsingSourceNames=false, iceControlling=true
2023-01-05 18:17:24.441 WARNING: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4] Conference.lambda$new$0#239: Took 742 ms to process an IQ (total delay 765 ms): <iq xmlns='jabber:client' to='jvb@auth.xxxxxxxx/zT3BLPPv' from='jvbbrewery@internal.auth.xxxxxxxx/focus' id='anZiQGF1dGgubWVldDIucGFudGhlci1wYWNrYWdpbmcuY29tL3pUM0JMUFB2AEdIWTY3LTEwANiqpjG+6gFTypwk+ATh6NU=' type='get'><conference-modify xmlns='jitsi:colibri2' meeting-id='2650f5e4-4b68-4779-985e-9f99b5a756b2' name='neuer_test-644@conference.xxxxxxxx' rtcstats-enabled='false' create='true'><endpoint xmlns='jitsi:colibri2' create='true' id='0b0e54b0'><media type='audio'><payload-type xmlns='urn:xmpp:jingle:apps:rtp:1' name='opus' clockrate='48000' channels='2' id='111'><parameter name='minptime' value='10'/><parameter name='useinbandfec' value='1'/></payload-type><payload-type xmlns='urn:xmpp:jingle:apps:rtp:1' name='ISAC' clockrate='16000' id='103'/><payload-type xmlns='urn:xmpp:jingle:apps:rtp:1' name='ISAC' clockrate='32000' id='104'/><payload-type xmlns='urn:xmpp:jingle:apps:rtp:1' name='telephone-event' clockrate='8000' id='126'/><rtp-hdrext xmlns='urn:xmpp:jingle:apps:rtp:rtp-hdrext:0' uri='urn:ietf:params:rtp-hdrext:ssrc-audio-level' id='1'/></media><transport ice-controlling='true'/><sources/></endpoint></conference-modify></iq>
2023-01-05 18:17:24.443 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.gatherCandidates#647: Gathering candidates for component stream-8d5f4428.RTP.
2023-01-05 18:17:24.448 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428] Endpoint.<init>#328: Created new endpoint isUsingSourceNames=true, iceControlling=true
2023-01-05 18:17:24.452 INFO: [48] SctpManager.<clinit>#62: Initializing Sctp4j
SCTP JNI load: Linux OS detected
SCTP lib loaded
=====>: org_jitsi_modified_sctp4j_SctpJni.c calling init
2023-01-05 18:17:24.536 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9 name=stream-0b0e54b0 componentId=1] Component.addRemoteCandidate#328: Add remote candidate for stream-0b0e54b0.RTP: xxxxxxxx:10034/udp/host
2023-01-05 18:17:24.536 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9] IceTransport.startConnectivityEstablishment#193: Starting the agent with remote candidates.
2023-01-05 18:17:24.536 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
2023-01-05 18:17:24.536 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.initCheckLists#972: Init checklist for stream stream-0b0e54b0
2023-01-05 18:17:24.538 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.setState#946: ICE state changed from Waiting to Running.
2023-01-05 18:17:24.539 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
2023-01-05 18:17:24.539 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
2023-01-05 18:17:24.570 INFO: [54] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.startCheckForPair#350: Could not start connectivity check: No socket found for xxxxxxxx:10000/udp->xxxxxxxx:10034/udp
2023-01-05 18:17:24.599 INFO: [57] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: xxxxxxxx:10000/udp/host -> xxxxxxxx:10034/udp/host (stream-0b0e54b0.RTP).
2023-01-05 18:17:24.599 INFO: [57] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9 name=stream-0b0e54b0 componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: xxxxxxxx:10034/udp
2023-01-05 18:17:24.600 INFO: [57] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: xxxxxxxx:10000/udp/host -> xxxxxxxx:10034/udp/host (stream-0b0e54b0.RTP).
2023-01-05 18:17:24.600 INFO: [57] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] DefaultNominator.strategyNominateFirstValid#143: Nominate (first valid): xxxxxxxx:10000/udp/host -> xxxxxxxx:10034/udp/host (stream-0b0e54b0.RTP).
2023-01-05 18:17:24.600 INFO: [57] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.nominate#1810: verify if nominated pair answer again
2023-01-05 18:17:24.600 INFO: [57] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
2023-01-05 18:17:24.621 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: xxxxxxxx:10000/udp/host -> xxxxxxxx:10034/udp/host (stream-0b0e54b0.RTP).
2023-01-05 18:17:24.621 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
2023-01-05 18:17:24.622 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: xxxxxxxx:10000/udp/host -> xxxxxxxx:10034/udp/host (stream-0b0e54b0.RTP).
2023-01-05 18:17:24.622 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9 name=stream-0b0e54b0] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-0b0e54b0.RTP: xxxxxxxx:10000/udp/host -> xxxxxxxx:10034/udp/host (stream-0b0e54b0.RTP)
2023-01-05 18:17:24.623 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.checkListStatesUpdated#1901: CheckList of stream stream-0b0e54b0 is COMPLETED
2023-01-05 18:17:24.623 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.setState#946: ICE state changed from Running to Completed.
2023-01-05 18:17:24.623 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9] IceTransport.iceStateChanged#342: ICE state changed old=Running new=Completed
2023-01-05 18:17:24.623 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] Endpoint$setupIceTransport$2.connected#375: ICE connected
2023-01-05 18:17:24.626 INFO: [52] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=null
2023-01-05 18:17:24.626 WARNING: [52] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] DtlsTransport.startDtlsHandshake#104: Starting the DTLS stack before it knows its role
2023-01-05 18:17:24.628 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.logCandTypes#2009: Harvester used for selected pair for stream-0b0e54b0.RTP: host
2023-01-05 18:17:24.636 INFO: [48] PartitionedByteBufferPool.<init>#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
2023-01-05 18:17:24.636 INFO: [48] PartitionedByteBufferPool.<init>#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
2023-01-05 18:17:24.637 INFO: [48] PartitionedByteBufferPool.<init>#84: Initialized a new PartitionedByteBufferPool with 8 partitions.
2023-01-05 18:17:24.740 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
2023-01-05 18:17:24.753 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir] IceTransport.startConnectivityEstablishment#199: Starting the Agent without remote candidates.
2023-01-05 18:17:24.753 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.startConnectivityEstablishment#736: Start ICE connectivity establishment.
2023-01-05 18:17:24.753 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.initCheckLists#972: Init checklist for stream stream-8d5f4428
2023-01-05 18:17:24.753 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.setState#946: ICE state changed from Waiting to Running.
2023-01-05 18:17:24.754 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir] IceTransport.iceStateChanged#342: ICE state changed old=Waiting new=Running
2023-01-05 18:17:24.754 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.startChecks#147: Start connectivity checks.
2023-01-05 18:17:24.976 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.triggerCheck#1737: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7961272326111100927):
	LocalCandidate=candidate:1 1 udp 2130706431 xxxxxxxx 10000 typ host
	RemoteCandidate=candidate:10000 1 udp 1853628159 xxxxxxxx 46669 typ prflx
2023-01-05 18:17:25.009 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: xxxxxxxx:10000/udp/host -> xxxxxxxx:46669/udp/prflx (stream-8d5f4428.RTP).
2023-01-05 18:17:25.010 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir name=stream-8d5f4428 componentId=1] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: xxxxxxxx:46669/udp
2023-01-05 18:17:25.010 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: xxxxxxxx:10000/udp/srflx -> xxxxxxxx:46669/udp/prflx (stream-8d5f4428.RTP).
2023-01-05 18:17:25.010 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] DefaultNominator.strategyNominateFirstValid#143: Nominate (first valid): xxxxxxxx:10000/udp/srflx -> xxxxxxxx:46669/udp/prflx (stream-8d5f4428.RTP).
2023-01-05 18:17:25.010 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.nominate#1810: verify if nominated pair answer again
2023-01-05 18:17:25.010 WARNING: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir name=stream-8d5f4428 componentId=1] MergingDatagramSocket.initializeActive#599: Active socket already initialized.
2023-01-05 18:17:25.011 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:false.
2023-01-05 18:17:25.047 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.processSuccessResponse#649: Pair succeeded: xxxxxxxx:10000/udp/srflx -> xxxxxxxx:46669/udp/prflx (stream-8d5f4428.RTP).
2023-01-05 18:17:25.047 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.processSuccessResponse#658: Pair validated: xxxxxxxx:10000/udp/srflx -> xxxxxxxx:46669/udp/prflx (stream-8d5f4428.RTP).
2023-01-05 18:17:25.047 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.processSuccessResponse#727: IsControlling: true USE-CANDIDATE:true.
2023-01-05 18:17:25.047 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] ConnectivityCheckClient.processSuccessResponse#742: Nomination confirmed for pair: xxxxxxxx:10000/udp/srflx -> xxxxxxxx:46669/udp/prflx (stream-8d5f4428.RTP).
2023-01-05 18:17:25.047 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir name=stream-8d5f4428] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-8d5f4428.RTP: xxxxxxxx:10000/udp/srflx -> xxxxxxxx:46669/udp/prflx (stream-8d5f4428.RTP)
2023-01-05 18:17:25.048 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.checkListStatesUpdated#1901: CheckList of stream stream-8d5f4428 is COMPLETED
2023-01-05 18:17:25.048 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.setState#946: ICE state changed from Running to Completed.
2023-01-05 18:17:25.048 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir] IceTransport.iceStateChanged#342: ICE state changed old=Running new=Completed
2023-01-05 18:17:25.048 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint$setupIceTransport$2.connected#375: ICE connected
2023-01-05 18:17:25.048 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@5b635418
2023-01-05 18:17:25.049 INFO: [60] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.logCandTypes#2009: Harvester used for selected pair for stream-8d5f4428.RTP: srflx
2023-01-05 18:17:25.063 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] TlsServerImpl.notifyClientVersion#199: Negotiated DTLS version DTLS 1.2
2023-01-05 18:17:25.175 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint$setupDtlsTransport$3.handshakeComplete#419: DTLS handshake complete
2023-01-05 18:17:25.180 INFO: [67] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint.acceptSctpConnection$lambda-28#660: Attempting to establish SCTP socket connection
Got sctp association state update: 1
sctp is now up.  was ready? false
2023-01-05 18:17:25.254 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4] Conference.recentSpeakersChanged#467: Recent speakers changed: [8d5f4428], dominant speaker changed: true silence:false
2023-01-05 18:17:25.280 INFO: [68] JitsiOpenSslProvider.<clinit>#52: jitsisrtp successfully loaded for OpenSSL 1.1
2023-01-05 18:17:25.281 INFO: [67] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint$createSctpConnection$3.onReady#608: SCTP connection is ready, creating the Data channel stack
2023-01-05 18:17:25.286 INFO: [67] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint$createSctpConnection$3.onReady#632: Will wait for the remote side to open the data channel.
2023-01-05 18:17:25.291 INFO: [68] Aes.createCipher#433: Will employ AES implemented by OpenSSL for AES/GCM/NoPadding.
2023-01-05 18:17:25.293 INFO: [68] Aes.createCipher#433: Will employ AES implemented by OpenSSL for AES/CTR/NoPadding.
2023-01-05 18:17:27.629 INFO: [53] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9] Agent.setState#946: ICE state changed from Completed to Terminated.
2023-01-05 18:17:27.630 INFO: [53] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9] IceTransport.iceStateChanged#342: ICE state changed old=Completed new=Terminated
2023-01-05 18:17:28.049 INFO: [53] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir] Agent.setState#946: ICE state changed from Completed to Terminated.
2023-01-05 18:17:28.049 INFO: [53] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir] IceTransport.iceStateChanged#342: ICE state changed old=Completed new=Terminated
2023-01-05 18:17:29.721 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] AbstractEndpoint.expire#289: Expiring.
2023-01-05 18:17:29.733 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] Endpoint.updateStatsOnExpire#1074: Expiring an endpoint with ICE connected, but not DTLS.
2023-01-05 18:17:29.734 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] Endpoint.expire#1112: Spent 0 seconds oversending
2023-01-05 18:17:29.734 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] Transceiver.teardown#353: Tearing down
2023-01-05 18:17:29.735 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] RtpReceiverImpl.tearDown#347: Tearing down
2023-01-05 18:17:29.735 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] RtpSenderImpl.tearDown#318: Tearing down
2023-01-05 18:17:29.736 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] DtlsTransport.stop#178: Stopping
2023-01-05 18:17:29.736 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9] IceTransport.stop#252: Stopping
2023-01-05 18:17:29.738 INFO: [56] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9 name=stream-0b0e54b0 componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
2023-01-05 18:17:29.739 WARNING: [56] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9 name=stream-0b0e54b0 componentId=1] MergingDatagramSocket.doRemove#349: Removing the active socket. Won't be able to send until a new one is elected.
2023-01-05 18:17:29.740 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9 ufrag=7jrt81gm1evus9 name=stream-0b0e54b0 componentId=1] MergingDatagramSocket.close#142: Closing.
2023-01-05 18:17:29.740 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9] IceTransport.startReadingData#218: Socket closed, stopping reader
2023-01-05 18:17:29.740 INFO: [48] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0 local_ufrag=7jrt81gm1evus9] IceTransport.startReadingData#230: No longer running, stopped reading packets
2023-01-05 18:17:29.740 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=0b0e54b0] Endpoint.expire#1130: Expired.
Got sctp association state update: 2
2023-01-05 18:17:49.775 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint$createSctpConnection$3.onDisconnected#637: SCTP connection is disconnected
2023-01-05 18:17:49.776 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4] Colibri2ConferenceHandler.handleConferenceModifyIQ#68: Received request to expire conference.
2023-01-05 18:17:49.776 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4] Conference.expire#572: Expiring.
2023-01-05 18:17:49.777 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4] EndpointConnectionStatusMonitor.stop#66: Stopped
2023-01-05 18:17:49.777 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] TlsServerImpl.notifyAlertReceived#245: close_notify received, connection closing
2023-01-05 18:17:49.778 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] AbstractEndpoint.expire#289: Expiring.
2023-01-05 18:17:49.787 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint.expire#1112: Spent 0 seconds oversending
2023-01-05 18:17:49.787 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Transceiver.teardown#353: Tearing down
2023-01-05 18:17:49.787 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] RtpReceiverImpl.tearDown#347: Tearing down
2023-01-05 18:17:49.790 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] RtpSenderImpl.tearDown#318: Tearing down
2023-01-05 18:17:49.791 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] DtlsTransport.stop#178: Stopping
2023-01-05 18:17:49.792 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir] IceTransport.stop#252: Stopping
2023-01-05 18:17:49.794 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir name=stream-8d5f4428 componentId=1] MergingDatagramSocket.close#142: Closing.
2023-01-05 18:17:49.794 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir] IceTransport.startReadingData#218: Socket closed, stopping reader
2023-01-05 18:17:49.795 INFO: [61] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir] IceTransport.startReadingData#230: No longer running, stopped reading packets
2023-01-05 18:17:49.795 INFO: [66] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm local_ufrag=21gjv1gm1evvir ufrag=21gjv1gm1evvir name=stream-8d5f4428 componentId=1] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
2023-01-05 18:17:49.795 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4 epId=8d5f4428 stats_id=Mekhi-ymm] Endpoint.expire#1130: Expired.
2023-01-05 18:17:49.797 INFO: [63] [confId=48f1c5848cf473f0 conf_name=neuer_test-644@conference.xxxxxxxx meeting_id=2650f5e4] Conference.updateStatisticsOnExpire#635: expire_conf,duration=26,has_failed=false,has_partially_failed=false
8

To complete everything, I added my config from the videobridge and jicofo:

videobridge

videobridge {
  entity-expiration {
    # If an entity has no activity after this timeout, it is expired
    timeout=1 minute

    # The interval at which the videobridge will check for expired entities
    check-interval=${videobridge.entity-expiration.timeout}
  }
  health {
    # The interval between health checks
    interval=1 minute

    # The timeout for a health check. This needs to be higher than [interval], otherwise health checks timeout because
    # none were scheduled.
    timeout=90 seconds

    # If performing a health check takes longer than this, it is considered unsuccessful.
    max-check-duration=3 seconds

    # Whether or not health check failures should be 'sticky'
    # (i.e. once the bridge becomes unhealthy, it will never
    # go back to a healthy state)
    sticky-failures=false
  }
  ep-connection-status {
    # How long we'll wait for an endpoint to *start* sending
    # data before we consider it 'inactive'
    first-transfer-timeout=15 seconds

    # How long an endpoint can be 'inactive' before it will
    # be considered disconnected
    max-inactivity-limit=3 seconds

    # How often we check endpoint's connectivity status
    check-interval=500 milliseconds
  }
  cc {
    bwe-change-threshold=0.15
    thumbnail-max-height-px=180
    onstage-ideal-height-px=1080
    onstage-preferred-height-px=360
    onstage-preferred-framerate=30
    // Whether the bridge is allowed to oversend (send the lowest layer regardless of BWE) for on-stage endpoints. If
    // allowed, it's only used when an endpoint is screensharing.
    allow-oversend-onstage=true
    // The maximum bitrate by which the estimation will be exceeded when oversending (if oversending is allowed).
    max-oversend-bitrate=500 kbps
    trust-bwe=true

    # How often we check to send probing data
    padding-period=15ms

    # How often we'll force recalculations of forwarded
    # streams
    max-time-between-calculations = 15 seconds

    # A JVB-wide last-n value, observed by all endpoints.  Endpoints
    # will take the minimum of their setting and this one (-1 implies
    # no last-n limit)
    jvb-last-n = -1
  }
  # The APIs by which the JVB can be controlled
  apis {
    xmpp-client {
      # The interval at which presence is published in the configured MUCs.
      presence-interval = ${videobridge.stats.interval}

      # Controls which statistics are sent.
      stats-filter {
        # Whether to filter the statistics.
        # If true, send whitelisted keys only. If false, send all statistics.
        enabled = false

        # Which statistics to send, when filter is enabled.
        # Ignored if filter is disabled.
        whitelist = ["average_participant_stress", "colibri2", "current_timestamp", "drain", "graceful_shutdown",
          "healthy", "region", "relay_id", "release", "shutting_down", "stress_level", "version"]
      }

      # The size of the Smack JID cache
      jid-cache-size = 1000

    configs {
                shard-local {
                    hostname = "xxxxxxxxx"
                    domain = "auth.xxxxxxxx"
                    username = "jvb"
                    password = "xxxxxxxxxxxxxxxx"
                    muc_jids = "JvbBrewery@internal.auth.xxxxxxxx"
                    muc_nickname = "xxxxxxxxxx"
                    disable_certificate_verification = true
                }
      }
    }
    # The COLIBRI REST API
    rest {
      enabled = true
    }
    jvb-api {
      enabled = true
    }
  }
  # Configuration of the different REST APIs.
  # Note that the COLIBRI REST API is configured under videobridge.apis.rest instead.
  rest {
    debug {
      enabled = true
    }
    health {
      enabled = true
    }
    shutdown {
      # Note that the shutdown API requires the COLIBRI API to also be enabled.
      enabled = true
    }
    drain {
      enabled = true
    }
    version {
      enabled = true
    }
  }
  http-servers {
    # The HTTP server which hosts services intended for 'public' use
    # (e.g. websockets for the bridge channel connection)
    public {
      # See JettyBundleActivatorConfig in Jicoco for values
      port = 9090
    }
    # The HTTP server which hosts services intended for 'private' use
    # (e.g. health or debug stats)
    private {
      # See JettyBundleActivatorConfig in Jicoco for values
      host = 0.0.0.0
      port = 9000
    }
  }
  relay {
    # Whether or not relays (octo) are enabled
    enabled=true

    # A string denoting the 'region' of this JVB. This region will be used by Jicofo in the selection of a bridge for
    # a client by comparing it to the client's region.
    # Must be set when 'enabled' is true.
    region="germany"

    # The unique identifier of the jitsi-videobridge instance as a relay.
    # Must be set when 'enabled' is true.
    relay-id="xxxxxxxx"
  }
  load-management {
    # Whether or not the reducer will be enabled to take actions to mitigate load
    reducer-enabled = false
    load-measurements {
      packet-rate {
        # The packet rate at which we'll consider the bridge overloaded
        load-threshold = 50000
        # The packet rate at which we'll consider the bridge 'underloaded' enough
        # to start recovery
        recovery-threshold = 40000
      }
    }
    load-reducers {
      last-n {
        # The factor by which we'll reduce the current last-n when trying to reduce load
        reduction-scale = .75
        # The factor by which we'll increase the current last-n when trying to recover
        recover-scale = 1.25
        # The minimum time in between runs of the last-n reducer to reduce or recover from
        # load
        impact-time = 1 minute
        # The lowest value we'll set for last-n
        minimum-last-n-value = 1
        # The highest last-n value we'll enforce.  Once the enforced last-n exceeds this value
        # we'll remove the limit entirely
        maximum-enforced-last-n-value = 40
      }
    }
    // LastN limits based on conference size. Maps a conference size to the maximum number of streams that will be
    // forwarded to endpoints in a conference of that size or larger (up until the next entry).
    conference-last-n-limits {
      // With these example values conferences with size<20 endpoints can have arbitrary lastN, conferences with
      // 20 < size <= 29 can have lastN at most 20, conferences 30 < size <= 50 can have lastN at most 15.
      #20 = 20,
      #30 = 15,
      #50 = 10,
      #90 = 2
    }
    // An estimation of the stress an endpoint adds to the bridge when ramped up. This is communicated to jicofo
    // together with the stress level, where it is used to calculate an augmented stress value for the bridge, which
    // takes into account recently added endpoints:
    // augmented_stress = jvb_reported_stress + number_recently_added_participants * average-participant-stress
    average-participant-stress = 0.01
  }
  sctp {
    # Whether SCTP data channels are enabled.
    enabled=true
  }
  stats {
    # The interval at which stats are gathered.
    interval = 5 seconds
    transports = [
      { type = "muc" }
    ]
  }
  websockets {
    enabled=true
    server-id="xxxxxxxx"
    # Whether to negotiate WebSocket compression (permessage-deflate)
    enable-compression = true

    # Optional, even when 'enabled' is set to true
    tls=true
    # Must be set when enabled = true
    domain="xxxxxxxx:443"
  }
  ice {
    tcp {
      # Whether ICE/TCP is enabled.
      enabled = true

      # The port to bind to for ICE/TCP.
      # port = 443

      # An optional additional port to advertise.
      # mapped-port = 8443
      # Whether to use "ssltcp" or plain "tcp".
      # ssltcp = true
    }

    udp {
        # The port for ICE/UDP.
        port = 10000
    }

    # An optional prefix to include in STUN username fragments generated by the bridge.
    # ufrag-prefix = "xxxxxxxx:"

    # Which candidate pairs to keep alive. The accepted values are defined in ice4j's KeepAliveStrategy:
    # "selected_and_tcp", "selected_only", or "all_succeeded".
    keep-alive-strategy = "selected_and_tcp"

    # Whether to use the "component socket" feature of ice4j.
    use-component-socket = true

    # Whether to attempt DNS resolution for remote candidates that contain a non-literal address. When set to 'false'
    # such candidates will be ignored.
    resolve-remote-candidates = false

    # The nomination strategy to use for ICE.  THe accepted values are defined in ice4j's NominationStrategy:
    # "NominateFirstValid", "NominateHighestPriority", "NominateFirstHostOrReflexiveValid", or "NominateBestRTT".
    nomination-strategy = "NominateFirstValid"

    # Whether to advertise private ICE candidates, i.e. RFC 1918 IPv4 addresses and fec0::/10 and fc00::/7 IPv6 addresses.
    advertise-private-candidates = true
  }

  transport {
    send {
      # The size of the dtls-transport outgoing queue. This is a per-participant
      # queue. Packets from the egress end-up in this queue right before
      # transmission by the outgoing srtp pipeline (which mainly consists of the
      # packet sender).
      #
      # Its size needs to be of the same order of magnitude as the rtp sender
      # queue. In a 100 participant call, assuming 300pps for the on-stage and
      # 100pps for low-definition, last-n 20 and 2 participants talking, so
      # 2*50pps for audio, this queue is fed 300+19*100+2*50 = 2300pps, so its
      # size in terms of millis is 1024/2300*1000 ~= 445ms.
      queue-size=1024
    }
  }

  # The experimental multiple streams per endpoint support
  multi-stream {
    enabled = true
  }

  speech-activity {
    # The number of speakers to include in the list of recent speakers sent with dominant speaker change
    # notifications.
    recent-speakers-count = 10
  }

  loudest {
      # Whether to route only the loudest speakers. If false, all audio is forwarded.
      route-loudest-only = false

      # The number of current loudest speakers to route audio packets for.
      # Ignored if route-loudest-only = false.
      num-loudest = 3

      # Whether to route dominant speaker when it is not among the current loudest speakers.
      # Ignored if route-loudest-only = false.
      always-route-dominant = true

      # Time after which speaker is removed from loudest list if
      # no new audio packets have been received from that speaker.
      energy-expire-time = 150 milliseconds

      # Alpha factor for exponential smoothing of energy values, multiplied by 100.
      energy-alpha-pct = 50
  }

  version {
    # Whether to announce the jitsi-videobridge version to clients in the ServerHello message.
    announce = false

    # A release identifier. When set, this will be appended to the version string
    # that is associated with this bridge when selecting bridges for a conference.
    # This allows finer-grained control of the selection process.
    # Note that multiple bridges must have the same version and release identifier
    # in order to share conferences between them.
    #release = "123"
  }

  shutdown {
    # The maximum amount of time to stay in GRACEFUL_SHUTDOWN before going into SHUTTING_DOWN. See ShutdownManager.
    graceful-shutdown-max-duration = 24 hours

    # The minimum number of participants required to stay in GRACEFUL_SHUTDOWN. If the number of participants falls
    # at or below this threshold the bridge will transition to SHUTTING_DOWN. See ShutdownManager.
    graceful-shutdown-min-participants = 0

    # The amount of time to stay in SHUTTING_DOWN before acutally shutting down. Gives jicofo time to actively move
    # participants off this bridge.
    shutting-down-delay = 1 minutes
  }

  # Whether to start the videobridge in drain mode.
  initial-drain-mode = false

  # In small conferences we disable the stats filter and always forward stats. This sets the minimum conference
  # size for which the filter is enabled.
  stats-filter-threshold = 20
}

jicofo

jicofo {
  xmpp: {
    client: {
      domain: auth.xxxxxxxx.xxxxxxxx
      xmpp-domain: xxxxxxxx.xxxxxxxx
      client-proxy: "focus.xxxxxxxxxxxx"
      conference-muc-jid = conference.xxxxxxxx.xxxxxxxx

      enabled = true
      hostname = "xxxxxxxxx"
      port = 5222
      username = "focus"
      password = "xxxxxxxxxxx"

      disable-certificate-verification = true
    }
    trusted-domains: [ "recorder.xxxxxxxx.xxxxxxxx" ]
  }
  bridge: {
    brewery-jid: "JvbBrewery@internal.auth.xxxxxxxx.xxxxxxxx"
    health-checks {
        enabled = true
        interval = 10 seconds
    }
    selection-strategy = IntraRegionBridgeSelectionStrategy
  }
  octo {
    enabled = True
    id = 42
  }
  rest {
    port = 9002
  }
}
9

Is the user that is already in the meeting unmuted and sending audio?
Double-check that jigasi can connect to the IP of the bridge using UDP port 10000?
When you do a 3 way call on your deployment it works?

10

Hi @damencho

thank you for the answer.

The user in the conference is unmuted and is sending audio and video correctly. The video and audio stream is send to all participants that are in the conference via Browser.

If I use the old videobridge, the communication between jigasi and the internal IP via UDP 10000 is working.

I have diabled the P2P meeting in the config.js. All conferences connect directly via the videobridge. I tested a conference with P2P and n2n (3 …) and they are working, exept of jigasi sip callers