Endpoints were suspended due to insufficient bandwidth (bwe=-1 bps), but only sometimes?

Hello everyone!

I was made aware that a meeting had no video or audio, looking at the logs of the jvb I found the following:

{"log":"JVB 2022-04-25 11:08:00.363 INFO: [73] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] BandwidthAllocator.allocate#379: Endpoints were suspended due to insufficient bandwidth (bwe=-1 bps): dbdd6580\n","stream":"stderr","time":"2022-04-25T09:08:00.363762547Z"}
{"log":"JVB 2022-04-25 11:08:02.845 WARNING: [162] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: ad2ddf30\n","stream":"stderr","time":"2022-04-25T09:08:02.84571816Z"}
{"log":"JVB 2022-04-25 11:08:06.418 WARNING: [166] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: ad2ddf30\n","stream":"stderr","time":"2022-04-25T09:08:06.418718155Z"}
{"log":"JVB 2022-04-25 11:08:06.546 WARNING: [39] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] AbstractEndpointMessageTransport.onMessage#67: Invalid message received (Parameter specified as non-null is null: method org.jitsi.videobridge.message.EndpointStats.put, parameter value (through reference chain: org.jitsi.videobridge.message.EndpointStats[\"connectionQuality\"]): {\"colibriClass\":\"EndpointStats\",\"bitrate\":{\"upload\":71,\"download\":32,\"audio\":{\"upload\":0,\"download\":32},\"video\":{\"upload\":69,\"download\":0}},\"packetLoss\":{\"total\":0,\"download\":0,\"upload\":0},\"connectionQuality\":null,\"jvbRTT\":47,\"maxEnabledResolution\":2160}\n","stream":"stderr","time":"2022-04-25T09:08:06.547012899Z"}

Unfortunately, I wasn’t able to reproduce the issue myself and don’t know what clients were used in the meeting. Apart from disabling bandwidth-estimation, which I would rather not, and seeing whether the issue shows up again, does someone have an idea how I might be able to reproduce the issue or what to look for in the logs for more relevant information?
When I start a meeting, the bwe is always sensible, not the -1 that was reported in the log above; are there any typical reasons why -1 could be reported for bwe if usually the estimation seems to work?

I’m running jitsi meet with docker compose, stable-7001, according to the quick-start guide with no other special configuration.
Websockets seem to work properly, no errors in browser console, and both P2P meetings and meetings through JVB work with participants via iOS, Firefox, and Chromium (Brave). In general, meetings (also with external participants) work fine, so I would assume the configuration of ports, websockets, etc. is in order. The only warnings I receive in the browser console are:

2022-04-28T11:27:42.149Z [modules/statistics/AnalyticsAdapter.js] <dispose>:  Disposing of analytics adapter.
2022-04-28T11:27:42.306Z [features/base/devices] <MC>:  Adjusting audio output is not supported
2022-04-28T11:27:43.142Z [modules/xmpp/strophe.jingle.js] <2849/getStunAndTurnCredentials/<>:  getting turn credentials with extdisco:2 failed, trying extdisco:1
2022-04-28T11:27:43.147Z [modules/xmpp/strophe.jingle.js] <2849/getStunAndTurnCredentials/</<>:  getting turn credentials failed
2022-04-28T11:27:43.147Z [modules/xmpp/strophe.jingle.js] <2849/getStunAndTurnCredentials/</<>:  is mod_turncredentials or similar installed and configured?
The description does not look like plan-b

But these don’t seem critical to me.

Ping @Boris_Grozev

1 Like

These are probably because an endpoint (ad2ddf30) just expired, or just joined. They should be harmless.

This one is also harmless. It happens because the endpoint reported "connectionQuality":null and the bridge does not expect nulls. The only result would be the stats not being forwarded. Fixed here:

This is strange. It means that media started flowing at least 10 secnds earlier, and no BWE was produced. Potentially TCC was not signaled properly, or the client did not send any TCC feedback.

1 Like

Thank you for your answer!
The last three lines always occured together with the first, so I thought they might be related; good to know that these are harmless!

What I didn’t really say in the first post was that the bwe=-1 bps is repeated every 0.5 to 1.5 seconds from what seems to be the point where a participant joined the meeting.
Thr first time it occured was about 11 seconds after the Pair Succeeded, Pair Validated, … messages.

I gave the logs a more thorough look now and excluded any Warnings etc. that were also present when the meetings function as expected.
What I found was the following line, repeated a few times throughout the log of the problematic meeting (once relatively at the beginning; then about 16s later repeated five times in the course of another 16s):

{"log":"JVB 2022-04-25 11:03:14.952 WARNING: [38] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.acceptWebSocket#671: Incoming web socket request with an invalid password. Expected: zmadmwato6q7wz6ea7gb5pmcti received 4cvmxb7rprgeadmqzaspq5y62\n","stream":"stderr","time":"2022-04-25T09:03:14.952470422Z"}

The password that was expected the one time at the beginnng was the received password the following times afterwards, but naturally the expected password was then different again.
If important, the received password the one time at the beginning was only 25 characters long whereas the expected one had 26 chars; the following times both had 26 chars.

Could that be the root of the problem?

Not the root of it, but it may be a clue. Assuming no intentional interference, a wrong password probably means that the endpoint on the bridge was re-created, but the browser was still trying to connect the WS with the previous password.

The “suspended” message would be repeated any time the bandwidth allocator runs (when varioous conditions change, or periodically every few seconds). How long did this endpoint ( e365ad04) stay in the conference? Was the report about missing audio/video for a specific participant, or the whole conference?

Can you share more of the logs? Jicofo logs are also worth looking at.

1 Like

The missing audio/video was for the whole conference, apparently noone was able to see or hear anything (apart from chat of course).

