~10 seconds of video, then the bridge drops


#1

Hi all,

I’m having a weird issue with jitsi-meet. Implementation details:

-MS Azure VM running Ubuntu 18.04
-Full jitsi-meet installation using quick install
-Letsencrypt cert

I can set up a new bridge, and I can connect two clients to it (my implementation only needs to support two clients at a time), and they both seem to be able to send and receive video once it’s up and running (I’m not getting audio at the moment, but that’s a problem for another time).

Anyway, I see video bidirectionally for about 10 seconds, but then the bridge abruptly drops. Here are the relevant jvb logs (I grepped out the INFO and FINE logs, but if anyone wants to see them, I have them):

JVB 2018-08-16 23:35:14.098 WARNING: [54] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 30b7878e not ready yet.
JVB 2018-08-16 23:35:14.098 WARNING: [54] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can’t send a message
JVB 2018-08-16 23:35:14.120 WARNING: [54] org.jitsi.xmpp.component.ComponentBase.verifyProcessingTime().540 PROCESSING TIME LIMIT EXCEEDED - it took 945ms to process: […XML Stuff I can’t post because it includes “links”…]
JVB 2018-08-16 23:35:27.329 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 193dfe89 not ready yet.
JVB 2018-08-16 23:35:27.329 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can’t send a message
JVB 2018-08-16 23:35:27.330 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 30b7878e not ready yet.
JVB 2018-08-16 23:35:27.330 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can’t send a message
JVB 2018-08-16 23:35:27.829 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 193dfe89 not ready yet.
JVB 2018-08-16 23:35:27.829 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can’t send a message
JVB 2018-08-16 23:35:27.829 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() SCTP connection with 30b7878e not ready yet.
JVB 2018-08-16 23:35:27.829 WARNING: [37] org.jitsi.videobridge.EndpointMessageTransport.log() No available transport channel, can’t send a message
JVB 2018-08-16 23:35:33.560 WARNING: [347723] org.jitsi.videobridge.IceUdpTransportManager.log() Cannot get transport type.

For reference: my firewall’s open on tcp/443, tcp/4443, tcp/80, and udp/10000-20000. I recently followed some advice I located on Google about NAT, adding the following to my /etc/jicofo/sip-communicator.properties (to no avail):

org.ice4j.ice.harvest.NAT_HARVESTER_LOCAL_ADDRESS=My.Private.IP.Address
org.ice4j.ice.harvest.NAT_HARVESTER_PUBLIC_ADDRESS=My.Public.IP.Address

I’m a little bamboozled w/r/t why this is happening. Does anyone have any suggestions?


#2

Do you see any exceptions in jicofo logs around the time you see this in jvb logs?


#3

Not exceptions, no–the conversation looks pretty normal from jicofo as far as I can tell:

Jicofo 2018-08-16 23:35:11.756 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 jabber/protocol/caps
Jicofo 2018-08-16 23:35:11.757 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 jitsi/meet/lipsync
Jicofo 2018-08-16 23:35:11.757 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:ietf:rfc:4588
Jicofo 2018-08-16 23:35:11.757 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:ietf:rfc:5761
Jicofo 2018-08-16 23:35:11.757 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:ietf:rfc:5888
Jicofo 2018-08-16 23:35:11.757 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:1
Jicofo 2018-08-16 23:35:11.775 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:dtls:0
Jicofo 2018-08-16 23:35:11.776 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:rtp:1
Jicofo 2018-08-16 23:35:11.796 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:rtp:audio
Jicofo 2018-08-16 23:35:11.796 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:rtp:video
Jicofo 2018-08-16 23:35:11.796 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:transports:dtls-sctp:1
Jicofo 2018-08-16 23:35:11.814 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:transports:ice-udp:1
Jicofo 2018-08-16 23:35:11.814 INFO: [81] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:rayo:client:1
Jicofo 2018-08-16 23:35:11.833 INFO: [81] org.jitsi.jicofo.AbstractChannelAllocator.log() Using jitsi-videobridge.my-website to allocate channels for: [Participant endpointId=193dfe89
Jicofo 2018-08-16 23:35:11.847 INFO: [56] org.jitsi.jicofo.Bridge.log() Adding 1 video streams on jitsi-videobridge.my-website video streams: 0 diff: 1 (estimated: 1)
Jicofo 2018-08-16 23:35:11.966 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().146 funnysheepdevelopregularly@ conference.my-website/30b7878e, features:
Jicofo 2018-08-16 23:35:11.966 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 jabber/protocol/caps
Jicofo 2018-08-16 23:35:11.966 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 jitsi/meet/lipsync
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:ietf:rfc:4588
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:ietf:rfc:5761
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:ietf:rfc:5888
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:1
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:dtls:0
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:rtp:1
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:rtp:audio
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:apps:rtp:video
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:transports:dtls-sctp:1
Jicofo 2018-08-16 23:35:11.967 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:jingle:transports:ice-udp:1
Jicofo 2018-08-16 23:35:11.968 INFO: [103] org.jitsi.jicofo.discovery.DiscoveryUtil.discoverParticipantFeatures().149 urn:xmpp:rayo:client:1
Jicofo 2018-08-16 23:35:11.968 INFO: [103] org.jitsi.jicofo.AbstractChannelAllocator.log() Using jitsi-videobridge.my-website to allocate channels for: [Participant endpointId=30b7878e
Jicofo 2018-08-16 23:35:11.978 INFO: [56] org.jitsi.jicofo.Bridge.log() Adding 3 video streams on jitsi-videobridge.my-website video streams: 0 diff: 4 (estimated: 4)
Jicofo 2018-08-16 23:35:12.439 INFO: [81] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.initiateSession().130 INVITE PEER: funnysheepdevelopregularly@ conference.my-website/193dfe89
Jicofo 2018-08-16 23:35:12.637 INFO: [103] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.initiateSession().130 INVITE PEER: funnysheepdevelopregularly@ conference.my-website30b7878e
Jicofo 2018-08-16 23:35:13.130 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Received session-accept from 193dfe89 with accepted sources:Sources{ video: [ssrc=3968917813 ssrc=4078938138 ssrc=3282798899 ssrc=232434561 ssrc=1291677694 ssrc=4194558155 ] audio: [ssrc=1728534150 ] }@ 9751371
Jicofo 2018-08-16 23:35:13.201 WARNING: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() No jingle session yet for 30b7878e
Jicofo 2018-08-16 23:35:13.430 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Received session-accept from 30b7878e with accepted sources:Sources{ video: [ssrc=3708194476 ssrc=1228456124 ssrc=1387346774 ssrc=2388241325 ssrc=4056546514 ssrc=3573335008 ] audio: [ssrc=989128294 ] }@ 785035120
Jicofo 2018-08-16 23:35:13.549 INFO: [37] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.sendAddSourceIQ().455 Notify add SSRC funnysheepdevelopregularly@ conference.my-website/193dfe89 SID: eh2g0j1974225 Sources{ video: [ssrc=3708194476 ssrc=1228456124 ssrc=1387346774 ssrc=2388241325 ssrc=4056546514 ssrc=3573335008 ] audio: [ssrc=989128294 ] }@ 611945685 source_Groups{ video:[ SourceGroup(FID)[ ssrc=3708194476 ssrc=1228456124 ]SourceGroup(FID)[ ssrc=1387346774 ssrc=4056546514 ]SourceGroup(FID)[ ssrc=2388241325 ssrc=3573335008 ]SourceGroup(SIM)[ ssrc=3708194476 ssrc=1387346774 ssrc=2388241325 ] ] }@ 326850164
Jicofo 2018-08-16 23:35:13.627 INFO: [37] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.sendAddSourceIQ().455 Notify add SSRC funnysheepdevelopregularly@ conference.my-website/30b7878e SID: 2l8kube9vejik Sources{ audio: [ssrc=1728534150 ] video: [ssrc=3968917813 ssrc=4078938138 ssrc=3282798899 ssrc=232434561 ssrc=1291677694 ssrc=4194558155 ] }@ 1748093508 source_Groups{ video:[ SourceGroup(FID)[ ssrc=3968917813 ssrc=4078938138 ]SourceGroup(FID)[ ssrc=3282798899 ssrc=1291677694 ]SourceGroup(FID)[ ssrc=232434561 ssrc=4194558155 ]SourceGroup(SIM)[ ssrc=3968917813 ssrc=3282798899 ssrc=232434561 ] ] }@ 535748820
Jicofo 2018-08-16 23:35:33.382 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberLeft sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@ 60b08adf member=ChatMember[funnysheepdevelopregularly@ conference.my-website/193dfe89, jid: null]@ 680184111]
Jicofo 2018-08-16 23:35:33.382 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member funnysheepdevelopregularly@ conference.my-website/193dfe89 is leaving
Jicofo 2018-08-16 23:35:33.383 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Terminating: funnysheepdevelopregularly@ conference.my-website/193dfe89
Jicofo 2018-08-16 23:35:33.383 INFO: [37] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.terminateSession().597 Terminate session: funnysheepdevelopregularly@ conference.my-website/193dfe89
Jicofo 2018-08-16 23:35:33.448 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removing funnysheepdevelopregularly@ conference.my-website/193dfe89 sources Sources{ video: [ssrc=3968917813 ssrc=4078938138 ssrc=3282798899 ssrc=232434561 ssrc=1291677694 ssrc=4194558155 ] audio: [ssrc=1728534150 ] }@ 1194453650
Jicofo 2018-08-16 23:35:33.451 INFO: [37] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.sendRemoveSourceIQ().558 Notify remove SSRC funnysheepdevelopregularly@ conference.my-website/30b7878e SID: 2l8kube9vejik Sources{ video: [ssrc=3968917813 ssrc=4078938138 ssrc=3282798899 ssrc=232434561 ssrc=1291677694 ssrc=4194558155 ] audio: [ssrc=1728534150 ] }@ 1194453650 source_Groups{ video:[ SourceGroup(FID)[ ssrc=3968917813 ssrc=4078938138 ]SourceGroup(FID)[ ssrc=3282798899 ssrc=1291677694 ]SourceGroup(FID)[ ssrc=232434561 ssrc=4194558155 ]SourceGroup(SIM)[ ssrc=3968917813 ssrc=3282798899 ssrc=232434561 ] ] }@ 1805933830
Jicofo 2018-08-16 23:35:33.469 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Region info, conference=ffad11: [[null, null]]
Jicofo 2018-08-16 23:35:33.469 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Expiring channels for: funnysheepdevelopregularly@ conference.my-website/193dfe89
Jicofo 2018-08-16 23:35:33.485 INFO: [56] org.jitsi.jicofo.Bridge.log() Removing 3 video streams on jitsi-videobridge.my-website video streams: 0 diff: 1 (estimated: 1)
Jicofo 2018-08-16 23:35:33.485 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removed participant: true, funnysheepdevelopregularly@ conference.my-website/193dfe89
Jicofo 2018-08-16 23:35:33.914 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberLeft sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@ 60b08adf member=ChatMember[funnysheepdevelopregularly@ conference.my-website/30b7878e, jid: 30b7878e-8417-4299-8171-b280f57082dd@ my-website/4ad10ca8-f58b-475d-8360-3ea2fd00f3dd]@ 113615730]
Jicofo 2018-08-16 23:35:33.915 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Owner has left the room !
Jicofo 2018-08-16 23:35:33.915 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member funnysheepdevelopregularly@ conference.my-website/30b7878e is leaving
Jicofo 2018-08-16 23:35:33.915 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Terminating: funnysheepdevelopregularly@ conference.my-website/30b7878e
Jicofo 2018-08-16 23:35:33.916 INFO: [37] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.terminateSession().597 Terminate session: funnysheepdevelopregularly@ conference.my-website/30b7878e
Jicofo 2018-08-16 23:35:33.925 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removing funnysheepdevelopregularly@ conference.my-website/30b7878e sources Sources{ video: [ssrc=3708194476 ssrc=1228456124 ssrc=1387346774 ssrc=2388241325 ssrc=4056546514 ssrc=3573335008 ] audio: [ssrc=989128294 ] }@ 1793706768
Jicofo 2018-08-16 23:35:33.925 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Region info, conference=ffad11: [[null]]
Jicofo 2018-08-16 23:35:33.925 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Expiring channels for: funnysheepdevelopregularly@ conference.my-website/30b7878e
Jicofo 2018-08-16 23:35:33.927 INFO: [56] org.jitsi.jicofo.Bridge.log() Removing 1 video streams on jitsi-videobridge.my-website video streams: 0 diff: 0 (estimated: 0)
Jicofo 2018-08-16 23:35:33.927 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removed participant: true, funnysheepdevelopregularly@ conference.my-website/30b7878e
Jicofo 2018-08-16 23:35:33.973 INFO: [37] org.jitsi.jicofo.FocusManager.log() Disposed conference for room: funnysheepdevelopregularly@ conference.my-website conference count: 0