Jigasi JVB connectivity problems

Hi there.
I’m trying to run a little bit customized docker-jitsi-meet solution.

  • Web, prosody and jicofo containers lives on the same instance in compose mode
  • JVB container lives on it’s own instance
  • Jigasi container lives on it’s own instance and network mode here is host.

Auth is disabled.
Everything works fine until I try to dial in. SIP call is configured properly and has headers.

X-Room-Name: 5e29c1e63954b01fa275d27e@muc.meet.jitsi X-Domain-Base: meet.jitsi

Jigasi trying to joing JVB ConferenceRoom and getting
Jigasi 2020-01-28 13:10:15.620 SEVERE: [78] impl.protocol.jabber.InfoRetreiver.retrieveDetails().369 Cannot load details for contact 16fec0f2a28@meet.jitsi: XMPP error reply received from 16fec0f2a28@meet.jitsi: XMPPError: item-not-found - cancel

As a result jitsi don’t respond ACK to incoming call. And for the conference user it looks like hanging call initialization
Screen Shot 2020-01-28 at 15.15.35

I see nothing too suspicious in JVB logs

Any ideas?

P.S. I was trying to run JVB in host mode too, but can’t get it configured in a proper way.
What should be
Dorg.ice4j.ice.harvest.NAT_HARVESTER_LOCAL_ADDRESS Dorg.ice4j.ice.harvest.NAT_HARVESTER_PUBLIC_ADDRESS
for that kind of run?

Are there other logs from jigasi when trying to join?

Sure.

Jigasi logs

Jigasi 2020-01-28 13:10:14.279 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:14.293 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:14.296 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:14.309 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:14.439 INFO: [44] org.jitsi.jigasi.SipGateway.incomingCallReceived().188 Incoming call received... Jigasi 2020-01-28 13:10:15.449 INFO: [70] org.jitsi.jigasi.SipGatewaySession.run().894 Wait thread cancelled Jigasi 2020-01-28 13:10:15.469 INFO: [44] org.jitsi.jigasi.JvbConference.setXmppProvider().561 16fec0f2a28@meet.jitsi will use ProtocolProviderServiceJabberImpl(16fec0f2a28@meet.jitsi (Jabber)) Jigasi 2020-01-28 13:10:15.579 INFO: [71] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().125 Jingle : ON Jigasi 2020-01-28 13:10:15.579 INFO: [71] org.jitsi.jigasi.JvbConference.registrationStateChanged().606 XMPP (16fec0f2a28@meet.jitsi): RegistrationStateChangeEvent[ oldState=Registering; newState=RegistrationState=Registering; reasonCode=-1; reason=null] Jigasi 2020-01-28 13:10:15.590 INFO: [71] impl.protocol.jabber.ProtocolProviderServiceJabberImpl.authenticated().2536 Authenticated: false Jigasi 2020-01-28 13:10:15.605 SEVERE: [78] util.AvatarCacheUtils.getLocallyStoredAvatar().219 Could not read avatar image from file avatarcache/Jabber&_co16fec0f2a28@meet.jitsi&_sl16fec0f2a28/Jabber&_co16fec0f2a28@meet.jitsi&_sl16fec0f2a28 java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/avatarcache/Jabber&_co16fec0f2a28@meet.jitsi&_sl16fec0f2a28/Jabber&_co16fec0f2a28@meet.jitsi&_sl16fec0f2a28 at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.util.AvatarCacheUtils.getLocallyStoredAvatar(AvatarCacheUtils.java:190) at net.java.sip.communicator.util.AvatarCacheUtils.getCachedAvatar(AvatarCacheUtils.java:77) at net.java.sip.communicator.impl.globaldisplaydetails.GlobalDisplayDetailsImpl$UpdateAccountInfo.run(GlobalDisplayDetailsImpl.java:377) Jigasi 2020-01-28 13:10:15.607 INFO: [71] org.jitsi.jigasi.JvbConference.joinConferenceRoom().647 Joining JVB conference room: 5e29c1e63954b01fa275d27e@muc.meet.jitsi Jigasi 2020-01-28 13:10:15.620 SEVERE: [78] impl.protocol.jabber.InfoRetreiver.retrieveDetails().369 Cannot load details for contact 16fec0f2a28@meet.jitsi : XMPP error reply received from 16fec0f2a28@meet.jitsi: XMPPError: item-not-found - cancel Jigasi 2020-01-28 13:10:15.638 INFO: [75] impl.protocol.jabber.ChatRoomJabberImpl.joined().1247 5e29c1e63954b01fa275d27e@muc.meet.jitsi/b10d85df has joined the 5e29c1e63954b01fa275d27e@muc.meet.jitsi chat room. Jigasi 2020-01-28 13:10:15.640 INFO: [86] impl.protocol.jabber.JingleNodesServiceDiscovery.run().111 Start Jingle Nodes discovery! Jigasi 2020-01-28 13:10:15.646 INFO: [86] impl.protocol.jabber.JingleNodesServiceDiscovery.run().151 Jingle Nodes discovery terminated! Jigasi 2020-01-28 13:10:15.646 INFO: [75] impl.protocol.jabber.ChatRoomJabberImpl.joined().1247 5e29c1e63954b01fa275d27e@muc.meet.jitsi/focus has joined the 5e29c1e63954b01fa275d27e@muc.meet.jitsi chat room. Jigasi 2020-01-28 13:10:15.647 INFO: [86] impl.protocol.jabber.JingleNodesServiceDiscovery.run().152 Found 0 Jingle Nodes relay for account: 16fec0f2a28@meet.jitsi in 7 ms. Jigasi 2020-01-28 13:10:15.964 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:15.979 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:15.979 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:15.992 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:15.995 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:16.008 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:16.009 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:16.022 SEVERE: [19] impl.packetlogging.PacketLoggingServiceImpl.run().845 Error writing packet to file java.lang.SecurityException: Insufficient rights to access this file in current user's home directory: /config/log/jitsi0.pcap at org.jitsi.impl.fileaccess.FileAccessServiceImpl.getPrivatePersistentFile(FileAccessServiceImpl.java:171) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.getFileNames(PacketLoggingServiceImpl.java:197) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.savePacket(PacketLoggingServiceImpl.java:582) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl.access$100(PacketLoggingServiceImpl.java:35) at net.java.sip.communicator.impl.packetlogging.PacketLoggingServiceImpl$SaverThread.run(PacketLoggingServiceImpl.java:834) Jigasi 2020-01-28 13:10:16.024 INFO: [110] impl.protocol.jabber.IceUdpTransportManager.createIceAgent().254 Auto discovered harvester is null Jigasi 2020-01-28 13:10:16.039 INFO: [110] impl.protocol.jabber.IceUdpTransportManager.createIceAgent().346 End gathering harvester within 152 ms Jigasi 2020-01-28 13:10:16.469 INFO: [118] impl.protocol.jabber.JingleNodesHarvester.harvest().92 harvest Jingle Nodes Jigasi 2020-01-28 13:10:16.583 INFO: [110] impl.protocol.jabber.CallPeerMediaHandlerJabberImpl.harvestCandidates().1198 End candidate harvest within 171 ms Jigasi 2020-01-28 13:10:16.584 WARNING: [110] impl.protocol.jabber.IceUdpTransportManager.startConnectivityEstablishment().1073 No ICE media stream for media: video - ignored candidates. Jigasi 2020-01-28 13:10:16.642 INFO: [110] org.jitsi.jigasi.JvbConference.incomingCallReceived().965 Got invite from focus Jigasi 2020-01-28 13:10:16.805 INFO: [123] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 126=rtpmap:-1 telephone-event/8000; 100=rtpmap:-1 VP8/90000 fmtp:x-google-start-bitrate=800; 101=rtpmap:-1 VP9/90000 fmtp:x-google-start-bitrate=800; 111=rtpmap:-1 opus/48000/2 fmtp:useinbandfec=1;minptime=10; 103=rtpmap:-1 unknown/90000; Jigasi 2020-01-28 13:10:16.806 INFO: [123] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides [103->107 ] Jigasi 2020-01-28 13:10:16.913 INFO: [123] service.protocol.media.CallPeerMediaHandler.start().1960 Starting Jigasi 2020-01-28 13:10:21.944 INFO: [75] impl.protocol.jabber.ChatRoomJabberImpl.left().1291 5e29c1e63954b01fa275d27e@muc.meet.jitsi/b10d85df has left the 5e29c1e63954b01fa275d27e@muc.meet.jitsi chat room. Jigasi 2020-01-28 13:10:21.945 INFO: [75] org.jitsi.jigasi.JvbConference.memberPresenceChanged().861 Member left : OWNER lqrmak5agsr4fhnv@meet.jitsi/btdsGqSO Jigasi 2020-01-28 13:10:24.755 INFO: [44] org.jitsi.jigasi.SipGatewaySession.handleCallState().766 SIP call ended: CallPeerChangeEvent: type=CallPeerStatusChange oldV=net.java.sip.communicator.service.protocol.CallPeerState:Incoming Call newV=net.java.sip.communicator.service.protocol.CallPeerState:Disconnected for peer=jigasi-user <jigasi-user@10.199.244.9>;status=Disconnected Jigasi 2020-01-28 13:10:24.756 INFO: [44] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().828 16fec0f2a28@meet.jitsi SIP peer state: Disconnected Jigasi 2020-01-28 13:10:29.758 INFO: [130] org.jitsi.jigasi.SipGatewaySession.sipCallEnded().507 Sip call ended: Call: id=15802134143351970209270 peers=0 Jigasi 2020-01-28 13:10:29.760 INFO: [130] org.jitsi.jigasi.JvbConference.stop().521 16fec0f2a28@meet.jitsi is removing account Jabber:16fec0f2a28@meet.jitsi/16fec0f2a28 Jigasi 2020-01-28 13:10:29.766 INFO: [130] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().132 Jingle : OFF Jigasi 2020-01-28 13:10:29.767 INFO: [130] org.jitsi.jigasi.AbstractGateway.notifyCallEnded().141 Removed session for call 16fec0f2a28@meet.jitsi Jigasi 2020-01-28 13:10:29.773 INFO: [131] org.jitsi.jigasi.JvbConference.peerStateChanged().1031 16fec0f2a28@meet.jitsi JVB peer state: net.java.sip.communicator.service.protocol.CallPeerState:Disconnected