I had a look at the jicofo logs, and apparently e365ad04 was the endpoint that joined first, so it was one of our (local) participants, and in this case the only local participant. Consequentially, it stayed in the conference the longest (about 25min and until the end of the conference, it joined 15min before the other participants joined).

I only now got what the epID is; looking at it with that knowledge, I found that e365ad04 was the only endpoint that got the “suspended due to insufficient bandwidth” message, and I guess it would be logical if only it had no audio/video; I’ll ask again if possibly the remotes were able to see each other, but I don’t know if that’s known.

In the jicofo logs, nothing notable happened to e365ad04, except that it got timed out once towards the end of the meeting but joined again 3sec afterwards.
The other participants however continously went through the following (I regexed the log before going through it removing a bit of clutter, if I removed something important I’ll get the complete lines):

Jicofo 2022-04-25 11:03:29.895 INFO: [1935] DiscoveryUtil.discoverParticipantFeatures#154: Doing feature discovery for meeting@muc.meet.jitsi/b6bdff46
Jicofo 2022-04-25 11:03:29.895 INFO: [1935] DiscoveryUtil.discoverParticipantFeatures#194: Successfully discovered features for meeting@muc.meet.jitsi/b6bdff46 in 0
Jicofo 2022-04-25 11:03:29.896 INFO: meeting@muc.meet.jitsi ColibriV1SessionManager.allocate#145: Added participant id=b6bdff46, bridge=jvbbrewery@internal-muc.meet.jitsi/b03ef2e1b5e6
Jicofo 2022-04-25 11:03:29.896 INFO: meeting@muc.meet.jitsi ColibriV1SessionManager.allocateChannels#165: Using Bridge[jid=jvbbrewery@internal-muc.meet.jitsi/b03ef2e1b5e6, relayId=null, region=null, stress=0.03] to allocate channels for: Participant[meeting@muc.meet.jitsi/b6bdff46]@1131629735
Jicofo 2022-04-25 11:03:29.907 INFO: meeting@muc.meet.jitsi ParticipantInviteRunnable.doInviteOrReinvite#419: Sending session-initiate to: meeting@muc.meet.jitsi/b6bdff46
Jicofo 2022-04-25 11:03:30.133 INFO: meeting@muc.meet.jitsi JitsiMeetConferenceImpl.onSessionAccept#942: Receive session-accept from meeting@muc.meet.jitsi/b6bdff46
Jicofo 2022-04-25 11:03:30.133 INFO: meeting@muc.meet.jitsi JitsiMeetConferenceImpl.onSessionAcceptInternal#1310: Accepted initial sources from b6bdff46: {meeting@muc.meet.jitsi/b6bdff46=[audio=[2019363261], video=[], groups=[]]}
Jicofo 2022-04-25 11:03:47.335 INFO: meeting@muc.meet.jitsi JitsiMeetConferenceImpl.onSessionTerminate#1045: Received session-terminate from Participant[meeting@muc.meet.jitsi/b6bdff46]@1131629735, bridge-session ID: c36b11, restart: true
Jicofo 2022-04-25 11:03:47.335 INFO: meeting@muc.meet.jitsi JitsiMeetConferenceImpl.terminateParticipant#843: Terminating b6bdff46, reason: null, send session-terminate: false
Jicofo 2022-04-25 11:03:47.335 INFO: [1928] AbstractOperationSetJingle.terminateSession#509: Terminate session: meeting@muc.meet.jitsi/b6bdff46, reason: null, send terminate: false
Jicofo 2022-04-25 11:03:47.336 INFO: meeting@muc.meet.jitsi JitsiMeetConferenceImpl.terminateParticipant#866: Removed participant b6bdff46 removed=true
Jicofo 2022-04-25 11:03:47.336 INFO: meeting@muc.meet.jitsi BridgeSession.terminate#201: Expiring channels for: Participant[meeting@muc.meet.jitsi/b6bdff46]@1131629735 on: Bridge[jid=jvbbrewery@internal-muc.meet.jitsi/b03ef2e1b5e6, relayId=null, region=null, stress=0.02]

to which the following correlates in the jvb logs:

JVB 2022-04-25 11:03:29.897 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
JVB 2022-04-25 11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
JVB 2022-04-25 11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
JVB 2022-04-25 11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
JVB 2022-04-25 11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
JVB 2022-04-25 11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
JVB 2022-04-25 11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=amgj31g1fvbpdk gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
JVB 2022-04-25 11:03:29.898 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] Agent.setState#948: ICE state changed from Running to Terminated.
JVB 2022-04-25 11:03:29.899 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk name=stream-b6bdff46 epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] MergingDatagramSocket.close#142: Closing.
JVB 2022-04-25 11:03:29.899 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.

At last, only for b6bdff46 these two Warnings appeared once in the log (the other participants that continously went through the Infos above didn’t get any Warnings):

Jicofo 2022-04-25 11:03:47.337 WARNING: meeting@muc.meet.jitsi JitsiMeetConferenceImpl.onSessionTerminate#1069: Rate limiting Participant[meeting@muc.meet.jitsi/b6bdff46]@1131629735 for restart requests
...
Jicofo 2022-04-25 11:06:12.879 WARNING: meeting@muc.meet.jitsi JitsiMeetConferenceImpl.onMemberLeft#816: Participant not found for b6bdff46. Terminated already or never started?

b6bdff46 was also the only endpoint that got the “invalid password” error in the jvb log.

I would have expected to see something like that for e365ad04, since it got the suspended due to insufficient bandwidth messages; seemingly e365ad04, which has “problems” in the jvb log, has none in the jicofo log. The other endpoints, which have problems in the jicofo log, have none in the jvb log, apart from b6bdff46 with the “invalid password” - if I’m gathering that correctly.

