Can't establish audio or video

ChicagoJay commented 3 years ago

Hello, once again.

I have created a video room on a Windows PC, with Chrome. I am able to join the room with an iPad, running Safari. However, no audio or video are transmitted. The slashed mic and video icons are present in the self screen, even though I have selected to allow video and audio. BEFORE the connection was established, audio and video were working.

Also, eventually, the iPad puts up a red message, saying "Unable to access camera. Please check if another application is using this device, select another device from the settings menu or try to reload the application." Options are to Dismiss or Contact support. The latter option brings up a new tab, to about:blank

2020.11.17 15:50:39 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - audio remote IP/port: 10.72.16.68/64230
2020.11.17 15:50:39 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=2d64f4b0fd2f9d06,content=video,ch_id=930b725c69ad09dd,endp_id=7b116a5d,stream=845704130
2020.11.17 15:50:39 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - video codec/freq: null/null Hz
2020.11.17 15:50:39 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - video remote IP/port: 10.72.16.68/64230
2020.11.17 15:50:39 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=2d64f4b0fd2f9d06,content=data,ch_id=889bcd963ac873e1,endp_id=7b116a5d
2020.11.17 15:50:39 ERROR [org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.connectThread]: org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer - Failed to accept a connection from a DTLS client!
java.io.IOException: org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl is closed!
at org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.assertNotClosed(DatagramTransportImpl.java:124) ~[libjitsi-1.0-20190724.114721-390.jar:?]
at org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.send(DatagramTransportImpl.java:477) ~[libjitsi-1.0-20190724.114721-390.jar:?]
at org.bouncycastle.crypto.tls.DTLSRecordLayer.sendRecord(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSRecordLayer.send(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake$RecordLayerBuffer.sendToRecordLayer(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.writeHandshakeFragment(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.writeMessage(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.resendOutboundFlight(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.receiveMessage(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSServerProtocol.serverHandshake(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSServerProtocol.accept(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.runInConnectThread(DtlsPacketTransformer.java:1043) [libjitsi-1.0-20190724.114721-390.jar:?]
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.access$000(DtlsPacketTransformer.java:40) [libjitsi-1.0-20190724.114721-390.jar:?]
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer$1.run(DtlsPacketTransformer.java:1272) [libjitsi-1.0-20190724.114721-390.jar:?]
2020.11.17 15:50:39 INFO [org.jitsi.impl.neomedia.rtp.translator.OutputDataStreamImpl]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1169163249 remainingRetries=9
2020.11.17 15:50:39 INFO [org.jitsi.impl.neomedia.RTPConnectorInputStream.receiveThread]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=9
2020.11.17 15:50:39 WARN [org.jitsi.impl.neomedia.RTPConnectorInputStream.receiveThread]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:39 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=8
2020.11.17 15:50:39 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:40 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=7
2020.11.17 15:50:40 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:40 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=6
2020.11.17 15:50:40 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:40 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=5
2020.11.17 15:50:40 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:40 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=4
2020.11.17 15:50:40 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:41 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=3
2020.11.17 15:50:41 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:41 INFO [ice4j.Agent-timer-pool-13-thread-2]: org.ice4j.ice.Agent - ICE state changed from Completed to Terminated. Local ufrag c5iv41enc30bng
2020.11.17 15:50:41 INFO [ice4j.Agent-timer-pool-13-thread-2]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=2d64f4b0fd2f9d06,content=data,ch_id=5594655c41a63790,endp_id=e9728ee9 old_state=Completed,new_state=Terminated
2020.11.17 15:50:41 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=2
2020.11.17 15:50:41 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:41 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=1
2020.11.17 15:50:41 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:42 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=2500364118 remainingRetries=0
2020.11.17 15:50:42 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:50:42 INFO [ice4j.Agent-timer-pool-13-thread-2]: org.ice4j.ice.Agent - ICE state changed from Completed to Terminated. Local ufrag 5v8c11enc30bnm
2020.11.17 15:50:42 INFO [ice4j.Agent-timer-pool-13-thread-2]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=2d64f4b0fd2f9d06,content=data,ch_id=889bcd963ac873e1,endp_id=7b116a5d old_state=Completed,new_state=Terminated
2020.11.17 15:50:42 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 7b116a5d not ready yet.
2020.11.17 15:50:42 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:50:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=a36471d3cf647ba7 conf_name=null,logging=false,conf_count=4,ch_count=6,v_streams=4
2020.11.17 15:50:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 9ms. Sticky failure: false

Thanks again for all your help!

ChicagoJay commented 3 years ago

I tried connecting again, and took fresh logs. Both devices are in the meet, and the mute and video off indicators are gone. But only the PC can see it's own camera, and there is no sound.


2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Member techtest@conference.d83.org/7b116a5d is leaving
2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Terminating Participant[techtest@conference.d83.org/7b116a5d]@1515079994, reason: gone, send st: false
2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.protocol.xmpp.AbstractOperationSetJingle - Terminate session: techtest@conference.d83.org/7b116a5d, reason: gone, send terminate: false
2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Removing techtest@conference.d83.org/7b116a5d sources Sources{ video: [ssrc=2500364118 ssrc=3040568869 ssrc=1792564902 ssrc=722639441 ssrc=2375572382 ssrc=1516992297 ] audio: [ssrc=4192614491 ] }@1990612539
2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.protocol.xmpp.AbstractOperationSetJingle - Notify remove SSRC techtest@conference.d83.org/e9728ee9 SID: 9o98harj6dqa3 Sources{ video: [ssrc=2500364118 ssrc=3040568869 ssrc=1792564902 ssrc=722639441 ssrc=2375572382 ssrc=1516992297 ] audio: [ssrc=4192614491 ] }@1990612539 source_Groups{ video:[ SourceGroup(FID)[ ssrc=2500364118 ssrc=3040568869 ]SourceGroup(SIM)[ ssrc=2500364118 ssrc=1792564902 ssrc=722639441 ]SourceGroup(FID)[ ssrc=1792564902 ssrc=2375572382 ]SourceGroup(FID)[ ssrc=722639441 ssrc=1516992297 ] ] }@1655302192
2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Region info, conference=16317 octo_enabled= false: [[null, null]]
2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Expiring channels for: techtest@conference.d83.org/7b116a5d on: Bridge[jid=jitsi-videobridge.d83.org, relayId=null, region=null, stress=0.00]
2020.11.17 15:55:40 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Removed participant: true, techtest@conference.d83.org/7b116a5d
2020.11.17 15:55:40 INFO [pool-8-thread-16]: org.jitsi.videobridge.Channel - CAT=stat expire_ch,conf_id=2d64f4b0fd2f9d06,content=audio,ch_id=4d8ab0afcdd3e6e1,endp_id=7b116a5d,stream=1166838549
2020.11.17 15:55:40 INFO [pool-8-thread-16]: org.jitsi.videobridge.RtpChannel - CAT=stat expire_ch_stats,conf_id=2d64f4b0fd2f9d06,content=audio,ch_id=4d8ab0afcdd3e6e1,endp_id=7b116a5d,stream=1166838549 bRecv=0,bSent=1462861,pRecv=0,pSent=15304,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0
2020.11.17 15:55:40 INFO [pool-8-thread-16]: org.jitsi.impl.neomedia.rtp.RawPacketCache - CAT=stat closed,stream=845704130 max_size_bytes=391150,max_size_packets=337,total_hits=0,total_misses=0,total_packets=50734,oldest_hit_ms=0
2020.11.17 15:55:40 INFO [pool-8-thread-16]: org.jitsi.videobridge.Channel - CAT=stat expire_ch,conf_id=2d64f4b0fd2f9d06,content=video,ch_id=930b725c69ad09dd,endp_id=7b116a5d,stream=845704130
2020.11.17 15:55:40 INFO [pool-8-thread-16]: org.jitsi.videobridge.RtpChannel - CAT=stat expire_ch_stats,conf_id=2d64f4b0fd2f9d06,content=video,ch_id=930b725c69ad09dd,endp_id=7b116a5d,stream=845704130 bRecv=0,bSent=58375215,pRecv=0,pSent=51095,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0
2020.11.17 15:55:40 INFO [MergingDatagramSocket reader thread for: 10.83.1.112:10000/udp -> null]: org.ice4j.socket.MergingDatagramSocket - Failed to receive: java.net.SocketException: Socket closed
2020.11.17 15:55:40 WARN [MergingDatagramSocket reader thread for: 10.83.1.112:10000/udp -> null]: org.ice4j.socket.MergingDatagramSocket - Removing the active socket. Won't be able to send until a new one is elected.
2020.11.17 15:55:40 INFO [pool-8-thread-16]: org.ice4j.socket.MergingDatagramSocket - Closing.
2020.11.17 15:55:40 WARN [pool-8-thread-16]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 7b116a5d not ready yet.
2020.11.17 15:55:40 WARN [pool-8-thread-16]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:55:40 INFO [pool-8-thread-16]: org.jitsi.videobridge.Channel - CAT=stat expire_ch,conf_id=2d64f4b0fd2f9d06,content=data,ch_id=889bcd963ac873e1,endp_id=7b116a5d
2020.11.17 15:55:40 ERROR [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.SctpConnection - Error accepting SCTP connection conf_id=2d64f4b0fd2f9d06,content=data,ch_id=889bcd963ac873e1,endp_id=7b116a5d
java.util.concurrent.RejectedExecutionException: shutdown
at org.jitsi.impl.osgi.framework.AsyncExecutor.submit(AsyncExecutor.java:205) ~[jitsi-android-osgi-1.0-20190327.160432-3.jar:?]
at org.jitsi.impl.osgi.framework.AsyncExecutor.execute(AsyncExecutor.java:64) ~[jitsi-android-osgi-1.0-20190327.160432-3.jar:?]
at org.jitsi.videobridge.SctpConnection.acceptIncomingSctpConnection(SctpConnection.java:1255) ~[jitsi-videobridge-1.1-20200124.173127-125.jar:?]
at org.jitsi.impl.osgi.framework.AsyncExecutor.runInThread(AsyncExecutor.java:122) [jitsi-android-osgi-1.0-20190327.160432-3.jar:?]
at org.jitsi.impl.osgi.framework.AsyncExecutor.access$000(AsyncExecutor.java:28) [jitsi-android-osgi-1.0-20190327.160432-3.jar:?]
at org.jitsi.impl.osgi.framework.AsyncExecutor$1.run(AsyncExecutor.java:231) [jitsi-android-osgi-1.0-20190327.160432-3.jar:?]
2020.11.17 15:55:40 INFO [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.SctpConnection - SctpConnection conf_id=2d64f4b0fd2f9d06,content=data,ch_id=889bcd963ac873e1,endp_id=7b116a5d closed before SctpSocket accept()-ed.
2020.11.17 15:55:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=1f2e7523deca6c8c conf_name=null,logging=false,conf_count=2,ch_count=3,v_streams=1
2020.11.17 15:55:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 9ms. Sticky failure: false
2020.11.17 15:55:54 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-VideobridgeExpireThread]: org.jitsi.videobridge.VideobridgeExpireThread - Running expire()
2020.11.17 15:55:57 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=e08b320f8283812e conf_name=null,logging=false,conf_count=2,ch_count=3,v_streams=1
2020.11.17 15:55:57 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 8ms. Sticky failure: false
2020.11.17 15:56:00 INFO [Jicofo Scheduledpool-41-thread-68]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Timing out single participant: techtest@conference.d83.org/e9728ee9
2020.11.17 15:56:00 INFO [Jicofo Scheduledpool-41-thread-68]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Terminating Participant[techtest@conference.d83.org/e9728ee9]@1929833813, reason: expired, send st: true
2020.11.17 15:56:00 INFO [Jicofo Scheduledpool-41-thread-68]: org.jitsi.protocol.xmpp.AbstractOperationSetJingle - Terminate session: techtest@conference.d83.org/e9728ee9, reason: expired, send terminate: true
2020.11.17 15:56:00 INFO [Jicofo Scheduledpool-41-thread-68]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Removing techtest@conference.d83.org/e9728ee9 sources Sources{ video: [ssrc=1169163249 ssrc=3225085609 ssrc=4131125431 ssrc=2407493413 ssrc=3965528874 ssrc=3447641948 ] audio: [ssrc=2270028350 ] }@50757058
2020.11.17 15:56:00 INFO [Jicofo Scheduledpool-41-thread-68]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Region info, conference=16317 octo_enabled= false: [[null]]
2020.11.17 15:56:00 INFO [Jicofo Scheduledpool-41-thread-68]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Expiring channels for: techtest@conference.d83.org/e9728ee9 on: Bridge[jid=jitsi-videobridge.d83.org, relayId=null, region=null, stress=0.00]
2020.11.17 15:56:00 INFO [pool-8-thread-4]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:00 INFO [Jicofo Scheduledpool-41-thread-68]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Removed participant: true, techtest@conference.d83.org/e9728ee9
2020.11.17 15:56:00 WARN [org.jitsi.videobridge.SctpConnection-pool-18-thread-2]: org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer - Dropping a DTLS packet. This DtlsPacketTransformer has not been started successfully or has been closed.
2020.11.17 15:56:00 INFO [pool-8-thread-4]: org.jitsi.videobridge.Channel - CAT=stat expire_ch,conf_id=2d64f4b0fd2f9d06,content=audio,ch_id=714a6ef19b21f1fd,endp_id=e9728ee9,stream=1585802350
2020.11.17 15:56:00 INFO [pool-8-thread-4]: org.jitsi.videobridge.RtpChannel - CAT=stat expire_ch_stats,conf_id=2d64f4b0fd2f9d06,content=audio,ch_id=714a6ef19b21f1fd,endp_id=e9728ee9,stream=1585802350 bRecv=1557404,bSent=4560,pRecv=16295,pSent=60,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0
2020.11.17 15:56:00 INFO [pool-8-thread-4]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:00 INFO [pool-8-thread-4]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:00 INFO [pool-8-thread-4]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:00 INFO [pool-8-thread-4]: org.jitsi.impl.neomedia.rtp.RawPacketCache - CAT=stat closed,stream=-1 max_size_bytes=736659,max_size_packets=653,total_hits=0,total_misses=0,total_packets=124688,oldest_hit_ms=0
2020.11.17 15:56:00 INFO [pool-8-thread-4]: org.jitsi.videobridge.Channel - CAT=stat expire_ch,conf_id=2d64f4b0fd2f9d06,content=video,ch_id=447e23c37cd82834,endp_id=e9728ee9,stream=403066341
2020.11.17 15:56:00 INFO [pool-8-thread-4]: org.jitsi.videobridge.RtpChannel - CAT=stat expire_ch_stats,conf_id=2d64f4b0fd2f9d06,content=video,ch_id=447e23c37cd82834,endp_id=e9728ee9,stream=403066341 bRecv=129129414,bSent=54356,pRecv=128389,pSent=708,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0
2020.11.17 15:56:00 INFO [MergingDatagramSocket reader thread for: 10.83.1.112:10000/udp -> null]: org.ice4j.socket.MergingDatagramSocket - Failed to receive: java.net.SocketException: Socket closed
2020.11.17 15:56:00 WARN [MergingDatagramSocket reader thread for: 10.83.1.112:10000/udp -> null]: org.ice4j.socket.MergingDatagramSocket - Removing the active socket. Won't be able to send until a new one is elected.
2020.11.17 15:56:00 INFO [pool-8-thread-4]: org.ice4j.socket.MergingDatagramSocket - Closing.
2020.11.17 15:56:00 INFO [pool-8-thread-4]: org.jitsi.videobridge.Channel - CAT=stat expire_ch,conf_id=2d64f4b0fd2f9d06,content=data,ch_id=5594655c41a63790,endp_id=e9728ee9
2020.11.17 15:56:07 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=68acee8b2aefc920 conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:56:07 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 9ms. Sticky failure: false
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 1
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 2
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 1
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 4
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2020.11.17 15:56:13 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 1
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 2
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 1
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 4
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 1
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 2
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 2
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 2
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 4
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled
2020.11.17 15:56:15 INFO [SSRC Cache Cleaner]: net.sf.fmj.media.Log - Stopping RTPSourceStream.
2020.11.17 15:56:17 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=93ea8c7bdcd32a1f conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:56:17 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 16ms. Sticky failure: false
2020.11.17 15:56:27 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=4d7021d0976ce7c6 conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:56:27 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 15ms. Sticky failure: false
2020.11.17 15:56:37 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=8d3a7881f1d989cc conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:56:37 ERROR [MergingDatagramSocket reader thread for: /10.83.1.112:11095 -> null]: org.ice4j.socket.MergingDatagramSocket - Cannot find socket to remove.
2020.11.17 15:56:37 ERROR [MergingDatagramSocket reader thread for: /10.83.1.112:11092 -> null]: org.ice4j.socket.MergingDatagramSocket - Cannot find socket to remove.
2020.11.17 15:56:37 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 11ms. Sticky failure: false
2020.11.17 15:56:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=241594c56a5beba5 conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:56:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 9ms. Sticky failure: false
2020.11.17 15:56:54 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-VideobridgeExpireThread]: org.jitsi.videobridge.VideobridgeExpireThread - Running expire()
2020.11.17 15:56:57 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=48b47d9531ed0145 conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:56:57 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 18ms. Sticky failure: false
2020.11.17 15:57:06 INFO [pool-46-thread-9]: org.jitsi.jicofo.xmpp.FocusComponent - Focus request for room: techtest@conference.d83.org
2020.11.17 15:57:06 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.ChatRoomRoleAndPresence - Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberJoined sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@84e509a member=ChatMember[techtest@conference.d83.org/125f3ea3, jid: null]@88447568]
2020.11.17 15:57:06 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Member techtest@conference.d83.org/125f3ea3 joined.
2020.11.17 15:57:06 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.impl.protocol.xmpp.colibri.OperationSetColibriConferenceImpl - Conference created: org.jitsi.impl.protocol.xmpp.colibri.ColibriConferenceImpl@4116134d
2020.11.17 15:57:06 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Added participant jid= techtest@conference.d83.org/e9728ee9, bridge=jitsi-videobridge.d83.org
2020.11.17 15:57:06 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Region info, conference=16317 octo_enabled= false: [[null, null]]
2020.11.17 15:57:06 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Added participant jid= techtest@conference.d83.org/125f3ea3, bridge=jitsi-videobridge.d83.org
2020.11.17 15:57:06 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Region info, conference=16317 octo_enabled= false: [[null, null, null]]
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-6]: org.jitsi.jicofo.discovery.DiscoveryUtil - Doing feature discovery for techtest@conference.d83.org/e9728ee9
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-6]: org.jitsi.jicofo.discovery.DiscoveryUtil - Successfully discovered features for techtest@conference.d83.org/e9728ee9 in 0
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-6]: org.jitsi.jicofo.AbstractChannelAllocator - Using jitsi-videobridge.d83.org to allocate channels for: Participant[techtest@conference.d83.org/e9728ee9]@900567064
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-7]: org.jitsi.jicofo.discovery.DiscoveryUtil - Doing feature discovery for techtest@conference.d83.org/125f3ea3
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-7]: org.jitsi.jicofo.discovery.DiscoveryUtil - Successfully discovered features for techtest@conference.d83.org/125f3ea3 in 1
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-7]: org.jitsi.jicofo.AbstractChannelAllocator - Using jitsi-videobridge.d83.org to allocate channels for: Participant[techtest@conference.d83.org/125f3ea3]@771986204
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=d13ac35e782718d9 conf_name=techtest,logging=true,conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.Conference - CAT=stat create_content,conf_id=d13ac35e782718d9,content=audio conf_count=2,ch_count=0,v_streams=0
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.ice4j.ice.Agent - Gathering candidates for component stream.RTP. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.Conference - CAT=stat create_ice_tm,conf_id=d13ac35e782718d9 ufrag=c4ulr1enc3c7at,bundle=e9728ee9,initiator=true
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.RtpChannel - CAT=stat create_stream,conf_id=d13ac35e782718d9,content=audio,ch_id=d9f043396a421df,endp_id=null,stream=2085913716
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.Content - CAT=stat create_channel,conf_id=d13ac35e782718d9,content=audio,ch_id=d9f043396a421df,endp_id=null,stream=2085913716 transport=default,bundle=e9728ee9,initiator=true,media_type=audio,relay_type=null
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.Conference - CAT=stat create_content,conf_id=d13ac35e782718d9,content=video conf_count=2,ch_count=1,v_streams=0
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.RtpChannel - CAT=stat create_stream,conf_id=d13ac35e782718d9,content=video,ch_id=6d953b26c9f7cb33,endp_id=null,stream=2019461860
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.Content - CAT=stat create_channel,conf_id=d13ac35e782718d9,content=video,ch_id=6d953b26c9f7cb33,endp_id=null,stream=2019461860 transport=default,bundle=e9728ee9,initiator=true,media_type=video,relay_type=null
2020.11.17 15:57:06 INFO [pool-8-thread-2]: org.jitsi.videobridge.Conference - CAT=stat create_content,conf_id=d13ac35e782718d9,content=data conf_count=2,ch_count=2,v_streams=1
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-6]: org.jitsi.jicofo.ParticipantChannelAllocator - Sending session-initiate to: techtest@conference.d83.org/e9728ee9
2020.11.17 15:57:06 INFO [pool-8-thread-3]: org.ice4j.ice.Agent - Gathering candidates for component stream.RTP. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:06 INFO [pool-8-thread-3]: org.jitsi.videobridge.Conference - CAT=stat create_ice_tm,conf_id=d13ac35e782718d9 ufrag=3k33p1enc3c7b8,bundle=125f3ea3,initiator=true
2020.11.17 15:57:06 INFO [pool-8-thread-3]: org.jitsi.videobridge.RtpChannel - CAT=stat create_stream,conf_id=d13ac35e782718d9,content=audio,ch_id=fbcf1d1c5e140015,endp_id=null,stream=1169583302
2020.11.17 15:57:06 INFO [pool-8-thread-3]: org.jitsi.videobridge.Content - CAT=stat create_channel,conf_id=d13ac35e782718d9,content=audio,ch_id=fbcf1d1c5e140015,endp_id=null,stream=1169583302 transport=default,bundle=125f3ea3,initiator=true,media_type=audio,relay_type=null
2020.11.17 15:57:06 INFO [pool-8-thread-3]: org.jitsi.videobridge.RtpChannel - CAT=stat create_stream,conf_id=d13ac35e782718d9,content=video,ch_id=13de9d24bfc701ca,endp_id=null,stream=818557007
2020.11.17 15:57:06 INFO [pool-8-thread-3]: org.jitsi.videobridge.Content - CAT=stat create_channel,conf_id=d13ac35e782718d9,content=video,ch_id=13de9d24bfc701ca,endp_id=null,stream=818557007 transport=default,bundle=125f3ea3,initiator=true,media_type=video,relay_type=null
2020.11.17 15:57:06 INFO [Jicofo Cachedpool-42-thread-7]: org.jitsi.jicofo.ParticipantChannelAllocator - Sending session-initiate to: techtest@conference.d83.org/125f3ea3
2020.11.17 15:57:07 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Got session-accept from: techtest@conference.d83.org/e9728ee9
2020.11.17 15:57:07 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Received session-accept from techtest@conference.d83.org/e9728ee9 with accepted sources:Sources{ video: [ssrc=3462026070 ssrc=302955601 ssrc=3814965740 ssrc=3338671396 ssrc=861465647 ssrc=1195509606 ] audio: [ssrc=668271946 ] }@1276554457
2020.11.17 15:57:07 WARN [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - No jingle session yet for techtest@conference.d83.org/125f3ea3
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.jitsi.videobridge.cc.BitrateController - new track projection for subjective_quality=0,primary_ssrc=3462026070,secondary_ssrcs={302955601=rtx},temporal_id=-1,spatial_id=-1 subjective_quality=1,primary_ssrc=3814965740,secondary_ssrcs={861465647=rtx},temporal_id=-1,spatial_id=-1 subjective_quality=2,primary_ssrc=3338671396,secondary_ssrcs={1195509606=rtx},temporal_id=-1,spatial_id=-1
2020.11.17 15:57:07 WARN [pool-8-thread-4]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:07 WARN [pool-8-thread-4]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.jitsi.videobridge.IceUdpTransportManager - Starting ICE agent without remote candidates.
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.ice4j.ice.Agent - Start ICE connectivity establishment. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.ice4j.ice.Agent - Init checklist for stream stream
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.ice4j.ice.Agent - ICE state changed from Waiting to Running. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=d13ac35e782718d9,content=data,ch_id=320c82eb846ab30d,endp_id=e9728ee9 old_state=Waiting,new_state=Running
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.ice4j.ice.Agent - Trigger checks for pairs that were received before running state
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.ice4j.ice.Agent - Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751041232895):
LocalCandidate=candidate:2 1 udp 2130706431 10.83.1.112 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1853824767 10.72.7.1 55523 typ prflx
2020.11.17 15:57:07 INFO [pool-8-thread-4]: org.ice4j.ice.ConnectivityCheckClient - Start connectivity checks. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [pool-8-thread-5]: org.ice4j.ice.Component - Update remote candidate for stream.RTP: 10.72.7.1:55523/udp
2020.11.17 15:57:07 INFO [pool-8-thread-5]: org.ice4j.ice.Component - new Pair added: 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/host (stream.RTP). Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - Pair succeeded: 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/prflx (stream.RTP). Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.socket.MergingDatagramSocket - Adding allowed address: 10.72.7.1:55523/udp
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - Pair validated: 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/prflx (stream.RTP). Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.DefaultNominator - Nominate (first valid): 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/prflx (stream.RTP). Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.Agent - verify if nominated pair answer again
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - IsControlling: true USE-CANDIDATE:false. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - Pair succeeded: 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/prflx (stream.RTP). Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - IsControlling: true USE-CANDIDATE:false. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - Pair succeeded: 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/prflx (stream.RTP). Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - IsControlling: true USE-CANDIDATE:true. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.ConnectivityCheckClient - Nomination confirmed for pair: 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/prflx (stream.RTP). Loal ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.CheckList - Selected pair for stream stream.RTP: 10.83.1.112:10000/udp/host -> 10.72.7.1:55523/udp/prflx (stream.RTP)
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.Agent - CheckList of stream stream is COMPLETED
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.Agent - ICE state changed from Running to Completed. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=d13ac35e782718d9,content=data,ch_id=320c82eb846ab30d,endp_id=e9728ee9 old_state=Running,new_state=Completed
2020.11.17 15:57:07 INFO [ice4j.NetAccessManager-pool-16-thread-7]: org.ice4j.ice.Agent - Harvester used for selected pair for stream.RTP (local ufrag c4ulr1enc3c7at): host
2020.11.17 15:57:07 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=d13ac35e782718d9,content=audio,ch_id=d9f043396a421df,endp_id=e9728ee9,stream=2085913716
2020.11.17 15:57:07 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - audio codec/freq: null/null Hz
2020.11.17 15:57:07 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - audio remote IP/port: 10.72.7.1/55523
2020.11.17 15:57:07 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=d13ac35e782718d9,content=video,ch_id=6d953b26c9f7cb33,endp_id=e9728ee9,stream=2019461860
2020.11.17 15:57:07 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - video codec/freq: null/null Hz
2020.11.17 15:57:07 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - video remote IP/port: 10.72.7.1/55523
2020.11.17 15:57:07 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=d13ac35e782718d9,content=data,ch_id=320c82eb846ab30d,endp_id=e9728ee9
2020.11.17 15:57:07 WARN [org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.connectThread]: org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl - Unknown DTLS handshake message type: 46
2020.11.17 15:57:07 INFO [org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.connectThread]: org.jitsi.impl.neomedia.transform.srtp.AES - AES benchmark (of execution times expressed in nanoseconds): BouncyCastle 267292, SunJCE 65068
2020.11.17 15:57:07 INFO [ConferenceSpeechActivity-pool-12-thread-2]: org.jitsi.videobridge.Conference - CAT=stat ds_change,conf_id=d13ac35e782718d9 ds_id=e9728ee9
2020.11.17 15:57:07 WARN [ConferenceSpeechActivity-pool-12-thread-2]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with e9728ee9 not ready yet.
2020.11.17 15:57:07 WARN [ConferenceSpeechActivity-pool-12-thread-2]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:07 WARN [ConferenceSpeechActivity-pool-12-thread-2]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:07 WARN [ConferenceSpeechActivity-pool-12-thread-2]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:07 INFO [RTP Forwarder: org.jitsi.impl.neomedia.rtp.translator.RTPConnectorImpl@1d3fd9e6]: net.sf.fmj.media.Log - Resetting queue, last seq added: 9223372036854775806, current seq: 2372
2020.11.17 15:57:07 INFO [RTP Forwarder: org.jitsi.impl.neomedia.rtp.translator.RTPConnectorImpl@13f825e9]: net.sf.fmj.media.Log - Resetting queue, last seq added: 9223372036854775806, current seq: 7912
2020.11.17 15:57:07 INFO [RTP Forwarder: org.jitsi.impl.neomedia.rtp.translator.RTPConnectorImpl@1d3fd9e6]: net.sf.fmj.media.Log - Resetting queue, last seq added: 9223372036854775806, current seq: 2372
2020.11.17 15:57:07 INFO [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.SctpConnection - SCTP socket accepted on conf_id=d13ac35e782718d9,content=data,ch_id=320c82eb846ab30d,endp_id=e9728ee9
2020.11.17 15:57:07 INFO [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - WebRTC data channel established for conf_id=d13ac35e782718d9,content=data,ch_id=320c82eb846ab30d,endp_id=e9728ee9
2020.11.17 15:57:07 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:07 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:07 INFO [RTP Forwarder: org.jitsi.impl.neomedia.rtp.translator.RTPConnectorImpl@1d3fd9e6]: net.sf.fmj.media.Log - Resetting queue, last seq added: 9223372036854775806, current seq: 2372
2020.11.17 15:57:07 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=8d4ca0cf003d9157 conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:57:07 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 14ms. Sticky failure: false
2020.11.17 15:57:08 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Got session-accept from: techtest@conference.d83.org/125f3ea3
2020.11.17 15:57:08 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.jicofo.JitsiMeetConferenceImpl - Received session-accept from techtest@conference.d83.org/125f3ea3 with accepted sources:Sources{ video: [ssrc=1215849652 ssrc=2257820225 ssrc=2871297272 ssrc=872056717 ssrc=1088801097 ssrc=1424896627 ] audio: [ssrc=2073035410 ] }@955608756
2020.11.17 15:57:08 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.protocol.xmpp.AbstractOperationSetJingle - Notify add SSRC techtest@conference.d83.org/e9728ee9 SID: da6u9mgo759t1 Sources{ video: [ssrc=1215849652 ssrc=2257820225 ssrc=2871297272 ssrc=872056717 ssrc=1088801097 ssrc=1424896627 ] audio: [ssrc=2073035410 ] }@2055000969 source_Groups{ video:[ SourceGroup(FID)[ ssrc=1215849652 ssrc=2257820225 ]SourceGroup(SIM)[ ssrc=1215849652 ssrc=2871297272 ssrc=872056717 ]SourceGroup(FID)[ ssrc=2871297272 ssrc=1088801097 ]SourceGroup(FID)[ ssrc=872056717 ssrc=1424896627 ] ] }@1872629283
2020.11.17 15:57:08 INFO [Smack-Single Threaded Executor 0 (0)]: org.jitsi.protocol.xmpp.AbstractOperationSetJingle - Notify add SSRC techtest@conference.d83.org/125f3ea3 SID: 28j5i8o13sdi1 Sources{ audio: [ssrc=668271946 ] video: [ssrc=3462026070 ssrc=302955601 ssrc=3814965740 ssrc=3338671396 ssrc=861465647 ssrc=1195509606 ] }@370612077 source_Groups{ video:[ SourceGroup(FID)[ ssrc=3462026070 ssrc=302955601 ]SourceGroup(FID)[ ssrc=3814965740 ssrc=861465647 ]SourceGroup(FID)[ ssrc=3338671396 ssrc=1195509606 ]SourceGroup(SIM)[ ssrc=3462026070 ssrc=3814965740 ssrc=3338671396 ] ] }@84167637
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.jitsi.videobridge.cc.BitrateController - new track projection for subjective_quality=0,primary_ssrc=1215849652,secondary_ssrcs={2257820225=rtx},temporal_id=-1,spatial_id=-1 subjective_quality=1,primary_ssrc=2871297272,secondary_ssrcs={1088801097=rtx},temporal_id=-1,spatial_id=-1 subjective_quality=2,primary_ssrc=872056717,secondary_ssrcs={1424896627=rtx},temporal_id=-1,spatial_id=-1
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.jitsi.videobridge.IceUdpTransportManager - Starting ICE agent without remote candidates.
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.ice4j.ice.Agent - Start ICE connectivity establishment. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.ice4j.ice.Agent - Init checklist for stream stream
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.ice4j.ice.Agent - ICE state changed from Waiting to Running. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=d13ac35e782718d9,content=data,ch_id=b8a70936dc0273fa,endp_id=125f3ea3 old_state=Waiting,new_state=Running
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.ice4j.ice.Agent - Trigger checks for pairs that were received before running state
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.ice4j.ice.Agent - Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7926369428998979583):
LocalCandidate=candidate:2 1 udp 2130706431 10.83.1.112 10000 typ host
RemoteCandidate=candidate:10000 1 udp 1845501695 10.72.24.13 63139 typ prflx
2020.11.17 15:57:08 INFO [pool-8-thread-6]: org.ice4j.ice.ConnectivityCheckClient - Start connectivity checks. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [pool-8-thread-7]: org.ice4j.ice.Component - Update remote candidate for stream.RTP: 10.72.16.68:63139/udp
2020.11.17 15:57:08 INFO [pool-8-thread-7]: org.ice4j.ice.Component - new Pair added: 10.83.1.112:10000/udp/host -> 10.72.16.68:63139/udp/host (stream.RTP). Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.Agent-executor-pool-14-thread-7]: org.ice4j.ice.ConnectivityCheckClient - Pair failed: 10.83.1.112:10000/udp/host -> 10.72.16.68:63139/udp/host (stream.RTP)
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.ConnectivityCheckClient - Pair succeeded: 10.83.1.112:10000/udp/host -> 10.72.24.13:63139/udp/prflx (stream.RTP). Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.socket.MergingDatagramSocket - Adding allowed address: 10.72.24.13:63139/udp
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.ConnectivityCheckClient - Pair validated: 10.83.1.112:10000/udp/host -> 10.72.24.13:63139/udp/prflx (stream.RTP). Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.DefaultNominator - Nominate (first valid): 10.83.1.112:10000/udp/host -> 10.72.24.13:63139/udp/prflx (stream.RTP). Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.Agent - verify if nominated pair answer again
2020.11.17 15:57:08 WARN [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.socket.MergingDatagramSocket - Active socket already initialized.
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.ConnectivityCheckClient - IsControlling: true USE-CANDIDATE:false. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.ConnectivityCheckClient - Pair succeeded: 10.83.1.112:10000/udp/host -> 10.72.24.13:63139/udp/prflx (stream.RTP). Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.ConnectivityCheckClient - IsControlling: true USE-CANDIDATE:true. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.ConnectivityCheckClient - Nomination confirmed for pair: 10.83.1.112:10000/udp/host -> 10.72.24.13:63139/udp/prflx (stream.RTP). Loal ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.CheckList - Selected pair for stream stream.RTP: 10.83.1.112:10000/udp/host -> 10.72.24.13:63139/udp/prflx (stream.RTP)
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.Agent - CheckList of stream stream is COMPLETED
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.Agent - ICE state changed from Running to Completed. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=d13ac35e782718d9,content=data,ch_id=b8a70936dc0273fa,endp_id=125f3ea3 old_state=Running,new_state=Completed
2020.11.17 15:57:08 INFO [ice4j.NetAccessManager-pool-16-thread-8]: org.ice4j.ice.Agent - Harvester used for selected pair for stream.RTP (local ufrag 3k33p1enc3c7b8): host
2020.11.17 15:57:08 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=d13ac35e782718d9,content=audio,ch_id=fbcf1d1c5e140015,endp_id=125f3ea3,stream=1169583302
2020.11.17 15:57:08 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - audio codec/freq: null/null Hz
2020.11.17 15:57:08 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - audio remote IP/port: 10.72.24.13/63139
2020.11.17 15:57:08 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=d13ac35e782718d9,content=video,ch_id=13de9d24bfc701ca,endp_id=125f3ea3,stream=818557007
2020.11.17 15:57:08 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - video codec/freq: null/null Hz
2020.11.17 15:57:08 INFO [IceUdpTransportManager connect thread]: org.jitsi.impl.neomedia.MediaStreamImpl - video remote IP/port: 10.72.24.13/63139
2020.11.17 15:57:08 INFO [IceUdpTransportManager connect thread]: org.jitsi.videobridge.Channel - CAT=stat transport_connected,conf_id=d13ac35e782718d9,content=data,ch_id=b8a70936dc0273fa,endp_id=125f3ea3
2020.11.17 15:57:08 INFO [org.jitsi.impl.neomedia.rtp.translator.OutputDataStreamImpl]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=3462026070 remainingRetries=9
2020.11.17 15:57:08 ERROR [org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.connectThread]: org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer - Failed to accept a connection from a DTLS client!
java.io.IOException: org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl is closed!
at org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.assertNotClosed(DatagramTransportImpl.java:124) ~[libjitsi-1.0-20190724.114721-390.jar:?]
at org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.send(DatagramTransportImpl.java:477) ~[libjitsi-1.0-20190724.114721-390.jar:?]
at org.bouncycastle.crypto.tls.DTLSRecordLayer.sendRecord(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSRecordLayer.send(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake$RecordLayerBuffer.sendToRecordLayer(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.writeHandshakeFragment(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.writeMessage(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.resendOutboundFlight(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.receiveMessage(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSServerProtocol.serverHandshake(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.bouncycastle.crypto.tls.DTLSServerProtocol.accept(Unknown Source) ~[bcprov-jdk15on-1.64.jar:1.64.0]
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.runInConnectThread(DtlsPacketTransformer.java:1043) [libjitsi-1.0-20190724.114721-390.jar:?]
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.access$000(DtlsPacketTransformer.java:40) [libjitsi-1.0-20190724.114721-390.jar:?]
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer$1.run(DtlsPacketTransformer.java:1272) [libjitsi-1.0-20190724.114721-390.jar:?]
2020.11.17 15:57:08 INFO [org.jitsi.impl.neomedia.RTPConnectorInputStream.receiveThread]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=9
2020.11.17 15:57:08 WARN [org.jitsi.impl.neomedia.RTPConnectorInputStream.receiveThread]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:08 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=8
2020.11.17 15:57:08 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:08 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=7
2020.11.17 15:57:08 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:09 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=6
2020.11.17 15:57:09 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:09 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=5
2020.11.17 15:57:09 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:09 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=4
2020.11.17 15:57:09 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:10 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=3
2020.11.17 15:57:10 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:10 INFO [ice4j.Agent-timer-pool-13-thread-3]: org.ice4j.ice.Agent - ICE state changed from Completed to Terminated. Local ufrag c4ulr1enc3c7at
2020.11.17 15:57:10 INFO [ice4j.Agent-timer-pool-13-thread-3]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=d13ac35e782718d9,content=data,ch_id=320c82eb846ab30d,endp_id=e9728ee9 old_state=Completed,new_state=Terminated
2020.11.17 15:57:10 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=2
2020.11.17 15:57:10 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:10 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=1
2020.11.17 15:57:10 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:10 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Sending a FIR to ssrc=1215849652 remainingRetries=0
2020.11.17 15:57:10 WARN [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-RTCPFeedbackMessageSender]: org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender - Not sending an FIR because the stream RTP manager is null.
2020.11.17 15:57:11 INFO [ice4j.Agent-timer-pool-13-thread-3]: org.ice4j.ice.Agent - ICE state changed from Completed to Terminated. Local ufrag 3k33p1enc3c7b8
2020.11.17 15:57:11 INFO [ice4j.Agent-timer-pool-13-thread-3]: org.jitsi.videobridge.IceUdpTransportManager - CAT=stat ice_state_change,conf_id=d13ac35e782718d9,content=data,ch_id=b8a70936dc0273fa,endp_id=125f3ea3 old_state=Completed,new_state=Terminated
2020.11.17 15:57:11 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:11 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:17 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=48a6dab51a1f37f0 conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:57:17 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 19ms. Sticky failure: false
2020.11.17 15:57:23 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:23 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:26 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:26 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:26 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:26 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:27 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=8560c0882a5b507b conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:57:27 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 8ms. Sticky failure: false
2020.11.17 15:57:36 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:36 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:37 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=c4d544931669dcd5 conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:57:37 ERROR [MergingDatagramSocket reader thread for: /10.83.1.112:11138 -> null]: org.ice4j.socket.MergingDatagramSocket - Cannot find socket to remove.
2020.11.17 15:57:37 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 15ms. Sticky failure: false
2020.11.17 15:57:38 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:38 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:41 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:41 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:46 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:46 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=a6d1078bc82a6496 conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:57:47 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 18ms. Sticky failure: false
2020.11.17 15:57:53 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:53 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:54 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-VideobridgeExpireThread]: org.jitsi.videobridge.VideobridgeExpireThread - Running expire()
2020.11.17 15:57:54 INFO [VideobridgeExpireThread-channel-pool-11-thread-5]: org.jitsi.videobridge.Content - expire_content,conf_id=2d64f4b0fd2f9d06,content=video
2020.11.17 15:57:54 INFO [VideobridgeExpireThread-channel-pool-11-thread-6]: org.jitsi.videobridge.Content - expire_content,conf_id=2d64f4b0fd2f9d06,content=data
2020.11.17 15:57:54 INFO [VideobridgeExpireThread-channel-pool-11-thread-4]: org.jitsi.videobridge.Content - expire_content,conf_id=2d64f4b0fd2f9d06,content=audio
2020.11.17 15:57:56 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:56 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:56 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:57:56 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:57:57 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=d0891f725670487e conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:57:57 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 8ms. Sticky failure: false
2020.11.17 15:58:06 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:58:06 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:58:07 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=a98e26a90de35977 conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:58:07 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 14ms. Sticky failure: false
2020.11.17 15:58:08 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:58:08 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:58:11 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:58:11 WARN [EndpointConnectionStatusMonitoring]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:58:16 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - SCTP connection with 125f3ea3 not ready yet.
2020.11.17 15:58:16 WARN [org.jitsi.impl.osgi.framework.AsyncExecutor]: org.jitsi.videobridge.EndpointMessageTransport - No available transport channel, can't send a message
2020.11.17 15:58:17 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.Videobridge - CAT=stat create_conf,conf_id=7edaaf547a9b9ee3 conf_name=null,logging=false,conf_count=3,ch_count=6,v_streams=4
2020.11.17 15:58:17 INFO [org.jitsi.utils.concurrent.RecurringRunnableExecutor.thread-org.jitsi.videobridge.health.Health]: org.jitsi.videobridge.health.Health - Performed a successful health check in 12ms. Sticky failure: false```

gjaekel commented 3 years ago

2020.11.17 15:57:08 ERROR [org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.connectThread]: org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer - Failed to accept a connection from a DTLS client! java.io.IOException: org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl is closed!

This indicates an (D)TLS-Error on the media streams.

At the moment, you can't use the Jitsi Meet App >=V20.4 because this will need to use the Jitsi Videobridge V2 . On Android, you may install an older Version (20.3) via APK. On iOS, you have to accept Apple's choice and have to wait.

ChicagoJay commented 3 years ago

I can't use it on iPads even in Safari? I thought that was just the Jitsi app.

gjaekel commented 3 years ago

Oh sorry, I' don't read it careful. I don't know about much about iOS and what version of Safari uses what engine. All browsers using the Chrome engine are known to work. This even include the new Microsoft Edge browser, and it seems that this available for iOS, too. May you give this a try for a technical check?

ChicagoJay commented 3 years ago

I am trying in Chrome for iPad, and I get a yellow bar on the bottom, that says: Audio and video error: undefined is not an object (evaluating 'navigator.mediaDevices.getUserMedia')

According to this, Apple is intentionally blocking access to the Mic and Camera from Chrome; "In short: A PWA or any website being used on iOS outside of the native Safari app will not be allowed access to getUserMedia as Apple is deliberately blocking access for "Security concerns"." WTH?

gjaekel commented 3 years ago

To be secure that Apple's customers will use software of Apple's choice.

ChicagoJay commented 3 years ago

Indeed. So now I am stuck. The whole point of this project was to come up with an in-house replacement for Google Meet, which has been terribly unreliable for our iPad users. Now that I finally have it all up and running, I get tripwired by Apple. Perfect.

I'm going to go home and drink strong adult beverages now.

gjaekel commented 3 years ago

Did you try Microsoft Edge for iOS, too? And to my knowldege, Dele is already working to get the JVB2 running on the OpenFire platform.

I'm going to go home and drink strong adult beverages now.

cheers! Do you know about our Frankfurter Apfelwein (https://en.wikipedia.org/wiki/Cider)?

naive924 commented 3 years ago

This issue seems to occur from Chrome 87.

Is this the cause?

Chrome 87 to remove support for non-standard RTP data channels in WebRTC https://www.techtsp.com/2020/08/chrome-87-remove-non-standard-rtp-data-channels-webrtc.html

gjaekel commented 3 years ago

IMHO it's a result of enforcing DTLS >=1.2 by the new version of the clients. The VB1 build inside ofmeeting.jar uses a version of the "bouncy castle" libs that don't support this. Unfortunately, the API has changed. Therefore you can't just replace the bc JARs -- I already prooved this -- but also have at least to recompile the code and maybe tweak it a little bit.

ChicagoJay commented 3 years ago

Did you try Microsoft Edge for iOS, too? And to my knowldege, Dele is already working to get the JVB2 running on the OpenFire platform.

Edge (as well as Firefox) has the same result as Chrome - yellow bar on the bottom. Also, in the setup screen, in the lobby, the gear shows "Permission not given" in the camera and microphone menus.

I guess we wait for VB2. Thanks all. I am guessing we leave this open until it's resolved?

ChicagoJay commented 3 years ago

Is the Jitsi Meet app supposed to work? I am able to grant access to the Camera and Mic to the app, but it's unable to start or join meetings on my OF server. When I try, it immediately says I've been disconnected, and to check my network connection. There is no change in behavior between joining an existing room or creating a new one. Also of note: I am not even asked to authenticate.

gjaekel commented 3 years ago

With respect to the JBV1-Problem, you need an App-Version <=20.3.
The App require TLS. You need to have a proper TLS certificate for your Server-Domain, a self-signed will not work.

ChicagoJay commented 3 years ago

With respect to the JBV1-Problem, you need an App-Version <=20.3.

I don't think it's possible to downgrade an app, once it's been installed on an iPad. 👎

The App require TLS. You need to have a proper TLS certificate for your Server-Domain, a self-signed will not work.

No problem - I have a 3rd Party wildcard certificate already installed! 👍

gjaekel commented 3 years ago

I don't think it's possible to downgrade an app, once it's been installed on an iPad. -1

One of our fellows - a follower of the World of Apple -- told me the same. That's why I open #76 about a month ago.

gjaekel commented 3 years ago

When I try, it immediately says I've been disconnected, and to check my network connection.

In my installation, I have an additional reverse proxy in front of the Openfire server because we want to have our own pre-pages. Therefore, I have to rewrite some URLs. From some "reverse engineering" to get this running, I remember that (on of?) the first things the App will ask is https://your.domain/config.js. You may request this by wget or curl and take a look on the content and may test the URLs in this configuration information for accessibility.

ChicagoJay commented 3 years ago

Yeah... That's way over my head.... XD

gjaekel commented 3 years ago

I'm sorry not to take this into account. But you may even use a browser to call this URL! The answer is in JSON, your browser probably even pretty-print the output.

deleolajide commented 3 years ago

Please test new pre-release version 1.1.0 and confirm if it works for you.

gjaekel commented 3 years ago

For the OFFocus plugin, the external start of the JVM for JiFoCo will fail on Unix because the separator used to build the ClassPath is in Windows style (; instead of :).

20201123-081746.612 INFO  [Thread-80] [o.j.o.p.o.JitsiJicofoWrapper] Successfully initialized Jitsi Focus Component (jicofo).
/opt/openjdk-bin-8.262_p10/jre/bin/java -Dnet.java.sip.communicator.SC_HOME_DIR_LOCATION=. -Dnet.java.sip.communicator.SC_HOME_DIR_NAME=. -Djava.util.logging.config.file=./logging.properties -Djdk.tls.ephemeralDHKeySize=2048 -cp /opt/openfire.4_6_1_20201020-171600/plugins/offo>
20201123-081746.671 INFO  [Thread-115] [o.j.o.p.o.JitsiJicofoWrapper] Error: Could not find or load main class org.jitsi.jicofo.Main

I was able to start this JVM with the corrected parameters at command line. Now, it appear as external component session as expected.

For the OFMeeting, it logs to start. The JVM user is listed as generated, but not as online. Here, we have the same issue with the ClassPath:

20201123-085124.789 INFO  [pool-7-thread-1] [o.j.o.p.o.JvbPluginWrapper] Successfully initialized Jitsi Videobridge.
/opt/openjdk-bin-8.262_p10/jre/bin/java -Dconfig.file=./application.conf -Dnet.java.sip.communicator.SC_HOME_DIR_LOCATION=. -Dnet.java.sip.communicator.SC_HOME_DIR_NAME=. -Djava.util.logging.config.file=./logging.properties -Djdk.tls.ephemeralDHKeySize=2048 -cp /opt/openfire.4_6_1_20201020-171600/plugins/ofmeet/classes/jvb/jitsi-videobridge.jar;/opt/openfire.4_6_1_20201020-171600/plugins/ofmeet/classes/jvb/jitsi-videobridge-2.1-SNAPSHOT-jar-with-dependencies.jar org.jitsi.videobridge.MainKt  --apis=rest

Again, I was able to launch this JVM on commandline, but I have to correct the parameter for the config.file too (./plugins/ofmeet/classes/jvb/application.conf).

Now, there's a room ofmeet with the user jvb joined, but focus is still missing.

At the console of the OFMeet-JVM, I found the exception:

Exception in thread "Smack-Single Threaded Executor 0 (0)" java.lang.NullPointerException
    at org.jitsi.xmpp.mucclient.MucClient.handleIq(MucClient.java:484)
    at org.jitsi.xmpp.mucclient.MucClient.access$300(MucClient.java:50)
    at org.jitsi.xmpp.mucclient.MucClient$2.handleIQRequest(MucClient.java:466)
    at org.jivesoftware.smack.AbstractXMPPConnection$4.run(AbstractXMPPConnection.java:1188)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
    at java.lang.Thread.run(Thread.java:748)

deleolajide commented 3 years ago

@gjaekel thanks for the PRs and Linux feedback. I just started testing on Linux. I got both Jicofo and JVB online and both in ofmeet MUC (thanks to you), but I can't finish my testing because ports 8080/8443 needed by JVB websockets are blocked.

I have updated the jar files for anyone else to test

gjaekel commented 3 years ago

I have updated the jar files for anyone else to test

Using this Versions (file timestamp about 20201123-1149), the OFMeet plugin at least start, and the room ofmeet gets populated with the two users, focus and jvb.

In the log, the content of the config file is printed. This is probably a left-over of debugging an should be deleted by reasons of security.

First quick test is negative, will try to analyze it further

20201123-133955.716 INFO  [socket_c2s-thread-2] [o.j.o.m.s.LocalMUCRoom] Applying affiliation change for foo@eval.xmpp.dnb.de
20201123-133955.716 INFO  [socket_c2s-thread-2] [o.j.o.m.s.LocalMUCRoom] New affiliation: owner
[...]
61230a68 member=ChatMember[gurkensalat@conference.eval.xmpp.dnb.de/d4e57ddc, jid: foo@eval.xmpp.dnb.de/a0eq5vsvpx]@1614639304]
20201123-134050.691 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.690 INFO: [28] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Owner has left the room !
20201123-134050.691 INFO  [socket_c2s-thread-2] [o.j.o.m.s.LocalMUCRoom] Applying affiliation change for bar@eval.xmpp.dnb.de
20201123-134050.691 INFO  [socket_c2s-thread-2] [o.j.o.m.s.LocalMUCRoom] New affiliation: owner
20201123-134050.692 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.692 INFO: [28] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Granted owner to gurkensalat@conference.eval.xmpp.dnb.de/3f358869
20201123-134050.692 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.692 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member gurkensalat@conference.eval.xmpp.dnb.de/d4e57ddc is leaving
20201123-134050.693 WARN  [ForkJoinPool.commonPool-worker-4] [o.j.o.h.PresenceUpdateHandler] Presence requested from server eval.xmpp.dnb.de by unknown user: gurkensalat@conference.eval.xmpp.dnb.de/3f358869
20201123-134050.693 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.693 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Terminating Participant[gurkensalat@conference.eval.xmpp.dnb.de/d4e57ddc]@814463719, reason: gone, send st: false
20201123-134050.693 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.693 INFO: [28] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.log() Terminate session: gurkensalat@conference.eval.xmpp.dnb.de/d4e57ddc, reason: gone, send terminate: false
20201123-134050.696 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.696 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removing gurkensalat@conference.eval.xmpp.dnb.de/d4e57ddc sources Sources{ video: [ssrc=3684857736 ssrc=2943570541 ssrc=4036661852 ssrc=2814317493 ssrc=1402067978 ssrc=921653126 ] audio: [ssrc=1027050203 ] }@14873672
20201123-134050.697 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.697 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removed participant: true, gurkensalat@conference.eval.xmpp.dnb.de/d4e57ddc
20201123-134050.698 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.697 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Region info, conference=12018 octo_enabled= false: [[null, null]]
20201123-134050.698 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 13:40:50.698 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Expiring channels for: gurkensalat@conference.eval.xmpp.dnb.de/d4e57ddc on: Bridge[jid=ofmeet@conference.eval.xmpp.dnb.de/jvb, relayId=null, region=null, stress=0.02]

gjaekel commented 3 years ago

ports 8080/8443 needed by JVB websockets

Please, may you explain or point to docs about the (new?) requirements? I see a listener at localhost:8080, but not at 8443.

deleolajide commented 3 years ago

See https://github.com/jitsi/jitsi-videobridge/blob/master/doc/web-sockets.md

gjaekel commented 3 years ago

The logged config says

videobridge{
[...]
    websockets {
      enabled = true
      domain = "eval.xmpp.dnb.de"
      tls = true
    }
[...]

This means, oe have to proxy the path /colibri-ws/jvb2/(.*)to this localhost:8080 JVM running the bridge? But the value of the domain here isn't that of my frontend server, but the one of the OFServer with the embedded Jetty. I'm not familiar with Jetty in any way; howto configure this?

gjaekel commented 3 years ago

The ICE handshake fails, it don't finger out the external IP address for the client!

20201123-143258.481 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.481 INFO: [30] [confId=2f193fc4e1d3a90d epId=76652b55 gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
20201123-143258.481 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.481 INFO: [30] [confId=2f193fc4e1d3a90d epId=76652b55 local_ufrag=8mutg1enqktdm1 gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] IceTransport.startConnectivityEstablishment#184: Starting the Agent without remote candidates.
20201123-143258.482 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.481 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 epId=76652b55 local_ufrag=8mutg1enqktdm1] Agent.startConnectivityEstablishment#713: Start ICE connectivity establishment.
20201123-143258.482 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.482 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 epId=76652b55 local_ufrag=8mutg1enqktdm1] Agent.initCheckLists#949: Init checklist for stream stream-76652b55
20201123-143258.482 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.482 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 epId=76652b55 local_ufrag=8mutg1enqktdm1] Agent.setState#923: ICE state changed from Waiting to Running.
20201123-143258.482 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.482 INFO: [30] [confId=2f193fc4e1d3a90d epId=76652b55 local_ufrag=8mutg1enqktdm1 gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] IceTransport.iceStateChanged#323: ICE state changed old=Waiting new=Running
20201123-143258.482 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.482 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 epId=76652b55 local_ufrag=8mutg1enqktdm1] ConnectivityCheckClient.startChecks#142: Start connectivity checks.
20201123-143258.526 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.526 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 name=stream-76652b55 epId=76652b55 local_ufrag=8mutg1enqktdm1] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-76652b55.RTP: 172.16.2.17:50998/udp
20201123-143258.526 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.526 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 name=stream-76652b55 epId=76652b55 local_ufrag=8mutg1enqktdm1] Component.updateRemoteCandidates#481: new Pair added: 10.10.1.141:10000/udp/host -> 172.16.2.17:50998/udp/host (stream-76652b55.RTP).
20201123-143258.526 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.526 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 name=stream-76652b55 epId=76652b55 local_ufrag=8mutg1enqktdm1] Component.updateRemoteCandidates#481: new Pair added: 10.10.59.52:10000/udp/host -> 172.16.2.17:50998/udp/host (stream-76652b55.RTP).
20201123-143258.544 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.544 INFO: [48] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 epId=76652b55 local_ufrag=8mutg1enqktdm1] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.10.1.141:10000/udp/host -> 172.16.2.17:50998/udp/host (stream-76652b55.RTP)
20201123-143258.564 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.564 INFO: [48] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 epId=76652b55 local_ufrag=8mutg1enqktdm1] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.10.59.52:10000/udp/host -> 172.16.2.17:50998/udp/host (stream-76652b55.RTP)
20201123-143258.644 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.643 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 name=stream-76652b55 epId=76652b55 local_ufrag=8mutg1enqktdm1] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-76652b55.RTP: 172.16.2.17:50998/udp
20201123-143258.644 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 14:32:58.644 INFO: [30] [confId=2f193fc4e1d3a90d gid=10 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=8mutg1enqktdm1 name=stream-76652b55 epId=76652b55 local_ufrag=8mutg1enqktdm1] Component.addUpdateRemoteCandidates#369: Not adding duplicate remote candidate: 172.16.2.17:50998/udp
20201123-143258.915 INFO  [Thread-164] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 14:32:58.915 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Got session-accept from: gurkensalat@conference.eval.xmpp.dnb.de/4d6a9037

Here a working handshake taken from the current production using OFMeet-1.0.0 containing JVB1:

20201123-122928.366 INFO  [pool-9-thread-5] [o.i.i.Component] Update remote candidate for stream.RTP: 172.16.2.17:43447/udp
20201123-122928.366 INFO  [pool-9-thread-5] [o.i.i.Component] new Pair added: 10.10.1.146:10000/udp/host -> 172.16.2.17:43447/udp/host (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.366 INFO  [pool-9-thread-5] [o.i.i.Component] new Pair added: 193.175.100.146:10000/udp/srflx -> 172.16.2.17:43447/udp/host (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.385 INFO  [ent-executor-pool-15-thread-2600] [o.i.i.ConnectivityCheckClient] Pair failed: 10.10.1.146:10000/udp/host -> 172.16.2.17:43447/udp/host (stream.RTP)
20201123-122928.387 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.ConnectivityCheckClient] Pair succeeded: 10.10.1.146:10000/udp/host -> 87.144.250.254:43447/udp/prflx (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.387 INFO  [ccessManager-pool-17-thread-1533] [o.i.s.MergingDatagramSocket] Adding allowed address: 87.144.250.254:43447/udp
20201123-122928.387 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.ConnectivityCheckClient] Pair validated: 193.175.100.146:10000/udp/srflx -> 87.144.250.254:43447/udp/prflx (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.388 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.DefaultNominator] Nominate (first valid): 193.175.100.146:10000/udp/srflx -> 87.144.250.254:43447/udp/prflx (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.388 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.Agent] verify if nominated pair answer again
20201123-122928.388 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.ConnectivityCheckClient] IsControlling: true USE-CANDIDATE:false. Local ufrag br81v1enqdr9ht
20201123-122928.409 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.ConnectivityCheckClient] Pair succeeded: 193.175.100.146:10000/udp/srflx -> 87.144.250.254:43447/udp/prflx (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.409 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.ConnectivityCheckClient] Pair validated: 193.175.100.146:10000/udp/srflx -> 87.144.250.254:43447/udp/prflx (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.409 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.DefaultNominator] Nominate (first valid): 193.175.100.146:10000/udp/srflx -> 87.144.250.254:43447/udp/prflx (stream.RTP). Local ufrag br81v1enqdr9ht
20201123-122928.409 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.ConnectivityCheckClient] IsControlling: true USE-CANDIDATE:true. Local ufrag br81v1enqdr9ht
20201123-122928.409 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.ConnectivityCheckClient] Nomination confirmed for pair: 193.175.100.146:10000/udp/srflx -> 87.144.250.254:43447/udp/prflx (stream.RTP). Loal ufrag br81v1enqdr9ht
20201123-122928.409 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.CheckList] Selected pair for stream stream.RTP: 193.175.100.146:10000/udp/srflx -> 87.144.250.254:43447/udp/prflx (stream.RTP)
20201123-122928.410 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.Agent] CheckList of stream stream is COMPLETED
20201123-122928.410 INFO  [ccessManager-pool-17-thread-1533] [o.i.i.Agent] ICE state changed from Running to Completed. Local ufrag br81v1enqdr9ht
20201123-122928.410 INFO  [ccessManager-pool-17-thread-1533] [o.j.v.IceUdpTransportManager] CAT=stat ice_state_change,conf_id=1274c41aba0b7b6f,content=data,ch_id=effe32906c648c1c,endp_id=88799817 old_state=Running,new_state=Completed

The ConfigFile-Servlet builds the output

    ice {
        tcp {
            enabled = false
            port = "4443"
            mapped-port = "4443"
        }
        udp {
            port = "10000"
            local-address = 10.10.1.141
            public-address = 193.175.100.141
        }
    }

Here, enabled = false. But at production stage, there's listener at port 4443!

gjaekel commented 3 years ago

I discovered that the maybe-related GUI-setting switched itself from disabled to automatic and can't be disabled!

gjaekel commented 3 years ago

Maybe similar to https://community.jitsi.org/t/videobridge-under-nat-doesnt-works-since-jvb2/68961, I'm going to carefully read this...

deleolajide commented 3 years ago

That is not yet implemented. The parameters being used can be seen in the generated application.conf file. TCP ICE is disabled by default. The issue stopping this from working in Linux is websockets TLS. I think I have fixed it, but currently issues with docker and certificates and can't confirm. Try the latest jar files

videobridge {
    health {
        interval=300 seconds
    }

    stats {
        # Enable broadcasting stats/presence in a MUC
        enabled = true
        transports = [
            { type = "muc" }
        ]
    }

   http-servers {
       public {
           tls-port = 8443
           key-store-path = "C:/openfire_4_6_0/resources/security/client.truststore"
           key-store-password = changeit
       }
   }
    websockets {
      enabled = true
      domain = "desktop-545pc5b:8443"
      tls = true
    }

    apis {
        xmpp-client {
               configs {
                # Connect to the first XMPP server
                shard {
                    hostname= "desktop-545pc5b"
                    domain = "desktop-545pc5b"
                    username = "jvb"
                    password = "AvszgRa11CEsIx3lNbj7mRakQ8S10N3dkSiSexS4"
                    muc_jids = "ofmeet@conference.desktop-545pc5b"
                    muc_nickname = "jvb"
                    disable_certificate_verification = true
                }
               }
        }
    }

    ice {
        tcp {
            enabled = false
            port = "4443"
            mapped-port = "4443"
        }
        udp {
            port = "10001"
            local-address = 192.168.1.251
            public-address = 90.248.44.212
        }
    }
}

gjaekel commented 3 years ago

From the log, I don't think that RTP via TCP is used in production, therefore this might be a misleading fact. But there "harvesting" of the external IP of the client is missing or fails.

Nevertheless, in the log is

20201123-134032.753 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 13:40:32.752 INFO: [30] org.ice4j.ice.harvest.AbstractUdpListener.<init>: Initialized AbstractUdpListener with address 10.10.1.141:10000/udp. Receive buffer size 10485760 (asked for 10485760)
20201123-134032.753 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 13:40:32.753 INFO: [30] org.ice4j.ice.harvest.SinglePortUdpHarvester.<init>: Initialized SinglePortUdpHarvester with address 10.10.1.141:10000/udp
20201123-134032.753 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 13:40:32.753 INFO: [30] org.ice4j.ice.harvest.AbstractUdpListener.<init>: Initialized AbstractUdpListener with address 10.10.59.52:10000/udp. Receive buffer size 10485760 (asked for 10485760)
20201123-134032.754 INFO  [Thread-88] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 13:40:32.753 INFO: [30] org.ice4j.ice.harvest.SinglePortUdpHarvester.<init>: Initialized SinglePortUdpHarvester with address 10.10.59.52:10000/udp

gjaekel commented 3 years ago

No change with current jars V1.1.0 @ 20201123-1539: The harvester don't detect the external IP of the client.

xmpp:jingle:transports:ice-udp:1'><fingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/></transport></channel-bundle><endpoint id='156786ab' stats-id='Adah-rAB'/></conference></iq>
20201123-161957.075 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:19:57.075 INFO: [69] org.jitsi.jicofo.ParticipantChannelAllocator.log() Sending session-initiate to: gurkensalat@conference.eval.xmpp.dnb.de/156786ab
20201123-161957.081 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.081 INFO: [30] [confId=2c866991c3838e9d epId=f3a3ba17 local_ufrag=adl801enqr1aco gid=35185 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco] Agent.gatherCandidates#622: Gathering candidates for component stream-f3a3ba17.RTP.
20201123-161957.085 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.085 INFO: [30] [confId=2c866991c3838e9d epId=f3a3ba17 gid=35185 conf_name=gurkensalat@conference.eval.xmpp.dnb.de] Endpoint.lambda$setTransportInfo$11#1068: Ignoring empty DtlsFingerprint extension: <transport xmlns='urn:xmpp:jingle:transports:ice-udp:1'><fingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' required='false'/></transport>
20201123-161957.090 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:19:57.090 INFO: [70] org.jitsi.jicofo.ParticipantChannelAllocator.log() Sending session-initiate to: gurkensalat@conference.eval.xmpp.dnb.de/f3a3ba17
20201123-161957.217 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:19:57.217 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Got session-accept from: gurkensalat@conference.eval.xmpp.dnb.de/f3a3ba17
20201123-161957.229 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:19:57.229 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Received session-accept from gurkensalat@conference.eval.xmpp.dnb.de/f3a3ba17 with accepted sources:Sources{ video: [ssrc=287101056 ssrc=1982578123 ssrc=3544975900 ssrc=1242428140 ssrc=3526765207 ssrc=2027284418 ] audio: [ssrc=3853944618 ] }@963640867
20201123-161957.234 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:19:57.234 WARNING: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() No jingle session yet for gurkensalat@conference.eval.xmpp.dnb.de/156786ab
20201123-161957.234 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:19:57.234 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Got session-accept from: gurkensalat@conference.eval.xmpp.dnb.de/156786ab
20201123-161957.240 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:19:57.240 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Received session-accept from gurkensalat@conference.eval.xmpp.dnb.de/156786ab with accepted sources:Sources{ video: [ssrc=1474127643 ssrc=1625032718 ssrc=378623891 ssrc=1996487113 ssrc=3621864815 ssrc=1311359455 ] audio: [ssrc=1935410601 ] }@626437550
20201123-161957.267 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.267 INFO: [30] [confId=2c866991c3838e9d epId=f3a3ba17 gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
20201123-161957.274 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.274 INFO: [30] [confId=2c866991c3838e9d epId=f3a3ba17 local_ufrag=adl801enqr1aco gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] IceTransport.startConnectivityEstablishment#184: Starting the Agent without remote candidates.
20201123-161957.274 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.274 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco epId=f3a3ba17 local_ufrag=adl801enqr1aco] Agent.startConnectivityEstablishment#713: Start ICE connectivity establishment.
20201123-161957.274 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.274 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco epId=f3a3ba17 local_ufrag=adl801enqr1aco] Agent.initCheckLists#949: Init checklist for stream stream-f3a3ba17
20201123-161957.276 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.276 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco epId=f3a3ba17 local_ufrag=adl801enqr1aco] Agent.setState#923: ICE state changed from Waiting to Running.
20201123-161957.277 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.277 INFO: [30] [confId=2c866991c3838e9d epId=f3a3ba17 local_ufrag=adl801enqr1aco gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] IceTransport.iceStateChanged#323: ICE state changed old=Waiting new=Running
20201123-161957.277 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.277 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco epId=f3a3ba17 local_ufrag=adl801enqr1aco] ConnectivityCheckClient.startChecks#142: Start connectivity checks.
20201123-161957.283 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.283 INFO: [30] [confId=2c866991c3838e9d epId=156786ab gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] DtlsTransport.setSetupAttribute#120: The remote side is acting as DTLS client, we'll act as server
20201123-161957.284 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.284 INFO: [30] [confId=2c866991c3838e9d epId=156786ab local_ufrag=2qacv1enqr19r6 gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] IceTransport.startConnectivityEstablishment#184: Starting the Agent without remote candidates.
20201123-161957.284 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.284 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 epId=156786ab local_ufrag=2qacv1enqr19r6] Agent.startConnectivityEstablishment#713: Start ICE connectivity establishment.
20201123-161957.284 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.284 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 epId=156786ab local_ufrag=2qacv1enqr19r6] Agent.initCheckLists#949: Init checklist for stream stream-156786ab
20201123-161957.284 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.284 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 epId=156786ab local_ufrag=2qacv1enqr19r6] Agent.setState#923: ICE state changed from Waiting to Running.
20201123-161957.284 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.284 INFO: [30] [confId=2c866991c3838e9d epId=156786ab local_ufrag=2qacv1enqr19r6 gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de] IceTransport.iceStateChanged#323: ICE state changed old=Waiting new=Running
20201123-161957.284 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.284 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 epId=156786ab local_ufrag=2qacv1enqr19r6] ConnectivityCheckClient.startChecks#142: Start connectivity checks.
20201123-161957.296 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.296 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 name=stream-156786ab epId=156786ab local_ufrag=2qacv1enqr19r6] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-156786ab.RTP: 172.16.2.17:34056/udp
20201123-161957.297 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.297 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 name=stream-156786ab epId=156786ab local_ufrag=2qacv1enqr19r6] Component.updateRemoteCandidates#481: new Pair added: 10.10.1.141:10000/udp/host -> 172.16.2.17:34056/udp/host (stream-156786ab.RTP).
20201123-161957.297 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.297 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 name=stream-156786ab epId=156786ab local_ufrag=2qacv1enqr19r6] Component.updateRemoteCandidates#481: new Pair added: 10.10.59.52:10000/udp/host -> 172.16.2.17:34056/udp/host (stream-156786ab.RTP).
20201123-161957.298 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.297 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco name=stream-f3a3ba17 epId=f3a3ba17 local_ufrag=adl801enqr1aco] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-f3a3ba17.RTP: 172.16.2.17:47180/udp
20201123-161957.298 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.298 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco name=stream-f3a3ba17 epId=f3a3ba17 local_ufrag=adl801enqr1aco] Component.updateRemoteCandidates#481: new Pair added: 10.10.1.141:10000/udp/host -> 172.16.2.17:47180/udp/host (stream-f3a3ba17.RTP).
20201123-161957.298 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.298 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco name=stream-f3a3ba17 epId=f3a3ba17 local_ufrag=adl801enqr1aco] Component.updateRemoteCandidates#481: new Pair added: 10.10.59.52:10000/udp/host -> 172.16.2.17:47180/udp/host (stream-f3a3ba17.RTP).
20201123-161957.311 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.311 INFO: [37] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 epId=156786ab local_ufrag=2qacv1enqr19r6] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.10.1.141:10000/udp/host -> 172.16.2.17:34056/udp/host (stream-156786ab.RTP)
20201123-161957.311 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.311 INFO: [36] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco epId=f3a3ba17 local_ufrag=adl801enqr1aco] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.10.1.141:10000/udp/host -> 172.16.2.17:47180/udp/host (stream-f3a3ba17.RTP)
20201123-161957.332 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.332 INFO: [37] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco epId=f3a3ba17 local_ufrag=adl801enqr1aco] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.10.59.52:10000/udp/host -> 172.16.2.17:47180/udp/host (stream-f3a3ba17.RTP)
20201123-161957.332 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.332 INFO: [36] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 epId=156786ab local_ufrag=2qacv1enqr19r6] ConnectivityCheckClient$PaceMaker.run#922: Pair failed: 10.10.59.52:10000/udp/host -> 172.16.2.17:34056/udp/host (stream-156786ab.RTP)
20201123-161957.403 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.403 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 name=stream-156786ab epId=156786ab local_ufrag=2qacv1enqr19r6] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-156786ab.RTP: 172.16.2.17:34056/udp
20201123-161957.403 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.403 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=2qacv1enqr19r6 name=stream-156786ab epId=156786ab local_ufrag=2qacv1enqr19r6] Component.addUpdateRemoteCandidates#369: Not adding duplicate remote candidate: 172.16.2.17:34056/udp
20201123-161957.405 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.405 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco name=stream-f3a3ba17 epId=f3a3ba17 local_ufrag=adl801enqr1aco] Component.addUpdateRemoteCandidates#347: Update remote candidate for stream-f3a3ba17.RTP: 172.16.2.17:47180/udp
20201123-161957.405 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:19:57.405 INFO: [30] [confId=2c866991c3838e9d gid=35185 stats_id=Adah-rAB componentId=1 conf_name=gurkensalat@conference.eval.xmpp.dnb.de ufrag=adl801enqr1aco name=stream-f3a3ba17 epId=f3a3ba17 local_ufrag=adl801enqr1aco] Component.addUpdateRemoteCandidates#369: Not adding duplicate remote candidate: 172.16.2.17:47180/udp
20201123-162003.088 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:20:03.088 INFO: [16] VideobridgeExpireThread.expire#140: Running expire()
20201123-162014.445 INFO  [Thread-129] [o.j.o.p.o.JitsiJicofoWrapper] Jicofo 2020-11-23 16:20:14.445 INFO: [28] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberLeft sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@2c5c6e25 member=ChatMember[gurkensalat@conference.eval.xmpp.dnb.de/f3a3ba17, jid: null]@606008847]

gjaekel commented 3 years ago

Is the Error loading config file: java.io.FileNotFoundException: ././sip-communicator.properties (No such file or directory) by intention?

Can't explain this error, because the procfs say that the CWD of the JVM is /opt/openfire.4_6_1_20201020-171600/plugins/offocus/classes/jicofo/ and there's a sip-communiator.properties

20201123-161602.592 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:02.591 INFO: [1] JitsiConfig.<clinit>#47: Initialized newConfig: merge of /opt/openfire.4_6_1_20201020-171600/plugins/ofmeet/classes/jvb/application.conf: 1,application.conf @ jar:file:/opt/open
fire.4_6_1_20201020-171600/plugins/ofmeet/classes/jvb/jitsi-videobridge.jar!/application.conf: 1,system properties,reference.conf @ jar:file:/opt/openfire.4_6_1_20201020-171600/plugins/ofmeet/classes/jvb/jitsi-videobridge.jar!/reference.conf: 1,reference.conf @ jar:file:/opt/op
enfire.4_6_1_20201020-171600/plugins/ofmeet/classes/jvb/jitsi-videobridge-2.1-SNAPSHOT-jar-with-dependencies.jar!/reference.conf: 1
20201123-161602.595 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:02.594 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#51: loading config file at path ././sip-communicator.properties
20201123-161602.596 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:02.595 INFO: [1] ReadOnlyConfigurationService.reloadConfiguration#56: Error loading config file: java.io.FileNotFoundException: ././sip-communicator.properties (No such file or directory)
20201123-161602.599 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:02.599 INFO: [1] JitsiConfig.<clinit>#68: Initialized legacyConfig: sip communicator props (no description provided)
20201123-161602.601 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:02.601 INFO: [1] JitsiConfig$Companion.reloadNewConfig#94: Reloading the Typesafe config source (previously reloaded 0 times).
20201123-161602.875 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:02.875 INFO: [9] org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize: Using AwsCandidateHarvester.
20201123-161603.021 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.021 INFO: [10] [hostname=eval.xmpp.dnb.de id=shard] MucClient.initializeConnectAndJoin#227: Initializing a new MucClient for [ org.jitsi.xmpp.mucclient.MucClientConfiguration id=shard domain=
eval.xmpp.dnb.de hostname=eval.xmpp.dnb.de port=null username=jvb mucs=[ofmeet@conference.eval.xmpp.dnb.de] mucNickname=jvb disableCertificateVerification=true]
20201123-161603.028 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.028 WARNING: [10] MucClient.createXMPPTCPConnectionConfiguration#113: Disabling certificate verification!
20201123-161603.048 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.048 INFO: [1] LastNReducer.<init>#65: LastNReducer with reductionScale: 0.75 recoverScale: 1.25 impactTime: PT1M minLastN: 0 maxEnforcedLastN: 40
20201123-161603.050 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.050 INFO: [1] TaskPools.<clinit>#81: TaskPools detected 2 processors, creating the CPU pool with that many threads
20201123-161603.063 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.062 INFO: [10] [hostname=eval.xmpp.dnb.de id=shard] MucClient.initializeConnectAndJoin#302: Dispatching a thread to connect and login.
20201123-161603.088 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.087 INFO: [1] UlimitCheck.printUlimits#115: Running with open files limit 8192 (hard 8192), thread limit 1547573 (hard 1547573).
20201123-161603.088 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.088 INFO: [1] VideobridgeExpireThread.start#88: Starting with 60 second interval.
20201123-161603.089 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.089 INFO: [1] HealthChecker.start#118: Started with interval=300000, timeout=PT30S, maxDuration=PT3S, stickyFailures=false.
20201123-161603.107 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.107 INFO: [1] MainKt.main#106: Not starting CallstatsService, disabled in configuration.
20201123-161603.110 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.110 INFO: [1] MainKt.main#124: Not starting public http server
20201123-161603.111 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.110 INFO: [1] MainKt.main#133: Starting private http server
20201123-161603.168 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.168 INFO: [1] org.eclipse.jetty.util.log.Log.initialized: Logging initialized @1007ms to org.eclipse.jetty.util.log.JavaUtilLog
20201123-161603.277 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.277 INFO: [1] org.eclipse.jetty.server.Server.doStart: jetty-9.4.33.v20201020; built: 2020-10-20T23:39:24.803Z; git: 1be68755656cef678b79a2ef1c2ebbca99e25420; jvm 1.8.0_262-b10
20201123-161603.358 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.358 INFO: [10] [hostname=eval.xmpp.dnb.de id=shard] MucClient$1.connected#259: Connected.
20201123-161603.358 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.358 INFO: [10] [hostname=eval.xmpp.dnb.de id=shard] MucClient.lambda$getConnectAndLoginCallable$7#594: Logging in.
20201123-161603.400 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.399 INFO: [9] org.ice4j.ice.harvest.MappingCandidateHarvesters.maybeAdd: Discarding a mapping harvester: org.ice4j.ice.harvest.AwsCandidateHarvester, face=null, mask=null
20201123-161603.400 INFO  [Thread-91] [o.j.o.p.o.JvbPluginWrapper] 2020-11-23 16:16:03.399 INFO: [9] org.ice4j.ice.harvest.MappingCandidateHarvesters.initialize: Initialized mapping harvesters (delay=775ms).  stunDiscoveryFailed=false

deleolajide commented 3 years ago

not using sip-communicator.properties with JVB. using the new config format https://github.com/igniterealtime/openfire-pade-plugin/issues/88#issuecomment-732207517

gjaekel commented 3 years ago

not using sip-communicator.properties with JVB

OK, then it's just a cosmetic issue. But then, I'm stumped why the ICE harvester don't work anymore.

gjaekel commented 3 years ago

Maybe the problem is caused by some missing JVM options like java.net.preferIPv4Stack=true. And I want to set some more like or garbage collecting options. May we introduce a user-configurable options string to fill before the first argument?

gjaekel commented 3 years ago

I checked it at my approval stage: Allow to use the java IPv6 stack (by removing java.net.preferIPv4Stack=true from the starter of the OpenFire JVM) will not lead to an issue; the listener are bound to the IPv4 and the corresponing IPv6 alias:

netstat -tulpen
[...]
udp        0      0 10.10.61.52:10000       0.0.0.0:*                           2051       3340107621 25594/java          
udp        0      0 10.10.1.137:10000       0.0.0.0:*                           2051       3340107620 25594/java

vs.

netstat -tulpen
[...]
udp6       0      0 10.10.61.52:10000       :::*                                2051       4084473884 21240/java          
udp6       0      0 fe80::250:c2ff:fe:10000 :::*                                2051       4084473883 21240/java          
udp6       0      0 10.10.1.137:10000       :::*                                2051       4084473882 21240/java          
udp6       0      0 fe80::250:c2ff:fe:10000 :::*                                2051       4084473881 21240/java

Here the complete ICE handling for one of two clients (audio only) (no matter of IPV6 enabled or not)

20201123-194246.133 INFO  [pool-9-thread-2] [o.j.v.IceUdpTransportManager] Starting ICE agent without remote candidates.
20201123-194246.133 INFO  [pool-9-thread-2] [o.i.i.Agent] Start ICE connectivity establishment. Local ufrag 1djgb1enr6klbu
20201123-194246.133 INFO  [pool-9-thread-2] [o.i.i.Agent] Init checklist for stream stream
20201123-194246.133 INFO  [pool-9-thread-2] [o.i.i.Agent] ICE state changed from Waiting to Running. Local ufrag 1djgb1enr6klbu
20201123-194246.133 INFO  [pool-9-thread-2] [o.j.v.IceUdpTransportManager] CAT=stat ice_state_change,conf_id=3813e6bd250e54af,content=data,ch_id=ed5d85c2161182d,endp_id=7c6338ce old_state=Waiting,new_state=Running
20201123-194246.133 INFO  [pool-9-thread-2] [o.i.i.Agent] Trigger checks for pairs that were received before running state
20201123-194246.133 INFO  [pool-9-thread-2] [o.i.i.Agent] Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7962116751007684095):
        LocalCandidate=candidate:7 1 udp 2113932031 10.10.1.137 10000 typ host
        RemoteCandidate=candidate:10000 1 udp 1853824767 87.144.250.254 34892 typ prflx
20201123-194246.138 INFO  [pool-9-thread-2] [o.i.i.ConnectivityCheckClient] Start connectivity checks. Local ufrag 1djgb1enr6klbu
20201123-194246.175 INFO  [etAccessManager-pool-17-thread-1] [o.i.i.ConnectivityCheckClient] Pair succeeded: 10.10.1.137:10000/udp/host -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.179 INFO  [etAccessManager-pool-17-thread-1] [o.i.s.MergingDatagramSocket] Adding allowed address: 87.144.250.254:34892/udp
20201123-194246.179 INFO  [etAccessManager-pool-17-thread-1] [o.i.i.ConnectivityCheckClient] Pair validated: 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.180 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.ConnectivityCheckClient] Pair succeeded: 10.10.1.137:10000/udp/host -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.180 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.ConnectivityCheckClient] Pair validated: 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.182 INFO  [etAccessManager-pool-17-thread-1] [o.i.i.DefaultNominator] Nominate (first valid): 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.183 INFO  [etAccessManager-pool-17-thread-1] [o.i.i.Agent] verify if nominated pair answer again
20201123-194246.183 INFO  [etAccessManager-pool-17-thread-1] [o.i.i.ConnectivityCheckClient] IsControlling: true USE-CANDIDATE:false. Local ufrag 1djgb1enr6klbu
20201123-194246.185 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.DefaultNominator] Nominate (first valid): 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.185 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.ConnectivityCheckClient] IsControlling: true USE-CANDIDATE:false. Local ufrag 1djgb1enr6klbu
20201123-194246.188 INFO  [pool-9-thread-3] [o.i.i.Component] Update remote candidate for stream.RTP: 172.16.142.3:34892/udp
20201123-194246.189 INFO  [pool-9-thread-3] [o.i.i.Component] Update remote candidate for stream.RTP: 172.16.142.3:9/tcp
20201123-194246.189 INFO  [pool-9-thread-3] [o.i.i.Component] new Pair added: 10.10.1.137:4443/tcp/host -> 172.16.142.3:9/tcp/host (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.190 INFO  [pool-9-thread-3] [o.i.i.Component] new Pair added: 10.10.61.52:4443/tcp/host -> 172.16.142.3:9/tcp/host (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.190 INFO  [pool-9-thread-3] [o.i.i.Component] new Pair added: 10.10.1.137:10000/udp/host -> 172.16.142.3:34892/udp/host (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.190 INFO  [pool-9-thread-3] [o.i.i.Component] new Pair added: 10.10.61.52:10000/udp/host -> 172.16.142.3:34892/udp/host (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.190 INFO  [pool-9-thread-3] [o.i.i.Component] new Pair added: 193.175.100.137:4443/tcp/srflx -> 172.16.142.3:9/tcp/host (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.190 INFO  [pool-9-thread-3] [o.i.i.Component] new Pair added: 193.175.100.137:10000/udp/srflx -> 172.16.142.3:34892/udp/host (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.201 INFO  [.Agent-executor-pool-15-thread-1] [o.i.i.ConnectivityCheckClient] Pair failed: 10.10.1.137:10000/udp/host -> 172.16.142.3:34892/udp/host (stream.RTP)
20201123-194246.216 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.ConnectivityCheckClient] Pair succeeded: 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.217 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.ConnectivityCheckClient] Pair validated: 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.217 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.DefaultNominator] Nominate (first valid): 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Local ufrag 1djgb1enr6klbu
20201123-194246.217 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.ConnectivityCheckClient] IsControlling: true USE-CANDIDATE:true. Local ufrag 1djgb1enr6klbu
20201123-194246.217 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.ConnectivityCheckClient] Nomination confirmed for pair: 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP). Loal ufrag 1djgb1enr6klbu
20201123-194246.219 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.CheckList] Selected pair for stream stream.RTP: 193.175.100.137:10000/udp/srflx -> 87.144.250.254:34892/udp/prflx (stream.RTP)
20201123-194246.220 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.Agent] CheckList of stream stream is COMPLETED
20201123-194246.220 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.Agent] ICE state changed from Running to Completed. Local ufrag 1djgb1enr6klbu
20201123-194246.221 INFO  [etAccessManager-pool-17-thread-2] [o.j.v.IceUdpTransportManager] CAT=stat ice_state_change,conf_id=3813e6bd250e54af,content=data,ch_id=ed5d85c2161182d,endp_id=7c6338ce old_state=Running,new_state=Completed
20201123-194246.221 INFO  [etAccessManager-pool-17-thread-2] [o.i.i.Agent] Harvester used for selected pair for stream.RTP (local ufrag 1djgb1enr6klbu): srflx
20201123-194246.223 INFO  [pTransportManager connect thread] [o.j.v.Channel] CAT=stat transport_connected,conf_id=3813e6bd250e54af,content=audio,ch_id=9826a6af1beb5090,endp_id=7c6338ce,stream=1125886769
20201123-194246.293 INFO  [k-Single Threaded Executor 0 (0)] [o.j.j.JitsiMeetConferenceImpl] Got session-accept from: gurkensalat@conference.appr.xmpp.dnb.de/cfad9c80
20201123-194246.295 INFO  [k-Single Threaded Executor 0 (0)] [o.j.j.JitsiMeetConferenceImpl] Received session-accept from gurkensalat@conference.appr.xmpp.dnb.de/cfad9c80 with accepted sources:Sources{ audio: [ssrc=1145860550 ] }@951064807

deleolajide commented 3 years ago

I have not got that far. I am still stuck trying to get a secure websockets connection for JVB2 inside my Linux Docker instance

gjaekel commented 3 years ago

I am still stuck trying to get a secure websockets connection for JVB2 inside my Linux Docker instance

For what purpose a http(!) websocket connection is needed in your environment? Do you rely on RTC-over-TCP?

deleolajide commented 3 years ago

For what purpose a http(!) websocket connection is needed in your environment? Do you rely on RTC-over-TCP?

No. To replace webrtc datachaneels. ses https://github.com/jitsi/jitsi-videobridge/blob/master/doc/web-sockets.md

gjaekel commented 3 years ago

Is this a must-have or an option? Will this prevent the propper ice handling with the client in my case?

deleolajide commented 3 years ago

Is this a must-have or an option? Will this prevent the propper ice handling with the client in my case?

For me it it. The client is throwing errors

gjaekel commented 3 years ago

You confuse me. The client don't use UDP for audio/video transmission anymore?

deleolajide commented 3 years ago

You confuse me. The client don't use UDP for audio/video transmission anymore?

Not media, but the data channel. Read the link I posted. It will explain it better than me :-) https://github.com/igniterealtime/openfire-pade-plugin/issues/88#issuecomment-732376095

gjaekel commented 3 years ago

This is not my day; I don't understand how to proceed in any way...

I have a reverse proxy and web server using a complete other domain to deliver some pre-pages. But technical you don't need this to use OFMeeting, all the related content is served by the Jetty at eval.xmpp.dnb.de:7443 which is local translated by iptables to 443. This port 443 is open and static translated by the firewall from the public IP to the DMZ transfer net IP. The same static translation is done for UDP on 10000. All work very well since a whole bunch of months.

The videobridge is annouced at the XMPP domain. Therefore, I expect that the websocket request is made to eval.xmpp.dnb.de:443, what is serverd by the Jetty. But where is the Jetty-configuration to proxy, upgrade and pass path /colibri-ws/server-id/conf-id/endpoint-id?pwd=123 in any way to the JVB process listening on localhost:8080?

deleolajide commented 3 years ago

I finally got it working on Linux and also working with Jitsi Mobile app

ChicagoJay commented 3 years ago

Huzzah! Mazel tov! Can't wait to try it! I'll keep my eyes open for the new plugin(s). Thanks so much, I really think this will help our students - Google Meet has been awful lately!

gjaekel commented 3 years ago

@ChicagoJay: There's a pre-release available. But you should make a snapshot or even a clone of your instance. At the moment I have a problem with the so-called ICE harvester to get the public IPs of the client. But maybe it's already working inside a intranet.

gjaekel commented 3 years ago

I finally got it working on Linux and also working with Jitsi Mobile app

Is there a new build?

FYI: I also asked for hints at https://community.jitsi.org/t/ice-harvester-of-jvb2-dont-recognize-public-address-of-clients/86256

deleolajide commented 3 years ago

I am working on a new 1.1.1 build, but you can always try the latest 1.1.0 refresh I made today

gjaekel commented 3 years ago

Will try it ASAP, but I have to prepare a conference event (local Java user group) soon.

igniterealtime / openfire-pade-plugin

Can't establish audio or video #88