JVB log does not look too suspicious but i can add it as well.

JVB logs

JVB 2020-01-28 13:10:10.315 INFO: [18] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=f3d298ed28bd786b conf_name=null,logging=false,conf_count=1,ch_count=0,v_streams=0 JVB 2020-01-28 13:10:10.351 INFO: [18] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 37ms. Sticky failure: false JVB 2020-01-28 13:10:13.011 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@7489eae1 JVB 2020-01-28 13:10:15.735 INFO: [108] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=3018688ea979f921 conf_name=5e29c1e63954b01fa275d27e,logging=true,conf_count=1,ch_count=0,v_streams=0 JVB 2020-01-28 13:10:15.738 INFO: [108] org.jitsi.videobridge.Conference.log() CAT=stat create_content,conf_id=3018688ea979f921,content=audio conf_count=1,ch_count=0,v_streams=0 JVB 2020-01-28 13:10:15.740 INFO: [108] org.ice4j.ice.Agent.log() Gathering candidates for component stream.RTP. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:15.743 INFO: [108] org.jitsi.videobridge.Conference.log() CAT=stat create_ice_tm,conf_id=3018688ea979f921 ufrag=3vdlo1dvm0ubpr,bundle=16fec0f2a28,initiator=true JVB 2020-01-28 13:10:15.743 INFO: [108] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=null,stream=631027340 JVB 2020-01-28 13:10:15.744 INFO: [108] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=null,stream=631027340 transport=default,bundle=16fec0f2a28,initiator=true,media_type=audio,relay_type=null JVB 2020-01-28 13:10:15.785 INFO: [108] org.jitsi.videobridge.Conference.log() CAT=stat create_content,conf_id=3018688ea979f921,content=video conf_count=1,ch_count=1,v_streams=0 JVB 2020-01-28 13:10:15.789 INFO: [108] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=3018688ea979f921,content=video,ch_id=74135a1f9657289f,endp_id=null,stream=1278509475 JVB 2020-01-28 13:10:15.789 INFO: [108] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=3018688ea979f921,content=video,ch_id=74135a1f9657289f,endp_id=null,stream=1278509475 transport=default,bundle=16fec0f2a28,initiator=true,media_type=video,relay_type=null JVB 2020-01-28 13:10:16.167 INFO: [108] org.ice4j.ice.Agent.log() Gathering candidates for component stream.RTP. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:16.168 INFO: [108] org.jitsi.videobridge.Conference.log() CAT=stat create_ice_tm,conf_id=3018688ea979f921 ufrag=a8nk71dvm0uc77,bundle=b10d85df,initiator=true JVB 2020-01-28 13:10:16.169 INFO: [108] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=3018688ea979f921,content=audio,ch_id=3345f0611b73adad,endp_id=null,stream=882071145 JVB 2020-01-28 13:10:16.169 INFO: [108] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=3018688ea979f921,content=audio,ch_id=3345f0611b73adad,endp_id=null,stream=882071145 transport=default,bundle=b10d85df,initiator=true,media_type=audio,relay_type=null JVB 2020-01-28 13:10:16.172 INFO: [108] org.jitsi.videobridge.RtpChannel.log() CAT=stat create_stream,conf_id=3018688ea979f921,content=video,ch_id=787d7b23c0d6af85,endp_id=null,stream=1251759215 JVB 2020-01-28 13:10:16.173 INFO: [108] org.jitsi.videobridge.Content.log() CAT=stat create_channel,conf_id=3018688ea979f921,content=video,ch_id=787d7b23c0d6af85,endp_id=null,stream=1251759215 transport=default,bundle=b10d85df,initiator=true,media_type=video,relay_type=null JVB 2020-01-28 13:10:16.178 INFO: [108] org.jitsi.videobridge.Conference.log() CAT=stat create_content,conf_id=3018688ea979f921,content=data conf_count=1,ch_count=4,v_streams=4 JVB 2020-01-28 13:10:16.588 INFO: [108] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 10.199.245.29:20000/udp/host JVB 2020-01-28 13:10:16.589 INFO: [108] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 34.232.173.75:20000/udp/srflx JVB 2020-01-28 13:10:16.589 INFO: [108] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 35.153.228.129:20646/udp/srflx JVB 2020-01-28 13:10:16.589 INFO: [108] org.jitsi.videobridge.IceUdpTransportManager.log() We have remote candidates for all ICE components. Starting the ICE agent. JVB 2020-01-28 13:10:16.590 INFO: [108] org.ice4j.ice.Agent.log() Start ICE connectivity establishment. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.590 INFO: [108] org.ice4j.ice.Agent.log() Init checklist for stream stream JVB 2020-01-28 13:10:16.590 INFO: [108] org.ice4j.ice.Agent.log() ICE state changed from Waiting to Running. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.591 INFO: [108] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=16fec0f2a28 old_state=Waiting,new_state=Running JVB 2020-01-28 13:10:16.592 INFO: [108] org.ice4j.ice.ConnectivityCheckClient.log() Start connectivity checks. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.613 INFO: [283] org.ice4j.ice.ConnectivityCheckClient.log() Pair failed: 172.17.0.2:10000/udp/host -> 10.199.245.29:20000/udp/host (stream.RTP) JVB 2020-01-28 13:10:16.641 INFO: [283] org.ice4j.ice.ConnectivityCheckClient.log() Pair failed: 172.17.0.2:10000/udp/host -> 34.232.173.75:20000/udp/srflx (stream.RTP) JVB 2020-01-28 13:10:16.662 INFO: [283] org.ice4j.ice.ConnectivityCheckClient.log() Pair failed: 172.17.0.2:10000/udp/host -> 35.153.228.129:20646/udp/srflx (stream.RTP) JVB 2020-01-28 13:10:16.668 INFO: [286] org.ice4j.ice.Agent.log() Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7926347438766424063): LocalCandidate=candidate:1 1 udp 2130706431 172.17.0.2 10000 typ host RemoteCandidate=candidate:10000 1 udp 1845496575 35.153.228.129 58140 typ prflx JVB 2020-01-28 13:10:16.689 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 172.17.0.2:10000/udp/host -> 35.153.228.129:58140/udp/prflx (stream.RTP). Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.689 INFO: [286] org.ice4j.socket.MergingDatagramSocket.log() Adding allowed address: 35.153.228.129:58140/udp JVB 2020-01-28 13:10:16.690 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 54.211.3.253:10000/udp/srflx -> 35.153.228.129:58140/udp/prflx (stream.RTP). Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.691 INFO: [286] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 54.211.3.253:10000/udp/srflx -> 35.153.228.129:58140/udp/prflx (stream.RTP). Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.692 INFO: [286] org.ice4j.ice.Agent.log() verify if nominated pair answer again JVB 2020-01-28 13:10:16.692 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:false. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.708 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 54.211.3.253:10000/udp/srflx -> 35.153.228.129:58140/udp/prflx (stream.RTP). Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.708 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 54.211.3.253:10000/udp/srflx -> 35.153.228.129:58140/udp/prflx (stream.RTP). Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.708 INFO: [286] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 54.211.3.253:10000/udp/srflx -> 35.153.228.129:58140/udp/prflx (stream.RTP). Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.709 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:true. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.709 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Nomination confirmed for pair: 54.211.3.253:10000/udp/srflx -> 35.153.228.129:58140/udp/prflx (stream.RTP). Loal ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.709 INFO: [286] org.ice4j.ice.CheckList.log() Selected pair for stream stream.RTP: 54.211.3.253:10000/udp/srflx -> 35.153.228.129:58140/udp/prflx (stream.RTP) JVB 2020-01-28 13:10:16.710 INFO: [286] org.ice4j.ice.Agent.log() CheckList of stream stream is COMPLETED JVB 2020-01-28 13:10:16.710 INFO: [286] org.ice4j.ice.Agent.log() ICE state changed from Running to Completed. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:16.710 INFO: [286] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=16fec0f2a28 old_state=Running,new_state=Completed JVB 2020-01-28 13:10:16.711 INFO: [281] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=16fec0f2a28,stream=631027340 JVB 2020-01-28 13:10:16.715 INFO: [286] org.ice4j.ice.Agent.log() Harvester used for selected pair for stream.RTP (local ufrag 3vdlo1dvm0ubpr): srflx JVB 2020-01-28 13:10:16.857 INFO: [296] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 169.132.90.211:61288/udp/host JVB 2020-01-28 13:10:16.857 INFO: [296] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 192.168.230.136:56215/udp/host JVB 2020-01-28 13:10:16.858 INFO: [296] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 169.132.90.211:61288/udp/host JVB 2020-01-28 13:10:16.858 INFO: [296] org.ice4j.ice.Component.log() Add remote candidate for stream.RTP: 192.168.230.136:56215/udp/host JVB 2020-01-28 13:10:16.858 INFO: [296] org.jitsi.videobridge.IceUdpTransportManager.log() We have remote candidates for all ICE components. Starting the ICE agent. JVB 2020-01-28 13:10:16.858 INFO: [296] org.ice4j.ice.Agent.log() Start ICE connectivity establishment. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:16.859 INFO: [296] org.ice4j.ice.Agent.log() Init checklist for stream stream JVB 2020-01-28 13:10:16.859 INFO: [296] org.ice4j.ice.Agent.log() ICE state changed from Waiting to Running. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:16.859 INFO: [296] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=3018688ea979f921,content=data,ch_id=e7456117b5a2df90,endp_id=b10d85df old_state=Waiting,new_state=Running JVB 2020-01-28 13:10:16.860 INFO: [296] org.ice4j.ice.Agent.log() Trigger checks for pairs that were received before running state JVB 2020-01-28 13:10:16.880 INFO: [296] org.ice4j.ice.Agent.log() Add peer CandidatePair with new reflexive address to checkList: CandidatePair (State=Frozen Priority=7961835276064522239): LocalCandidate=candidate:1 1 udp 2130706431 172.17.0.2 10000 typ host RemoteCandidate=candidate:10000 1 udp 1853759231 134.17.26.33 58105 typ prflx JVB 2020-01-28 13:10:16.880 INFO: [296] org.ice4j.ice.ConnectivityCheckClient.log() Start connectivity checks. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:16.894 INFO: [297] org.ice4j.ice.Component.log() Update remote candidate for stream.RTP: 169.132.90.211:61288/udp JVB 2020-01-28 13:10:16.894 INFO: [297] org.ice4j.ice.Component.log() Not adding duplicate remote candidate: 169.132.90.211:61288/udp JVB 2020-01-28 13:10:16.894 INFO: [297] org.ice4j.ice.Component.log() Update remote candidate for stream.RTP: 192.168.230.136:56215/udp JVB 2020-01-28 13:10:16.894 INFO: [297] org.ice4j.ice.Component.log() Not adding duplicate remote candidate: 192.168.230.136:56215/udp JVB 2020-01-28 13:10:16.895 INFO: [297] org.ice4j.ice.Component.log() Update remote candidate for stream.RTP: 169.132.90.211:61288/udp JVB 2020-01-28 13:10:16.895 INFO: [297] org.ice4j.ice.Component.log() Not adding duplicate remote candidate: 169.132.90.211:61288/udp JVB 2020-01-28 13:10:16.895 INFO: [297] org.ice4j.ice.Component.log() Update remote candidate for stream.RTP: 192.168.230.136:56215/udp JVB 2020-01-28 13:10:16.895 INFO: [297] org.ice4j.ice.Component.log() Not adding duplicate remote candidate: 192.168.230.136:56215/udp JVB 2020-01-28 13:10:16.932 INFO: [108] org.jitsi.videobridge.cc.BitrateController.log() new track projection for subjective_quality=0,primary_ssrc=3608324390,secondary_ssrcs={799609032=rtx},temporal_id=0,spatial_id=-1 subjective_quality=1,primary_ssrc=3608324390,secondary_ssrcs={799609032=rtx},temporal_id=1,spatial_id=-1 subjective_quality=2,primary_ssrc=3608324390,secondary_ssrcs={799609032=rtx},temporal_id=2,spatial_id=-1 subjective_quality=3,primary_ssrc=964144837,secondary_ssrcs={1153008126=rtx},temporal_id=0,spatial_id=-1 subjective_quality=4,primary_ssrc=964144837,secondary_ssrcs={1153008126=rtx},temporal_id=1,spatial_id=-1 subjective_quality=5,primary_ssrc=964144837,secondary_ssrcs={1153008126=rtx},temporal_id=2,spatial_id=-1 subjective_quality=6,primary_ssrc=2205793350,secondary_ssrcs={2242215045=rtx},temporal_id=0,spatial_id=-1 subjective_quality=7,primary_ssrc=2205793350,secondary_ssrcs={2242215045=rtx},temporal_id=1,spatial_id=-1 subjective_quality=8,primary_ssrc=2205793350,secondary_ssrcs={2242215045=rtx},temporal_id=2,spatial_id=-1 JVB 2020-01-28 13:10:16.933 WARNING: [108] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 16fec0f2a28 not ready yet. JVB 2020-01-28 13:10:16.939 WARNING: [108] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message JVB 2020-01-28 13:10:16.939 INFO: [108] org.ice4j.ice.Component.log() Update remote candidate for stream.RTP: 169.132.90.211:61288/udp JVB 2020-01-28 13:10:16.939 INFO: [108] org.ice4j.ice.Component.log() Not adding duplicate remote candidate: 169.132.90.211:61288/udp JVB 2020-01-28 13:10:16.941 INFO: [108] org.ice4j.ice.Component.log() Update remote candidate for stream.RTP: 192.168.230.136:56215/udp JVB 2020-01-28 13:10:16.942 INFO: [108] org.ice4j.ice.Component.log() Not adding duplicate remote candidate: 192.168.230.136:56215/udp JVB 2020-01-28 13:10:16.957 INFO: [305] org.ice4j.ice.ConnectivityCheckClient.log() Pair failed: 172.17.0.2:10000/udp/host -> 192.168.230.136:56215/udp/host (stream.RTP) JVB 2020-01-28 13:10:16.974 INFO: [281] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=3018688ea979f921,content=video,ch_id=74135a1f9657289f,endp_id=16fec0f2a28,stream=1278509475 JVB 2020-01-28 13:10:17.000 INFO: [305] org.ice4j.ice.ConnectivityCheckClient.log() Pair failed: 172.17.0.2:10000/udp/host -> 192.168.230.136:56215/udp/host (stream.RTP) JVB 2020-01-28 13:10:17.074 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 172.17.0.2:10000/udp/host -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.074 INFO: [286] org.ice4j.socket.MergingDatagramSocket.log() Adding allowed address: 169.132.90.211:61288/udp JVB 2020-01-28 13:10:17.074 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.075 INFO: [286] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.075 INFO: [286] org.ice4j.ice.Agent.log() verify if nominated pair answer again JVB 2020-01-28 13:10:17.075 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:false. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.082 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 172.17.0.2:10000/udp/host -> 134.17.26.33:58105/udp/prflx (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.082 INFO: [286] org.ice4j.socket.MergingDatagramSocket.log() Adding allowed address: 134.17.26.33:58105/udp JVB 2020-01-28 13:10:17.082 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 54.211.3.253:10000/udp/srflx -> 134.17.26.33:58105/udp/prflx (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.083 INFO: [286] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 54.211.3.253:10000/udp/srflx -> 134.17.26.33:58105/udp/prflx (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.083 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:false. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.102 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 172.17.0.2:10000/udp/host -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.103 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.103 INFO: [286] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.104 INFO: [286] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:false. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.203 INFO: [320] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 172.17.0.2:10000/udp/host -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.204 INFO: [320] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.204 INFO: [320] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.204 INFO: [320] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:false. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.226 INFO: [320] org.ice4j.ice.ConnectivityCheckClient.log() Pair succeeded: 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.226 INFO: [320] org.ice4j.ice.ConnectivityCheckClient.log() Pair validated: 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.226 INFO: [320] org.ice4j.ice.DefaultNominator.log() Nominate (first valid): 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.227 INFO: [320] org.ice4j.ice.ConnectivityCheckClient.log() IsControlling: true USE-CANDIDATE:true. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.227 INFO: [320] org.ice4j.ice.ConnectivityCheckClient.log() Nomination confirmed for pair: 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP). Loal ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.227 INFO: [320] org.ice4j.ice.CheckList.log() Selected pair for stream stream.RTP: 54.211.3.253:10000/udp/srflx -> 169.132.90.211:61288/udp/host (stream.RTP) JVB 2020-01-28 13:10:17.227 INFO: [320] org.ice4j.ice.Agent.log() CheckList of stream stream is COMPLETED JVB 2020-01-28 13:10:17.227 INFO: [320] org.ice4j.ice.Agent.log() ICE state changed from Running to Completed. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:17.228 INFO: [320] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=3018688ea979f921,content=data,ch_id=e7456117b5a2df90,endp_id=b10d85df old_state=Running,new_state=Completed JVB 2020-01-28 13:10:17.228 INFO: [282] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=3018688ea979f921,content=audio,ch_id=3345f0611b73adad,endp_id=b10d85df,stream=882071145 JVB 2020-01-28 13:10:17.230 INFO: [320] org.ice4j.ice.Agent.log() Harvester used for selected pair for stream.RTP (local ufrag a8nk71dvm0uc77): srflx JVB 2020-01-28 13:10:17.246 INFO: [282] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=3018688ea979f921,content=video,ch_id=787d7b23c0d6af85,endp_id=b10d85df,stream=1251759215 JVB 2020-01-28 13:10:17.254 INFO: [282] org.jitsi.videobridge.Channel.log() CAT=stat transport_connected,conf_id=3018688ea979f921,content=data,ch_id=e7456117b5a2df90,endp_id=b10d85df JVB 2020-01-28 13:10:17.290 SEVERE: [330] org.jitsi.sctp4j.Sctp.log() Init'ing brian's patched usrsctp =====>: org_jitsi_sctp4j_Sctp.c calling init =====>: org_jitsi_sctp4j_Sctp.c about to set SCTP_DEBUG_ALL =====>: org_jitsi_sctp4j_Sctp.c setting SCTP_DEBUG_ALL JVB 2020-01-28 13:10:17.444 WARNING: [323] org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.log() Unknown DTLS handshake message type: -9 JVB 2020-01-28 13:10:17.568 WARNING: [323] org.jitsi.impl.neomedia.transform.srtp.OpenSSLWrapperLoader.log() Unable to load jnopenssl: java.lang.UnsatisfiedLinkError: /tmp/jna-105622/jna1846287429705212753.tmp: libcrypto.so.1.0.0: cannot open shared object file: No such file or directory JVB 2020-01-28 13:10:17.589 INFO: [19] org.jitsi.videobridge.Conference.log() CAT=stat ds_change,conf_id=3018688ea979f921 ds_id=b10d85df JVB 2020-01-28 13:10:17.590 WARNING: [19] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 16fec0f2a28 not ready yet. JVB 2020-01-28 13:10:17.590 WARNING: [19] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message JVB 2020-01-28 13:10:17.591 WARNING: [19] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with b10d85df not ready yet. JVB 2020-01-28 13:10:17.591 WARNING: [19] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message JVB 2020-01-28 13:10:17.654 INFO: [323] org.jitsi.impl.neomedia.transform.srtp.AES.log() AES benchmark (of execution times expressed in nanoseconds): BouncyCastle 5914543, SunJCE 6115113, SunPKCS11 4085743 JVB 2020-01-28 13:10:17.655 INFO: [323] org.jitsi.impl.neomedia.transform.srtp.AES.log() Will employ AES implemented by SunPKCS11. JVB 2020-01-28 13:10:17.718 INFO: [313] net.sf.fmj.media.Log.info() Resetting queue, last seq added: 9223372036854775806, current seq: 21199 JVB 2020-01-28 13:10:17.729 INFO: [335] org.jitsi.impl.neomedia.rtp.translator.RTCPFeedbackMessageSender.log() Sending a FIR to ssrc=3608324390 remainingRetries=9 JVB 2020-01-28 13:10:18.014 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@789f1103 JVB 2020-01-28 13:10:18.155 INFO: [313] net.sf.fmj.media.Log.info() Resetting queue, last seq added: 9223372036854775806, current seq: 14511 JVB 2020-01-28 13:10:19.715 INFO: [191] org.ice4j.ice.Agent.log() ICE state changed from Completed to Terminated. Local ufrag 3vdlo1dvm0ubpr JVB 2020-01-28 13:10:19.716 INFO: [191] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=16fec0f2a28 old_state=Completed,new_state=Terminated JVB 2020-01-28 13:10:20.230 INFO: [191] org.ice4j.ice.Agent.log() ICE state changed from Completed to Terminated. Local ufrag a8nk71dvm0uc77 JVB 2020-01-28 13:10:20.231 INFO: [191] org.jitsi.videobridge.IceUdpTransportManager.log() CAT=stat ice_state_change,conf_id=3018688ea979f921,content=data,ch_id=e7456117b5a2df90,endp_id=b10d85df old_state=Completed,new_state=Terminated JVB 2020-01-28 13:10:20.351 INFO: [18] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=5c1e0fad079fea74 conf_name=null,logging=false,conf_count=2,ch_count=5,v_streams=4 JVB 2020-01-28 13:10:20.398 INFO: [18] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 47ms. Sticky failure: false JVB 2020-01-28 13:10:21.206 INFO: [331] org.jitsi.videobridge.SctpConnection.log() SCTP socket accepted on conf_id=3018688ea979f921,content=data,ch_id=e7456117b5a2df90,endp_id=b10d85df JVB 2020-01-28 13:10:21.221 INFO: [331] org.jitsi.videobridge.EndpointMessageTransport.log() WebRTC data channel established for conf_id=3018688ea979f921,content=data,ch_id=e7456117b5a2df90,endp_id=b10d85df JVB 2020-01-28 13:10:21.235 WARNING: [331] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 16fec0f2a28 not ready yet. JVB 2020-01-28 13:10:21.235 WARNING: [331] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message JVB 2020-01-28 13:10:21.236 WARNING: [331] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 16fec0f2a28 not ready yet. JVB 2020-01-28 13:10:21.236 WARNING: [331] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message JVB 2020-01-28 13:10:21.238 WARNING: [331] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 16fec0f2a28 not ready yet. JVB 2020-01-28 13:10:21.238 WARNING: [331] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message JVB 2020-01-28 13:10:21.970 INFO: [297] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:21.973 WARNING: [330] org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.log() Dropping a DTLS packet. This DtlsPacketTransformer has not been started successfully or has been closed. JVB 2020-01-28 13:10:21.975 INFO: [297] org.jitsi.videobridge.Channel.log() CAT=stat expire_ch,conf_id=3018688ea979f921,content=audio,ch_id=3345f0611b73adad,endp_id=b10d85df,stream=882071145 JVB 2020-01-28 13:10:21.975 INFO: [297] org.jitsi.videobridge.RtpChannel.log() CAT=stat expire_ch_stats,conf_id=3018688ea979f921,content=audio,ch_id=3345f0611b73adad,endp_id=b10d85df,stream=882071145 bRecv=19009,bSent=108,pRecv=222,pSent=1,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0 JVB 2020-01-28 13:10:21.976 INFO: [297] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:21.976 INFO: [297] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:21.979 INFO: [297] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:21.980 INFO: [297] org.jitsi.impl.neomedia.rtp.RawPacketCache.log() CAT=stat closed,stream=-1 max_size_bytes=422847,max_size_packets=408,total_hits=0,total_misses=0,total_packets=1256,oldest_hit_ms=0 JVB 2020-01-28 13:10:21.981 INFO: [297] org.jitsi.videobridge.Channel.log() CAT=stat expire_ch,conf_id=3018688ea979f921,content=video,ch_id=787d7b23c0d6af85,endp_id=b10d85df,stream=1251759215 JVB 2020-01-28 13:10:21.981 INFO: [297] org.jitsi.videobridge.RtpChannel.log() CAT=stat expire_ch_stats,conf_id=3018688ea979f921,content=video,ch_id=787d7b23c0d6af85,endp_id=b10d85df,stream=1251759215 bRecv=1251492,bSent=1204,pRecv=1288,pSent=36,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0 JVB 2020-01-28 13:10:21.987 INFO: [292] org.ice4j.socket.MergingDatagramSocket.log() Failed to receive: java.net.SocketException: Socket closed JVB 2020-01-28 13:10:21.987 WARNING: [292] org.ice4j.socket.MergingDatagramSocket.log() Removing the active socket. Won't be able to send until a new one is elected. JVB 2020-01-28 13:10:21.987 INFO: [297] org.ice4j.socket.MergingDatagramSocket.log() Closing. JVB 2020-01-28 13:10:21.988 INFO: [285] org.ice4j.socket.MergingDatagramSocket.log() Failed to receive: java.net.SocketException: Socket closed JVB 2020-01-28 13:10:21.988 WARNING: [19] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 16fec0f2a28 not ready yet. JVB 2020-01-28 13:10:21.990 WARNING: [19] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can't send a message JVB 2020-01-28 13:10:21.990 INFO: [297] org.jitsi.videobridge.Channel.log() CAT=stat expire_ch,conf_id=3018688ea979f921,content=data,ch_id=e7456117b5a2df90,endp_id=b10d85df JVB 2020-01-28 13:10:23.015 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@7fea6486 JVB 2020-01-28 13:10:26.960 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 0 JVB 2020-01-28 13:10:26.961 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 1 JVB 2020-01-28 13:10:26.961 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0 JVB 2020-01-28 13:10:26.961 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0 JVB 2020-01-28 13:10:26.961 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0 JVB 2020-01-28 13:10:26.961 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0 JVB 2020-01-28 13:10:26.962 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0 JVB 2020-01-28 13:10:26.962 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 0 JVB 2020-01-28 13:10:26.962 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 0 JVB 2020-01-28 13:10:26.962 INFO: [303] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled JVB 2020-01-28 13:10:26.962 INFO: [303] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:28.016 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@57f9efa6 JVB 2020-01-28 13:10:28.087 INFO: [17] org.jitsi.videobridge.VideobridgeExpireThread.log() Running expire() JVB 2020-01-28 13:10:29.767 INFO: [297] org.jitsi.videobridge.Channel.log() CAT=stat expire_ch,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=16fec0f2a28,stream=631027340 JVB 2020-01-28 13:10:29.768 INFO: [297] org.jitsi.videobridge.RtpChannel.log() CAT=stat expire_ch_stats,conf_id=3018688ea979f921,content=audio,ch_id=b3307fb6d661ad32,endp_id=16fec0f2a28,stream=631027340 bRecv=0,bSent=19189,pRecv=0,pSent=225,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0 JVB 2020-01-28 13:10:29.769 INFO: [297] org.jitsi.impl.neomedia.rtp.RawPacketCache.log() CAT=stat closed,stream=1278509475 max_size_bytes=10604,max_size_packets=21,total_hits=0,total_misses=0,total_packets=68,oldest_hit_ms=0 JVB 2020-01-28 13:10:29.769 INFO: [297] org.ice4j.socket.MergingDatagramSocket.log() Closing. JVB 2020-01-28 13:10:29.770 INFO: [297] org.jitsi.videobridge.Channel.log() CAT=stat expire_ch,conf_id=3018688ea979f921,content=video,ch_id=74135a1f9657289f,endp_id=16fec0f2a28,stream=1278509475 JVB 2020-01-28 13:10:29.770 INFO: [297] org.jitsi.videobridge.RtpChannel.log() CAT=stat expire_ch_stats,conf_id=3018688ea979f921,content=video,ch_id=74135a1f9657289f,endp_id=16fec0f2a28,stream=1278509475 bRecv=0,bSent=27359,pRecv=0,pSent=75,bRetr=0,bNotRetr=0,pRetr=0,pNotRetr=0,pMiss=0 JVB 2020-01-28 13:10:30.399 INFO: [18] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=d1a35810f8c86538 conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0 JVB 2020-01-28 13:10:30.427 INFO: [18] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 28ms. Sticky failure: false JVB 2020-01-28 13:10:30.770 INFO: [289] org.ice4j.socket.MergingDatagramSocket.log() Failed to receive: java.net.SocketException: Socket closed JVB 2020-01-28 13:10:31.932 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 1 JVB 2020-01-28 13:10:31.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 2 JVB 2020-01-28 13:10:31.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0 JVB 2020-01-28 13:10:31.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0 JVB 2020-01-28 13:10:31.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0 JVB 2020-01-28 13:10:31.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0 JVB 2020-01-28 13:10:31.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0 JVB 2020-01-28 13:10:31.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 1 JVB 2020-01-28 13:10:31.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 4 JVB 2020-01-28 13:10:31.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled JVB 2020-01-28 13:10:31.935 INFO: [315] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:33.017 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@6c1cd95a JVB 2020-01-28 13:10:36.932 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 2 JVB 2020-01-28 13:10:36.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 2 JVB 2020-01-28 13:10:36.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0 JVB 2020-01-28 13:10:36.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0 JVB 2020-01-28 13:10:36.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0 JVB 2020-01-28 13:10:36.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0 JVB 2020-01-28 13:10:36.933 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0 JVB 2020-01-28 13:10:36.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 2 JVB 2020-01-28 13:10:36.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 4 JVB 2020-01-28 13:10:36.934 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled JVB 2020-01-28 13:10:36.934 INFO: [315] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:36.935 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Total packets added: 0 JVB 2020-01-28 13:10:36.935 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times reset() called: 1 JVB 2020-01-28 13:10:36.935 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Times grow() called: 0 JVB 2020-01-28 13:10:36.935 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because full: 0 JVB 2020-01-28 13:10:36.935 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped while shrinking: 0 JVB 2020-01-28 13:10:36.935 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late: 0 JVB 2020-01-28 13:10:36.936 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped because they were late by more than MAX_SIZE: 0 JVB 2020-01-28 13:10:36.936 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Packets dropped in reset(): 0 JVB 2020-01-28 13:10:36.936 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Max size reached: 0 JVB 2020-01-28 13:10:36.936 INFO: [315] net.sf.fmj.media.Log.info() net.sf.fmj.media.rtp.RTPSourceStream Adaptive jitter buffer mode was enabled JVB 2020-01-28 13:10:36.936 INFO: [315] net.sf.fmj.media.Log.info() Stopping RTPSourceStream. JVB 2020-01-28 13:10:38.018 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@6c8bcad3 JVB 2020-01-28 13:10:40.430 INFO: [18] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=9c401b9f8dad65c0 conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0 JVB 2020-01-28 13:10:40.459 INFO: [18] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 29ms. Sticky failure: false JVB 2020-01-28 13:10:43.019 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@40694d90 JVB 2020-01-28 13:10:48.021 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@58750ee4 JVB 2020-01-28 13:10:50.464 INFO: [18] org.jitsi.videobridge.Videobridge.log() CAT=stat create_conf,conf_id=f46d5addd0f763ea conf_name=null,logging=false,conf_count=2,ch_count=0,v_streams=0 JVB 2020-01-28 13:10:50.511 INFO: [18] org.jitsi.videobridge.health.Health.log() Performed a successful health check in 48ms. Sticky failure: false JVB 2020-01-28 13:10:53.023 INFO: [14] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.colibri.ColibriStatsExtension@5229dab9

Where 10.199.245.29 is private IP of jigasi, 34.232.173.75 public IP of jigasi and 35.153.228.129 is public IP of my rtpengine

Can you add these changes to the jigasi config:

And make sure you also have this: net.java.sip.communicator.packetlogging.PACKET_LOGGING_ENABLED=false

Those those change anything?

1 Like

Sure. Will post results here. Thank you

[UPDATED] Sorry, i gave the logs here a little bit early. There is some other changes i have to analyze. But the behavior is different now. I finally got the ACK.

This helped. Now the connection established and I’m getting the ACK, but there is still some problem.
I also have to set
net.java.sip.communicator.impl.protocol.sip .SKIP_REINVITE_ON_FOCUS_CHANGE_PROP=true
as long as my Asterisk doesn’t handle re-invite very well.

But i still have some unexpected errors in my logs

jigasi logs

Jigasi 2020-01-29 13:36:41.067 INFO: [43] org.jitsi.jigasi.SipGateway.incomingCallReceived().188 Incoming call received…
Jigasi 2020-01-29 13:36:42.086 INFO: [70] org.jitsi.jigasi.SipGatewaySession.run().1073 Wait thread cancelled
Jigasi 2020-01-29 13:36:42.096 INFO: [43] org.jitsi.jigasi.JvbConference.setXmppProvider().567 0e762218@meet.jitsi will use ProtocolProviderServiceJabberImpl(0e762218@meet.jitsi (Jabber))
Jigasi 2020-01-29 13:36:42.201 INFO: [71] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().125 Jingle : ON
Jigasi 2020-01-29 13:36:42.204 INFO: [71] org.jitsi.jigasi.JvbConference.registrationStateChanged().612 XMPP (0e762218@meet.jitsi): RegistrationStateChangeEvent[ oldState=Registering; newState=RegistrationState=Registering; reasonCode=-1; reason=null]
Jigasi 2020-01-29 13:36:42.212 INFO: [71] impl.protocol.jabber.ProtocolProviderServiceJabberImpl.authenticated().2535 Authenticated: false
Jigasi 2020-01-29 13:36:42.223 INFO: [71] org.jitsi.jigasi.JvbConference.joinConferenceRoom().653 Joining JVB conference room: 5e2efb07acc2f98052e4d003@muc.meet.jitsi
Jigasi 2020-01-29 13:36:42.259 INFO: [75] impl.protocol.jabber.ChatRoomJabberImpl.joined().1256 5e2efb07acc2f98052e4d003@muc.meet.jitsi/01c74596 has joined the 5e2efb07acc2f98052e4d003@muc.meet.jitsi chat room.
Jigasi 2020-01-29 13:36:42.267 INFO: [75] impl.protocol.jabber.ChatRoomJabberImpl.joined().1256 5e2efb07acc2f98052e4d003@muc.meet.jitsi/focus has joined the 5e2efb07acc2f98052e4d003@muc.meet.jitsi chat room.
Jigasi 2020-01-29 13:36:42.592 INFO: [92] impl.protocol.jabber.IceUdpTransportManager.createIceAgent().346 End gathering harvester within 67 ms
Jigasi 2020-01-29 13:36:43.159 INFO: [92] impl.protocol.jabber.CallPeerMediaHandlerJabberImpl.harvestCandidates().1198 End candidate harvest within 153 ms
Jigasi 2020-01-29 13:36:43.216 INFO: [92] org.jitsi.jigasi.JvbConference.incomingCallReceived().990 Got invite from focus
Jigasi 2020-01-29 13:36:43.393 INFO: [104] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 126=rtpmap:-1 telephone-event/8000; 111=rtpmap:-1 opus/48000/2 fmtp:useinbandfec=1;minptime=10; 103=rtpmap:-1 unknown/90000;
Jigasi 2020-01-29 13:36:43.395 INFO: [104] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides [103->104 ]
Jigasi 2020-01-29 13:36:43.445 INFO: [104] service.protocol.media.CallPeerMediaHandler.start().1960 Starting
Jigasi 2020-01-29 13:36:43.862 INFO: [104] org.jitsi.jigasi.JvbConference.onJvbCallStarted().771 JVB conference call IN_PROGRESS 5e2efb07acc2f98052e4d003@muc.meet.jitsi
Jigasi 2020-01-29 13:36:43.866 INFO: [104] org.jitsi.jigasi.JvbConference.peerStateChanged().1056 0e762218@meet.jitsi JVB peer state: net.java.sip.communicator.service.protocol.CallPeerState:Connected
Jigasi 2020-01-29 13:36:43.867 INFO: [104] org.jitsi.jigasi.JvbConference.advertisePeerSSRCs().298 Peer net.java.sip.communicator.service.protocol.CallPeerState:Connected SSRCs audio: 4100985920 video: null
Jigasi 2020-01-29 13:36:44.086 INFO: [127] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 101=rtpmap:-1 telephone-event/8000;
Jigasi 2020-01-29 13:36:44.086 INFO: [127] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides
Jigasi 2020-01-29 13:36:44.103 INFO: [43] org.jitsi.jigasi.SipGatewaySession.handleCallState().929 Sip call IN_PROGRESS: Call: id=15803014009731150561400 peers=1
Jigasi 2020-01-29 13:36:44.104 INFO: [43] org.jitsi.jigasi.SipGatewaySession.handleCallState().937 SIP call format used: rtpmap:0 PCMU/8000
Jigasi 2020-01-29 13:36:44.111 INFO: [43] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1007 0e762218@meet.jitsi SIP peer state: Connected
Jigasi 2020-01-29 13:36:44.112 INFO: [43] service.protocol.media.CallPeerMediaHandler.start().1960 Starting
Jigasi 2020-01-29 13:36:44.250 INFO: [43] service.protocol.media.TransportManager.sendHolePunchPacket().552 Send NAT hole punch packets
Jigasi 2020-01-29 13:36:44.309 SEVERE: [178] net.sf.fmj.media.Log.error() Unable to handle format: LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
Jigasi 2020-01-29 13:36:44.310 SEVERE: [178] net.sf.fmj.media.Log.error() Failed to prefetch: net.sf.fmj.media.ProcessEngine@1cf4bfbe
Jigasi 2020-01-29 13:36:44.314 SEVERE: [176] net.sf.fmj.media.Log.error() Error: Unable to prefetch net.sf.fmj.media.ProcessEngine@1cf4bfbe

Jigasi 2020-01-29 13:36:44.613 INFO: [43] service.protocol.media.CallPeerMediaHandler.start().1960 Starting
Jigasi 2020-01-29 13:36:46.111 SEVERE: [137] org.jitsi.jigasi.SipGatewaySession.run().864 Stopped receiving RTP for Call: id=15803014009731150561400 peers=1
Jigasi 2020-01-29 13:37:07.900 INFO: [43] org.jitsi.jigasi.SipGatewaySession.handleCallState().942 SIP call ended: CallPeerChangeEvent: type=CallPeerStatusChange oldV=net.java.sip.communicator.service.protocol.CallPeerState:Connected newV=net.java.sip.communicator.service.protocol.CallPeerState:Disconnected for peer=jigasi-user jigasi-user@10.199.244.9;status=Disconnected
Jigasi 2020-01-29 13:37:07.903 INFO: [43] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1007 0e762218@meet.jitsi SIP peer state: Disconnected
Jigasi 2020-01-29 13:37:12.906 INFO: [190] org.jitsi.jigasi.SipGatewaySession.sipCallEnded().552 Sip call ended: Call: id=15803014009731150561400 peers=0
Jigasi 2020-01-29 13:37:12.908 INFO: [190] org.jitsi.jigasi.JvbConference.stop().527 0e762218@meet.jitsi is removing account Jabber:0e762218@meet.jitsi/0e762218
Jigasi 2020-01-29 13:37:12.912 INFO: [190] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().132 Jingle : OFF
Jigasi 2020-01-29 13:37:12.915 INFO: [190] org.jitsi.jigasi.AbstractGateway.notifyCallEnded().141 Removed session for call 0e762218@meet.jitsi
Jigasi 2020-01-29 13:37:12.924 INFO: [193] org.jitsi.jigasi.JvbConference.peerStateChanged().1056 0e762218@meet.jitsi JVB peer state: net.java.sip.communicator.service.protocol.CallPeerState:Disconnected
Jigasi 2020-01-29 13:37:12.934 SEVERE: [107] org.jitsi.impl.neomedia.RTPConnectorOutputStream.log() Failed to send a packet to target /34.232.173.75:10000:java.io.IOException: No active socket.
Jigasi 2020-01-29 13:37:12.937 SEVERE: [193] org.jitsi.impl.neomedia.RTPConnectorOutputStream.log() Failed to send a packet to target /34.232.173.75:10000:java.io.IOException: No active socket.

Thanks a lot for previous help. If you have good advise on that, it would be really helpful too/

So other than the errors, there is no audio or what is the problem?
Cause I have seen these Unable to handle format and Unable to prefetch without causing any issue.
Make sure you have correct codecs configured for the sip side, the xmpp side will be offering opus only and jigasi needs to transcode it.

Audio works fine. There is no problems actually except this logs.

Thank you for help!

So you can ignore them.