There’s also a js exception and some other things in the jvb log, but these all happen for me as well during successful meetings, so they don’t seem to correspond to the problem here.

I can also post the complete logs and maybe the logs of a successful meeting as well if that’s easier; if it is, should I remove something from them before posting them?

The timing is interesting. Jicofo allocates an endpoint on the bridge at 11:03:29.896. Then the bridge expires the endpoint at 11:03:29.897 (assuming the clocks of jvb and jicfo are not too far off) before ICE had completed. This explains why the endpoint requested a restart 17 seconds later – with the endpoint expired on the bridge the browser would have seen ICE fail.

The “Rate limiting … for restart requests” message means this happened twice within 10 seconds, or 3 times within 1 minute. Are you saying you see a similar pattern (at least “Received session-terminate from … restart: true”) for the other endpoints?

Note that we forward audio regardless of BWE, so the BWE message would not explain the lack of audio. The BWE message is probably a result of whatever else was going wrong with the conference, not a cause.

Can you post more of the bridge logs prior to this expiration? I want to understand why the endpint was expired just after creation. It would be useful to know what type of thread [159] is, look for it’s previous logs, too.

1 Like

Yeah, the “Received session-terminate from … restart:true” seems to have happened to the remote participants. The local participant only was expired once but didn’t send the repeated session-terminates throughout the meeting.

Hmm, if the audio should be transmitted at all times, then there must be something else going wrong beside the BWE, from what I got told there definitely was no audio for anyone.

Below is about half of the JVB log throughout the meeting, the full one exceeds the body char limit - before are only the HealthChecker and VideobridgeExpireThread messages. The js exception in the and other warnings I didn’t mention before also happen during successful meetings, so are probably not important in the current regard if I had to guess.
Let me know if there’s other info that might be helpful or if I can help anyhow!

JVB Log
11:02:37.143 INFO: [28] [confId=96b19d990531c1ed gid=89263 conf_name=meeting@muc.meet.jitsi] EndpointConnectionStatusMonitor.start#57: Starting connection status monitor
11:02:37.143 INFO: [28] Videobridge.createConference#294: create_conf, id=96b19d990531c1ed gid=89263 meetingId=f1845b4c-3868-44e2-ab49-c8044e9b678e
11:02:37.144 INFO: [147] [confId=96b19d990531c1ed epId=e365ad04 local_ufrag=a8rob1g1fvan2o gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o] Agent.gatherCandidates#649: Gathering candidates for component stream-e365ad04.RTP.
11:02:37.148 WARNING: [147] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:02:37.148 WARNING: [147] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:02:37.148 INFO: [147] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:02:37.151 INFO: [147] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v] Agent.gatherCandidates#649: Gathering candidates for component stream-b6bdff46.RTP.
11:02:37.154 WARNING: [147] [confId=96b19d990531c1ed gid=89263 conf_name=meeting@muc.meet.jitsi] Conference.addEndpointSsrc#1155: SSRC -1 moved from ep e365ad04 to ep b6bdff46
11:02:37.154 WARNING: [147] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:02:37.154 WARNING: [147] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:02:37.155 INFO: [147] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:02:37.278 INFO: [147] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:02:37.279 INFO: [147] [confId=96b19d990531c1ed epId=e365ad04 local_ufrag=a8rob1g1fvan2o gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:02:37.279 INFO: [147] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:02:37.279 INFO: [147] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.initCheckLists#974: Init checklist for stream stream-e365ad04
11:02:37.279 INFO: [147] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.setState#948: ICE state changed from Waiting to Running.
11:02:37.279 INFO: [147] [confId=96b19d990531c1ed epId=e365ad04 local_ufrag=a8rob1g1fvan2o gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:02:37.279 INFO: [147] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:02:37.324 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.triggerCheck#1739: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962083765692399615):
{"log":"\u0009LocalCandidate=candidate:1 1 udp 2130706431 192.168.0.5 10000 typ host
{"log":"\u0009RemoteCandidate=candidate:10000 1 udp 1853817087 192.168.1.46 58841 typ prflx
11:02:37.344 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.processSuccessResponse#648: Pair succeeded: 192.168.0.5:10000/udp/host -\u003e 192.168.1.46:58841/udp/prflx (stream-e365ad04.RTP).
11:02:37.344 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o name=stream-e365ad04 epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 192.168.1.46:58841/udp
11:02:37.344 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.processSuccessResponse#657: Pair validated: 192.168.1.82:10000/udp/srflx -\u003e 192.168.1.46:58841/udp/prflx (stream-e365ad04.RTP).
11:02:37.344 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 192.168.1.82:10000/udp/srflx -\u003e 192.168.1.46:58841/udp/prflx (stream-e365ad04.RTP)
11:02:37.344 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.nominate#1812: verify if nominated pair answer again
11:02:37.345 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.processSuccessResponse#726: IsControlling: true USE-CANDIDATE:false.
11:02:37.347 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.processSuccessResponse#648: Pair succeeded: 192.168.1.82:10000/udp/srflx -\u003e 192.168.1.46:58841/udp/prflx (stream-e365ad04.RTP).
11:02:37.347 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.processSuccessResponse#657: Pair validated: 192.168.1.82:10000/udp/srflx -\u003e 192.168.1.46:58841/udp/prflx (stream-e365ad04.RTP).
11:02:37.347 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.processSuccessResponse#726: IsControlling: true USE-CANDIDATE:true.
11:02:37.347 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] ConnectivityCheckClient.processSuccessResponse#741: Nomination confirmed for pair: 192.168.1.82:10000/udp/srflx -\u003e 192.168.1.46:58841/udp/prflx (stream-e365ad04.RTP).
11:02:37.348 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o name=stream-e365ad04 epId=e365ad04 local_ufrag=a8rob1g1fvan2o] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-e365ad04.RTP: 192.168.1.82:10000/udp/srflx -\u003e 192.168.1.46:58841/udp/prflx (stream-e365ad04.RTP)
11:02:37.348 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.checkListStatesUpdated#1903: CheckList of stream stream-e365ad04 is COMPLETED
11:02:37.348 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.setState#948: ICE state changed from Running to Completed.
11:02:37.349 INFO: [156] [confId=96b19d990531c1ed epId=e365ad04 local_ufrag=a8rob1g1fvan2o gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Running new=Completed
11:02:37.349 INFO: [156] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] Endpoint$setupIceTransport$2.connected#331: ICE connected
11:02:37.349 INFO: [151] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@3280fe86
11:02:37.349 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.logCandTypes#2011: Harvester used for selected pair for stream-e365ad04.RTP: srflx
11:02:37.350 INFO: [151] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] TlsServerImpl.notifyClientVersion#194: Negotiated DTLS version DTLS 1.2
11:02:37.376 INFO: [151] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] Endpoint$setupDtlsTransport$3.handshakeComplete#374: DTLS handshake complete
11:02:37.417 INFO: [151] [confId=96b19d990531c1ed gid=89263 conf_name=meeting@muc.meet.jitsi] Conference.recentSpeakersChanged#554: Recent speakers changed: [e365ad04], dominant speaker changed: true
11:02:37.653 WARNING: [151] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:02:37.653 WARNING: [151] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:02:37.653 INFO: [151] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:02:37.653 INFO: [151] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:02:37.653 INFO: [151] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:02:37.653 INFO: [151] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] Agent.initCheckLists#974: Init checklist for stream stream-b6bdff46
11:02:37.653 INFO: [151] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] Agent.setState#948: ICE state changed from Waiting to Running.
11:02:37.653 INFO: [151] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:02:37.653 INFO: [151] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:02:38.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:02:38.171 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:02:38.171 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:02:38.172 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v name=stream-b6bdff46 epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-b6bdff46.RTP: 10.16.89.212:56050/udp
11:02:38.173 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v name=stream-b6bdff46 epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:56050/udp/host (stream-b6bdff46.RTP).
11:02:38.179 WARNING: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0x6260F55F800115931AE95713]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e10.16.89.212:56050/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:02:38.180 INFO: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:56050/udp/host (stream-b6bdff46.RTP)
11:02:40.349 INFO: [152] [confId=96b19d990531c1ed gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi ufrag=a8rob1g1fvan2o epId=e365ad04 local_ufrag=a8rob1g1fvan2o] Agent.setState#948: ICE state changed from Completed to Terminated.
11:02:40.349 INFO: [152] [confId=96b19d990531c1ed epId=e365ad04 local_ufrag=a8rob1g1fvan2o gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Completed new=Terminated
11:02:48.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:02:54.865 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:02:54.865 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:02:54.865 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:02:54.865 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:02:54.865 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:02:54.866 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:02:54.866 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:02:54.866 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] Agent.setState#948: ICE state changed from Running to Terminated.
11:02:54.866 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=ae2ur1g1fvan2v name=stream-b6bdff46 epId=b6bdff46 local_ufrag=ae2ur1g1fvan2v] MergingDatagramSocket.close#142: Closing.
11:02:54.866 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:02:54.867 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj] Agent.gatherCandidates#649: Gathering candidates for component stream-b6bdff46.RTP.
11:02:54.869 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:02:54.869 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:02:54.870 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:02:55.118 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:02:55.118 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:02:55.118 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:02:55.119 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:02:55.119 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:02:55.119 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] Agent.initCheckLists#974: Init checklist for stream stream-b6bdff46
11:02:55.119 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] Agent.setState#948: ICE state changed from Waiting to Running.
11:02:55.119 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:02:55.119 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:02:55.288 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:02:55.288 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:02:55.289 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj name=stream-b6bdff46 epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-b6bdff46.RTP: 10.16.89.212:57720/udp
11:02:55.289 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj name=stream-b6bdff46 epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:57720/udp/host (stream-b6bdff46.RTP).
11:02:55.303 WARNING: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0x46A3F55F800140BC1A918B7B]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e10.16.89.212:57720/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:02:55.303 INFO: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:57720/udp/host (stream-b6bdff46.RTP)
11:02:58.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:03:08.110 INFO: [24] VideobridgeExpireThread.expire#140: Running expire()
11:03:08.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:03:12.303 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:03:12.304 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:03:12.304 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:03:12.304 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:03:12.304 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:03:12.305 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:03:12.305 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:03:12.305 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] Agent.setState#948: ICE state changed from Running to Terminated.
11:03:12.306 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=ctsb61g1fvb8cj name=stream-b6bdff46 epId=b6bdff46 local_ufrag=ctsb61g1fvb8cj] MergingDatagramSocket.close#142: Closing.
11:03:12.307 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:03:12.309 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=amgj31g1fvbpdk gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk] Agent.gatherCandidates#649: Gathering candidates for component stream-b6bdff46.RTP.
11:03:12.312 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:12.312 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:12.312 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:03:12.844 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:12.845 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:12.845 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:03:12.846 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=amgj31g1fvbpdk gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:03:12.846 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:03:12.846 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] Agent.initCheckLists#974: Init checklist for stream stream-b6bdff46
11:03:12.846 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] Agent.setState#948: ICE state changed from Waiting to Running.
11:03:12.846 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=amgj31g1fvbpdk gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:03:12.846 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:03:12.898 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:12.898 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:12.898 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk name=stream-b6bdff46 epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-b6bdff46.RTP: 10.16.89.212:58452/udp
11:03:12.898 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk name=stream-b6bdff46 epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:58452/udp/host (stream-b6bdff46.RTP).
11:03:12.907 WARNING: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0x0BE8F55F8001B0B3314DC895]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e10.16.89.212:58452/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:03:12.907 INFO: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:58452/udp/host (stream-b6bdff46.RTP)
11:03:14.952 WARNING: [38] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.acceptWebSocket#671: Incoming web socket request with an invalid password. Expected: zmadmwato6q7wz6ea7gb5pmcti received 4cvmxb7rprgeadmqzaspq5y62
11:03:18.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000002S. Sticky failure: false
11:03:22.601 WARNING: [151] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: ad2ddf30
11:03:22.601 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8 gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8] Agent.gatherCandidates#649: Gathering candidates for component stream-ad2ddf30.RTP.
11:03:22.609 WARNING: [159] [confId=96b19d990531c1ed gid=89263 conf_name=meeting@muc.meet.jitsi] Conference.addEndpointSsrc#1155: SSRC -1 moved from ep b6bdff46 to ep ad2ddf30
11:03:22.611 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:22.611 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:22.612 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:03:23.764 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:03:23.764 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:03:23.764 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:03:23.764 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Agent.initCheckLists#974: Init checklist for stream stream-ad2ddf30
11:03:23.764 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Agent.setState#948: ICE state changed from Waiting to Running.
11:03:23.764 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:03:23.764 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:03:23.869 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Agent.triggerCheck#1739: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):
{"log":"\u0009LocalCandidate=candidate:1 1 udp 2130706431 192.168.0.5 10000 typ host
{"log":"\u0009RemoteCandidate=candidate:10000 1 udp 1853824767 147.161.247.112 5951 typ prflx
11:03:24.057 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-ad2ddf30.RTP: 192.168.1.117:58818/udp
11:03:24.057 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:58818/udp/host (stream-ad2ddf30.RTP).
11:03:24.068 WARNING: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0xA313F65F8001373E0B991E12]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e192.168.1.117:58818/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:03:24.068 INFO: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:58818/udp/host (stream-ad2ddf30.RTP)
11:03:28.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:03:29.897 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:03:29.898 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=amgj31g1fvbpdk gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:03:29.898 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] Agent.setState#948: ICE state changed from Running to Terminated.
11:03:29.899 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=amgj31g1fvbpdk name=stream-b6bdff46 epId=b6bdff46 local_ufrag=amgj31g1fvbpdk] MergingDatagramSocket.close#142: Closing.
11:03:29.899 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:03:29.899 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb] Agent.gatherCandidates#649: Gathering candidates for component stream-b6bdff46.RTP.
11:03:29.903 WARNING: [159] [confId=96b19d990531c1ed gid=89263 conf_name=meeting@muc.meet.jitsi] Conference.addEndpointSsrc#1155: SSRC -1 moved from ep ad2ddf30 to ep b6bdff46
11:03:29.904 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:29.904 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:29.904 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:03:30.144 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:30.144 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:30.144 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:03:30.145 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:03:30.145 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:03:30.145 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] Agent.initCheckLists#974: Init checklist for stream stream-b6bdff46
11:03:30.145 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] Agent.setState#948: ICE state changed from Waiting to Running.
11:03:30.145 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:03:30.145 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:03:30.189 INFO: [165] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] ConnectivityCheckClient.processTimeout#880: timeout for pair: 192.168.0.5:10000/udp/host -\u003e 147.161.247.112:5951/udp/prflx (stream-ad2ddf30.RTP), failing.
11:03:30.189 INFO: [165] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] ConnectivityCheckClient.updateCheckListAndTimerStates#460: CheckList will failed in a few seconds if no succeeded checks come
11:03:30.302 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:30.302 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:30.303 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb name=stream-b6bdff46 epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-b6bdff46.RTP: 10.16.89.212:58085/udp
11:03:30.303 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb name=stream-b6bdff46 epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:58085/udp/host (stream-b6bdff46.RTP).
11:03:30.308 WARNING: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0x032CF65F80013BD755E7F692]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e10.16.89.212:58085/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:03:30.308 INFO: [153] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 10.16.89.212:58085/udp/host (stream-b6bdff46.RTP)
11:03:31.363 WARNING: [39] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.acceptWebSocket#671: Incoming web socket request with an invalid password. Expected: q3xvwrn5t3byxx9bq7noz66hwh received zmadmwato6q7wz6ea7gb5pmcti
11:03:32.642 WARNING: [33] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.acceptWebSocket#671: Incoming web socket request with an invalid password. Expected: q3xvwrn5t3byxx9bq7noz66hwh received zmadmwato6q7wz6ea7gb5pmcti
11:03:34.650 WARNING: [33] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.acceptWebSocket#671: Incoming web socket request with an invalid password. Expected: q3xvwrn5t3byxx9bq7noz66hwh received zmadmwato6q7wz6ea7gb5pmcti
11:03:35.190 INFO: [152] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] ConnectivityCheckClient$1.run#470: CheckList for stream stream-ad2ddf30 FAILED
11:03:35.191 INFO: [152] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Agent.checkListStatesUpdated#1923: Suspicious ICE connectivity failure. Checks failed but the remote end was able to reach us.
11:03:35.191 INFO: [152] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Agent.checkListStatesUpdated#1927: ICE state is FAILED
11:03:35.191 INFO: [152] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] Agent.setState#948: ICE state changed from Running to Failed.
11:03:35.192 INFO: [152] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Running new=Failed
11:03:38.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:03:38.645 WARNING: [67] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.acceptWebSocket#671: Incoming web socket request with an invalid password. Expected: q3xvwrn5t3byxx9bq7noz66hwh received zmadmwato6q7wz6ea7gb5pmcti
11:03:41.079 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:03:41.080 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:03:41.080 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:03:41.080 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:03:41.080 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:03:41.081 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:03:41.081 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:03:41.084 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] MergingDatagramSocket.close#142: Closing.
11:03:41.085 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:03:41.085 INFO: [164] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7a3gd1g1fvc3f8 name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=7a3gd1g1fvc3f8] MergingDatagramSocket$SocketContainer.runInReaderThread#770: Failed to receive: java.net.SocketException: Socket closed
11:03:41.088 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv] Agent.gatherCandidates#649: Gathering candidates for component stream-ad2ddf30.RTP.
11:03:41.097 WARNING: [159] [confId=96b19d990531c1ed gid=89263 conf_name=meeting@muc.meet.jitsi] Conference.addEndpointSsrc#1155: SSRC -1 moved from ep b6bdff46 to ep ad2ddf30
11:03:41.099 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:41.099 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:41.100 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:03:41.478 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:03:41.479 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:03:41.479 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:03:41.479 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] Agent.initCheckLists#974: Init checklist for stream stream-ad2ddf30
11:03:41.479 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] Agent.setState#948: ICE state changed from Waiting to Running.
11:03:41.479 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:03:41.479 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:03:41.631 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-ad2ddf30.RTP: 192.168.1.117:55001/udp
11:03:41.631 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:55001/udp/host (stream-ad2ddf30.RTP).
11:03:41.641 WARNING: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0x4958F65F8001F24EA6E8760C]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e192.168.1.117:55001/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:03:41.641 INFO: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:55001/udp/host (stream-ad2ddf30.RTP)
11:03:46.675 WARNING: [67] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.acceptWebSocket#671: Incoming web socket request with an invalid password. Expected: q3xvwrn5t3byxx9bq7noz66hwh received zmadmwato6q7wz6ea7gb5pmcti
11:03:47.352 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:03:47.352 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:03:47.352 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:03:47.352 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:03:47.353 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:03:47.354 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:03:47.354 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:03:47.354 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] Agent.setState#948: ICE state changed from Running to Terminated.
11:03:47.355 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Amos-CQK componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7ptfn1g1fvcajb name=stream-b6bdff46 epId=b6bdff46 local_ufrag=7ptfn1g1fvcajb] MergingDatagramSocket.close#142: Closing.
11:03:47.355 INFO: [159] [confId=96b19d990531c1ed epId=b6bdff46 gid=89263 stats_id=Amos-CQK conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:03:48.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:03:48.628 WARNING: [38] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:03:50.816 WARNING: [37] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:03:52.726 WARNING: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:03:55.453 WARNING: [67] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:03:57.218 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:03:58.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:03:58.668 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:03:58.669 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:03:58.669 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:03:58.669 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:03:58.669 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:03:58.670 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:03:58.670 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:03:58.670 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] Agent.setState#948: ICE state changed from Running to Terminated.
11:03:58.670 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=7a12h1g1fvclgv name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=7a12h1g1fvclgv] MergingDatagramSocket.close#142: Closing.
11:03:58.670 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:03:58.670 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=5demf1g1fvd6me gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me] Agent.gatherCandidates#649: Gathering candidates for component stream-ad2ddf30.RTP.
11:03:58.672 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:03:58.672 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:03:58.672 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:03:59.250 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:03:59.250 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=5demf1g1fvd6me gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:03:59.250 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:03:59.250 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] Agent.initCheckLists#974: Init checklist for stream stream-ad2ddf30
11:03:59.250 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] Agent.setState#948: ICE state changed from Waiting to Running.
11:03:59.250 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=5demf1g1fvd6me gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:03:59.250 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:03:59.422 WARNING: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:03:59.434 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-ad2ddf30.RTP: 192.168.1.117:64828/udp
11:03:59.435 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:64828/udp/host (stream-ad2ddf30.RTP).
11:03:59.454 WARNING: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0xDD9DF65F800135A05B66F0B7]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e192.168.1.117:64828/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:03:59.454 INFO: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:64828/udp/host (stream-ad2ddf30.RTP)
11:04:02.623 WARNING: [33] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:04:02.743 WARNING: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:02.822 WARNING: [37] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:04:07.228 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:08.109 INFO: [24] VideobridgeExpireThread.expire#140: Running expire()
11:04:08.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:04:12.735 WARNING: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:16.399 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:04:16.400 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:04:16.400 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:04:16.400 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:04:16.400 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:04:16.400 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:04:16.400 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=5demf1g1fvd6me gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:04:16.400 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] Agent.setState#948: ICE state changed from Running to Terminated.
11:04:16.401 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=5demf1g1fvd6me name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=5demf1g1fvd6me] MergingDatagramSocket.close#142: Closing.
11:04:16.401 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:04:16.401 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h] Agent.gatherCandidates#649: Gathering candidates for component stream-ad2ddf30.RTP.
11:04:16.406 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:04:16.406 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:04:16.406 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:04:16.664 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:04:16.664 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:04:16.665 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:04:16.665 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] Agent.initCheckLists#974: Init checklist for stream stream-ad2ddf30
11:04:16.665 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] Agent.setState#948: ICE state changed from Waiting to Running.
11:04:16.665 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:04:16.665 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:04:16.804 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-ad2ddf30.RTP: 192.168.1.117:49490/udp
11:04:16.804 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] Component.updateRemoteCandidates#484: new Pair added: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:49490/udp/host (stream-ad2ddf30.RTP).
11:04:16.807 WARNING: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] ConnectivityCheckClient.startCheckForPair#374: Failed to send BINDING-REQUEST(0x1)[attrib.count=6 len=92 tranID=0xA7E1F65F8001B4FED7DAA5D9]
{"log":"java.lang.IllegalArgumentException: No socket found for 192.168.0.5:10000/udp-\u003e192.168.1.117:49490/udp
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:631)
{"log":"\u0009at org.ice4j.stack.NetAccessManager.sendMessage(NetAccessManager.java:581)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest0(StunClientTransaction.java:267)
{"log":"\u0009at org.ice4j.stack.StunClientTransaction.sendRequest(StunClientTransaction.java:245)
{"log":"\u0009at org.ice4j.stack.StunStack.sendRequest(StunStack.java:680)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:335)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient.startCheckForPair(ConnectivityCheckClient.java:231)
{"log":"\u0009at org.ice4j.ice.ConnectivityCheckClient$PaceMaker.run(ConnectivityCheckClient.java:938)
{"log":"\u0009at org.ice4j.util.PeriodicRunnable.executeRun(PeriodicRunnable.java:206)
{"log":"\u0009at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
{"log":"\u0009at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
{"log":"\u0009at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
{"log":"\u0009at java.base/java.lang.Thread.run(Thread.java:829)
11:04:16.807 INFO: [160] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] ConnectivityCheckClient$PaceMaker.run#942: Pair failed: 192.168.0.5:10000/udp/host -\u003e 192.168.1.117:49490/udp/host (stream-ad2ddf30.RTP)
11:04:16.959 WARNING: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:17.235 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:18.129 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:04:18.701 WARNING: [33] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:04:22.719 WARNING: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:27.248 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:28.129 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:04:32.721 WARNING: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:33.851 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] AbstractEndpoint.expire#301: Expiring.
11:04:33.851 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1016: Spent 0 seconds oversending
11:04:33.851 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Transceiver.teardown#348: Tearing down
11:04:33.852 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpReceiverImpl.tearDown#344: Tearing down
11:04:33.852 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] RtpSenderImpl.tearDown#314: Tearing down
11:04:33.853 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] DtlsTransport.stop#186: Stopping
11:04:33.853 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] IceTransport.stop#250: Stopping
11:04:33.853 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] Agent.setState#948: ICE state changed from Running to Terminated.
11:04:33.855 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Julien-GfM componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=2pgnf1g1fvdo0h name=stream-ad2ddf30 epId=ad2ddf30 local_ufrag=2pgnf1g1fvdo0h] MergingDatagramSocket.close#142: Closing.
11:04:33.855 INFO: [159] [confId=96b19d990531c1ed epId=ad2ddf30 gid=89263 stats_id=Julien-GfM conf_name=meeting@muc.meet.jitsi] Endpoint.expire#1034: Expired.
11:04:34.706 WARNING: [67] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:04:35.093 WARNING: [38] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: ad2ddf30 (conference 96b19d990531c1ed)
11:04:37.013 WARNING: [39] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: ad2ddf30 (conference 96b19d990531c1ed)
11:04:37.257 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:38.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:04:40.988 WARNING: [37] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: ad2ddf30 (conference 96b19d990531c1ed)
11:04:42.665 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: ad2ddf30
11:04:47.260 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:48.128 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:04:49.000 WARNING: [67] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: ad2ddf30 (conference 96b19d990531c1ed)
11:04:50.675 WARNING: [38] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: b6bdff46 (conference 96b19d990531c1ed)
11:04:52.669 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: ad2ddf30
11:04:57.274 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:04:58.129 INFO: [25] HealthChecker.run#171: Performed a successful health check in PT0.000001S. Sticky failure: false
11:05:02.678 WARNING: [159] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: ad2ddf30
11:05:05.013 WARNING: [33] ColibriWebSocketServlet.createWebSocket#185: Received request for a nonexistent endpoint: ad2ddf30 (conference 96b19d990531c1ed)
11:05:06.265 INFO: [159] [confId=96b19d990531c1ed epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp gid=89263 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp] Agent.gatherCandidates#649: Gathering candidates for component stream-dbdd6580.RTP.
11:05:06.267 WARNING: [159] [confId=96b19d990531c1ed gid=89263 type=video conf_name=meeting@muc.meet.jitsi] ChannelShim.lambda$setSourceGroups$2#318: Ignoring source group with \u003c2 sources: \u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e
11:05:06.267 WARNING: [159] MediaSourceFactory.getSourceSsrcs#405: Unprocessed source groups: Optional[\u003cssrc-group xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' semantics='SIM'/\u003e]
11:05:06.267 INFO: [159] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 conf_name=meeting@muc.meet.jitsi] Endpoint.setTransportInfo#728: Ignoring empty DtlsFingerprint extension: \u003ctransport xmlns='urn:xmpp:jingle:transports:ice-udp:1'\u003e\u003cfingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/\u003e\u003c/transport\u003e
11:05:06.752 INFO: [159] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
11:05:06.752 INFO: [159] [confId=96b19d990531c1ed epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] IceTransport.startConnectivityEstablishment#197: Starting the Agent without remote candidates.
11:05:06.753 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.startConnectivityEstablishment#738: Start ICE connectivity establishment.
11:05:06.753 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.initCheckLists#974: Init checklist for stream stream-dbdd6580
11:05:06.753 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.setState#948: ICE state changed from Waiting to Running.
11:05:06.753 INFO: [159] [confId=96b19d990531c1ed epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Waiting new=Running
11:05:06.753 INFO: [159] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.startChecks#149: Start connectivity checks.
11:05:06.854 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.triggerCheck#1739: Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7926369428998979583):
{"log":"\u0009LocalCandidate=candidate:1 1 udp 2130706431 192.168.0.5 10000 typ host
{"log":"\u0009RemoteCandidate=candidate:10000 1 udp 1845501695 178.197.193.176 63372 typ prflx
11:05:06.895 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.processSuccessResponse#648: Pair succeeded: 192.168.0.5:10000/udp/host -\u003e 178.197.193.176:63372/udp/prflx (stream-dbdd6580.RTP).
11:05:06.895 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 componentId=1 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp name=stream-dbdd6580 epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ComponentSocket.addAuthorizedAddress#99: Adding allowed address: 178.197.193.176:63372/udp
11:05:06.895 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.processSuccessResponse#657: Pair validated: 79.249.242.184:10000/udp/srflx -\u003e 178.197.193.176:63372/udp/prflx (stream-dbdd6580.RTP).
11:05:06.895 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] DefaultNominator.strategyNominateFirstHostOrReflexiveValid#268: Nominate (first highest valid): 79.249.242.184:10000/udp/srflx -\u003e 178.197.193.176:63372/udp/prflx (stream-dbdd6580.RTP)
11:05:06.895 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.nominate#1812: verify if nominated pair answer again
11:05:06.896 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.processSuccessResponse#726: IsControlling: true USE-CANDIDATE:false.
11:05:06.938 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.processSuccessResponse#648: Pair succeeded: 79.249.242.184:10000/udp/srflx -\u003e 178.197.193.176:63372/udp/prflx (stream-dbdd6580.RTP).
11:05:06.938 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.processSuccessResponse#657: Pair validated: 79.249.242.184:10000/udp/srflx -\u003e 178.197.193.176:63372/udp/prflx (stream-dbdd6580.RTP).
11:05:06.938 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.processSuccessResponse#726: IsControlling: true USE-CANDIDATE:true.
11:05:06.939 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] ConnectivityCheckClient.processSuccessResponse#741: Nomination confirmed for pair: 79.249.242.184:10000/udp/srflx -\u003e 178.197.193.176:63372/udp/prflx (stream-dbdd6580.RTP).
11:05:06.939 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp name=stream-dbdd6580 epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] CheckList.handleNominationConfirmed#406: Selected pair for stream stream-dbdd6580.RTP: 79.249.242.184:10000/udp/srflx -\u003e 178.197.193.176:63372/udp/prflx (stream-dbdd6580.RTP)
11:05:06.939 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.checkListStatesUpdated#1903: CheckList of stream stream-dbdd6580 is COMPLETED
11:05:06.939 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.setState#948: ICE state changed from Running to Completed.
11:05:06.940 INFO: [156] [confId=96b19d990531c1ed epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] IceTransport.iceStateChanged#340: ICE state changed old=Running new=Completed
11:05:06.940 INFO: [156] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] Endpoint$setupIceTransport$2.connected#331: ICE connected
11:05:06.940 INFO: [162] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] DtlsTransport.startDtlsHandshake#102: Starting DTLS handshake, role=org.jitsi.nlj.dtls.DtlsServer@3697be8
11:05:06.940 INFO: [156] [confId=96b19d990531c1ed gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi ufrag=ijbd1g1fvf8mp epId=dbdd6580 local_ufrag=ijbd1g1fvf8mp] Agent.logCandTypes#2011: Harvester used for selected pair for stream-dbdd6580.RTP: srflx
11:05:06.941 INFO: [162] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] TlsServerImpl.notifyClientVersion#194: Negotiated DTLS version DTLS 1.2
11:05:06.955 WARNING: [166] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:05:06.955 WARNING: [166] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: ad2ddf30
11:05:06.994 INFO: [162] [confId=96b19d990531c1ed epId=dbdd6580 gid=89263 stats_id=Icie-Jh3 conf_name=meeting@muc.meet.jitsi] Endpoint$setupDtlsTransport$3.handshakeComplete#374: DTLS handshake complete
11:05:07.281 WARNING: [162] [confId=96b19d990531c1ed epId=e365ad04 gid=89263 stats_id=Selina-CRR conf_name=meeting@muc.meet.jitsi] EndpointMessageTransport.endpointMessage#598: Unable to find endpoint to send EndpointMessage to: b6bdff46
11:05:08.109 INFO: [24] VideobridgeExpireThread.expire#140: Running expire()

ICE never completed in the first place, which is consistent with the client behavior. That’s likely what triggered the other problems.

I still don’t know what caused the endpoint to expire on the bridge, but it may be limited to colibri1. The current stable version of jicofo only supports colibri2, and we can’t allocate time to tracing issues that only affect colibri2. Consider updating the system, and please let us know if something similar happens again.

Boris

1 Like

Sorry, I’ve been away for the past week. Thanks for your answer!

I had a look through the handbook and github, but only found pull requests and a non-related issue mentioning colibri2 or what I would need to update (the host is currently an uptodate Debian 11). I only found this as a wrap-up and this configuration option mentioned in another thread:

jicofo {
  colibri {
    // Whether to use the new version of COLIBRI
    enable-colibri2 = true
  }
}

I’m currently only using env-vars and didn’t find an env-var for enabling colibri2, but I already had a look at using the /config/custom-config.js file. Should that option be added there or am I on the completely wrong track?

Just update to the latest stable version of jicofo, it only supports colibri2 and doesn’t require any configuration changes unless you’re also using octo.

1 Like

Oh, I had a look at the releases after my version as well but didn’t see any mention of colibri2 and so I thought I’d need a configuration change, but now I’ve also found the merged pr.

I’ve updated my instances and they’re now using colibri2. I’ll monitor if the same issue arises again and report back if it does.

Thanks again for all your help, Boris!

1 Like