Jigasi stopped working after upgrading

Hello,

I’m using your ubuntu repository on my ubuntu 18.04 server. When upgrading jigasi from 1.0.-244 to 1.1-38-g8f3c241-1, I’m unable to join a conference using a phone. I get fast busy and I get this message in the error logs:

2020-01-07 14:24:30.964 INFOS: [171] org.jitsi.jigasi.SipGateway.incomingCallReceived().188 Incoming call received…
2020-01-07 14:24:30.966 INFOS: [172] org.jitsi.jigasi.SipGatewaySession.run().1281 [ctx=15784250709641827059593] Wait thread cancelled
2020-01-07 14:24:30.968 INFOS: [171] org.jitsi.jigasi.JvbConference.setXmppProvider().539 [ctx=15784250709641827059593] Using ProtocolProviderServiceJabberImpl(Jabber:2d999379@conference.mydomain.tld/2d999379)
2020-01-07 14:24:31.038 INFOS: [173] impl.protocol.jabber.OperationSetBasicTelephonyJabberImpl.registrationStateChanged().125 Jingle : ON
2020-01-07 14:24:31.038 INFOS: [173] org.jitsi.jigasi.JvbConference.registrationStateChanged().577 [ctx=15784250709641827059593] Registering XMPP.
2020-01-07 14:24:31.147 INFOS: [173] impl.protocol.jabber.ProtocolProviderServiceJabberImpl.authenticated().2535 Authenticated: false
2020-01-07 14:24:31.149 INFOS: [173] org.jitsi.jigasi.JvbConference.joinConferenceRoom().648 [ctx=15784250709641827059593] Joining JVB conference room: test
2020-01-07 14:24:31.186 GRAVE: [173] org.jitsi.jigasi.JvbConference.inviteFocus().1376 [ctx=15784250709641827059593] Could not invite the focus to the conference
org.jivesoftware.smack.XMPPException$XMPPErrorException: XMPP error reply received from focus.conference.mydomain.tld: XMPPError: not-acceptable - modify
at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:132)
at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:263)
at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:214)
at org.jitsi.jigasi.JvbConference.inviteFocus(JvbConference.java:1370)
at org.jitsi.jigasi.JvbConference.joinConferenceRoom(JvbConference.java:716)
at org.jitsi.jigasi.JvbConference.registrationStateChanged(JvbConference.java:569)
at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:187)
at net.java.sip.communicator.service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged(AbstractProtocolProviderService.java:141)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:1389)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.connectAndLogin(ProtocolProviderServiceJabberImpl.java:970)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.initializeConnectAndLogin(ProtocolProviderServiceJabberImpl.java:795)
at net.java.sip.communicator.impl.protocol.jabber.ProtocolProviderServiceJabberImpl.register(ProtocolProviderServiceJabberImpl.java:500)
at org.jitsi.jigasi.util.RegisterThread.run(RegisterThread.java:59)
2020-01-07 14:24:31.197 INFOS: [178] impl.protocol.jabber.ChatRoomJabberImpl.joined().1256 test@conference.conference.mydomain.tld/focus has joined the test@conference.conference.mydomain.tld chat room.
2020-01-07 14:24:31.211 INFOS: [178] impl.protocol.jabber.ChatRoomJabberImpl.joined().1256 test@conference.conference.mydomain.tld/6c1fae8d has joined the test@conference.conference.mydomain.tld chat room.
2020-01-07 14:24:31.211 INFOS: [178] impl.protocol.jabber.ChatRoomJabberImpl.joined().1256 test@conference.conference.mydomain.tld/2d999379 has joined the test@conference.conference.mydomain.tld chat room.
2020-01-07 14:24:31.322 INFOS: [192] impl.protocol.jabber.IceUdpTransportManager.createIceAgent().254 Auto discovered harvester is null
2020-01-07 14:24:31.323 INFOS: [192] impl.protocol.jabber.IceUdpTransportManager.createIceAgent().346 End gathering harvester within 3 ms
2020-01-07 14:24:31.450 INFOS: [192] impl.protocol.jabber.CallPeerMediaHandlerJabberImpl.harvestCandidates().1198 End candidate harvest within 124 ms
2020-01-07 14:24:31.502 INFOS: [192] org.jitsi.jigasi.JvbConference.incomingCallReceived().1024 [ctx=15784250709641827059593] Got invite from focus
2020-01-07 14:24:31.518 INFOS: [203] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1204 [ctx=15784250709641827059593] SIP peer state: Connecting
2020-01-07 14:24:31.538 INFOS: [205] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1003 Dynamic PT map: 101=rtpmap:-1 telephone-event/8000; 96=rtpmap:-1 opus/48000/2 fmtp:usedtx=1; 98=rtpmap:-1 iLBC/8000; 97=rtpmap:-1 AMR-WB/16000;
2020-01-07 14:24:31.539 INFOS: [205] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides
2020-01-07 14:24:31.546 INFOS: [204] org.jitsi.jigasi.SipGatewaySession.handleCallState().1138 [ctx=15784250709641827059593] SIP call ended: CallPeerChangeEvent: type=CallPeerStatusChange oldV=net.java.sip.communicator.service.protocol.CallPeerState:Connecting newV=net.java.sip.communicator.service.protocol.CallPeerState:Disconnected for peer=ME 5555551234@127.0.0.1;status=Disconnected
2020-01-07 14:24:31.548 INFOS: [206] 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;
2020-01-07 14:24:31.553 INFOS: [206] service.protocol.media.MediaHandler.registerDynamicPTsWithStream().1020 PT overrides [103->104 ]
2020-01-07 14:24:31.553 INFOS: [204] org.jitsi.jigasi.SipGatewaySession.peerStateChanged().1204 [ctx=15784250709641827059593] SIP peer state: Disconnected
2020-01-07 14:24:31.565 INFOS: [206] service.protocol.media.CallPeerMediaHandler.start().1961 Starting
2020-01-07 14:24:31.680 GRAVE: [214] org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.log() Failed to connect this DTLS client to a DTLS server!
java.io.IOException: org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl is closed!
at org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.assertNotClosed(DatagramTransportImpl.java:124)
at org.jitsi.impl.neomedia.transform.dtls.DatagramTransportImpl.send(DatagramTransportImpl.java:477)
at org.bouncycastle.crypto.tls.DTLSRecordLayer.sendRecord(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSRecordLayer.send(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSReliableHandshake$RecordLayerBuffer.sendToRecordLayer(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.writeHandshakeFragment(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.writeMessage(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.resendOutboundFlight(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.receiveMessage(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSReliableHandshake.receiveMessageBody(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSClientProtocol.clientHandshake(Unknown Source)
at org.bouncycastle.crypto.tls.DTLSClientProtocol.connect(Unknown Source)
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.runInConnectThread(DtlsPacketTransformer.java:1006)
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer.access$000(DtlsPacketTransformer.java:40)
at org.jitsi.impl.neomedia.transform.dtls.DtlsPacketTransformer$1.run(DtlsPacketTransformer.java:1272)
2020-01-07 14:24:31.687 INFOS: [206] org.jitsi.jigasi.JvbConference.callStateChanged().1122 [ctx=15784250709641827059593] JVB conference call IN_PROGRESS.

Do I need to change something to my configuration in order to make it compatible with the new version?

Thanks.

What are the jitsi-meet versions that are in use?

ii jitsi-meet 1.0.4101-1 all WebRTC JavaScript video conferences
ii jitsi-meet-prosody 1.0.3729-1 all Prosody configuration for Jitsi Meet
ii jitsi-meet-web 1.0.3729-1 all WebRTC JavaScript video conferences
ii jitsi-meet-web-config 1.0.3729-1 all Configuration for web serving of Jitsi Meet
ii jitsi-videobridge 1126-1 amd64 WebRTC compatible Selective Forwarding Unit (SFU)

Hello,

No idea about my problem? It there anything else I can add to help?

Thanks.

Yeah, I’m not sure problem connecting the DTLS seems strange … seems like a problem between jvb and jigasi that’s why I asked, but we are running jvb 1126 in production so that should not be a problem.
Also “XMPPError: not-acceptable - modify” seems strange, can you check at the same time about logs in jicofo.

Hello,

jicofo logs:

Jicofo 2020-01-10 10:44:04.689 INFOS: [60] org.jitsi.jicofo.xmpp.FocusComponent.handleConferenceIq().401 Focus request for room: test@conference.conference.domain.tld
Jicofo 2020-01-10 10:44:04.720 INFOS: [54] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberJoined sourceRoom=or
g.jitsi.impl.protocol.xmpp.ChatRoomImpl@e916b7e member=ChatMember[test@conference.conference.domain.tld/186b4953, jid: null]@1956299791]
Jicofo 2020-01-10 10:44:04.721 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member test@conference.conference.domain.tld/186b4953 joined.
Jicofo 2020-01-10 10:44:04.739 INFOS: [54] org.jitsi.impl.protocol.xmpp.colibri.OperationSetColibriConferenceImpl.log() Conference created: org.jitsi.impl.protocol.xmpp.co
libri.ColibriConferenceImpl@4ced4a20
Jicofo 2020-01-10 10:44:04.743 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Added participant jid= test@conference.conference.domain.tld/72291bca, bridge=jitsi-v
ideobridge.conference.domain.tld
Jicofo 2020-01-10 10:44:04.744 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Region info, conference=ff3595 octo_enabled= false: [[null, null]]
Jicofo 2020-01-10 10:44:04.745 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Added participant jid= test@conference.conference.domain.tld/186b4953, bridge=jitsi-v
ideobridge.conference.domain.tld
Jicofo 2020-01-10 10:44:04.747 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Region info, conference=ff3595 octo_enabled= false: [[null, null, null]]
Jicofo 2020-01-10 10:44:04.766 INFOS: [75] org.jitsi.jicofo.AbstractChannelAllocator.log() Using jitsi-videobridge.conference.domain.tld to allocate channels for: Participant$
endpointId=186b4953]
Jicofo 2020-01-10 10:44:05.118 INFOS: [88] org.jitsi.jicofo.AbstractChannelAllocator.log() Using jitsi-videobridge.conference.domain.tld to allocate channels for: Participant$
endpointId=72291bca]
Jicofo 2020-01-10 10:44:05.120 INFOS: [57] org.jitsi.jicofo.Bridge.log() Adding 1 video streams on jitsi-videobridge.conference.domain.tld video streams: 0 diff: 1 (estimated$
1)
Jicofo 2020-01-10 10:44:05.568 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Received session-accept from 72291bca with accepted sources:Sources{ video: [ssr$
=234345269 ssrc=454418411 ssrc=2753286514 ssrc=2622643840 ssrc=3704544713 ssrc=3752003390 ] audio: [ssrc=3187698963 ] }@837112273
Jicofo 2020-01-10 10:44:05.578 AVERTISSEMENT: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() No jingle session yet for 186b4953
Jicofo 2020-01-10 10:44:06.288 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Ignored ‘ice-state’ update for participant: test@conference.conference.domain.tld/18$
b4953 state: null
Jicofo 2020-01-10 10:44:06.577 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Received session-accept from 186b4953 with accepted sources:Sources{ audio: [ssr$
=2579947362 ] }@822454608
Jicofo 2020-01-10 10:44:06.581 INFOS: [54] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.sendAddSourceIQ().478 Notify add SSRC test@conference.conference.domain.tld/7229$
bca SID: 47vib31m12331 Sources{ video: audio: [ssrc=2579947362 ] }@765344527 source_Groups{ }@1570756979
Jicofo 2020-01-10 10:44:06.584 INFOS: [54] org.jitsi.jicofo.LipSyncHack.log() Not merging A/V streams from test@conference.conference.domain.tld/72291bca to test@conference.c$
nference.domain.tld/186b4953
Jicofo 2020-01-10 10:44:06.585 INFOS: [54] org.jitsi.jicofo.LipSyncHack.log() Not merging A/V streams from test@conference.conference.domain.tld/72291bca to test@conference.c$
nference.domain.tld/186b4953
Jicofo 2020-01-10 10:44:06.585 INFOS: [54] org.jitsi.jicofo.LipSyncHack.log() Not merging A/V streams from test@conference.conference.domain.tld/72291bca to test@conference.c$
nference.domain.tld/186b4953
Jicofo 2020-01-10 10:44:06.586 INFOS: [54] org.jitsi.jicofo.LipSyncHack.log() Not merging A/V streams from test@conference.conference.domain.tld/72291bca to test@conference.co
nference.domain.tld/186b4953
Jicofo 2020-01-10 10:44:06.586 INFOS: [54] org.jitsi.jicofo.LipSyncHack.log() Not merging A/V streams from test@conference.conference.domain.tld/72291bca to test@conference.co
nference.domain.tld/186b4953
Jicofo 2020-01-10 10:44:06.588 INFOS: [54] org.jitsi.jicofo.LipSyncHack.log() Not merging A/V streams from test@conference.conference.domain.tld/72291bca to test@conference.co
nference.domain.tld/186b4953
Jicofo 2020-01-10 10:44:06.589 INFOS: [54] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.sendAddSourceIQ().478 Notify add SSRC test@conference.conference.domain.tld/186b4
953 SID: efgohl3q0ag78 Sources{ audio: [ssrc=3187698963 ] video: [ssrc=234345269 ssrc=454418411 ssrc=2753286514 ssrc=2622643840 ssrc=3704544713 ssrc=3752003390 ] }@1707177
554 source_Groups{ video:[ SourceGroup(FID)[ ssrc=234345269 ssrc=454418411 ]SourceGroup(FID)[ ssrc=2753286514 ssrc=3704544713 ]SourceGroup(FID)[ ssrc=2622643840 ssrc=37520
03390 ]SourceGroup(SIM)[ ssrc=234345269 ssrc=2753286514 ssrc=2622643840 ] ] }@970686374
Jicofo 2020-01-10 10:44:11.377 INFOS: [54] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberLeft sourceRoom=org.
jitsi.impl.protocol.xmpp.ChatRoomImpl@e916b7e member=ChatMember[test@conference.conference.domain.tld/186b4953, jid: jitsimeet@conference.domain.tld/186b4953]@1956299791]
Jicofo 2020-01-10 10:44:11.378 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member test@conference.conference.domain.tld/186b4953 is leaving
Jicofo 2020-01-10 10:44:11.381 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Terminating: test@conference.conference.domain.tld/186b4953
Jicofo 2020-01-10 10:44:11.381 INFOS: [54] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.terminateSession().620 Terminate session: test@conference.conference.domain.tld/1
86b4953
Jicofo 2020-01-10 10:44:11.384 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removing test@conference.conference.domain.tld/186b4953 sources Sources{ audio: [ssrc
=2579947362 ] }@434537943
Jicofo 2020-01-10 10:44:11.387 INFOS: [54] org.jitsi.protocol.xmpp.AbstractOperationSetJingle.sendRemoveSourceIQ().581 Notify remove SSRC test@conference.conference.domain.tld
/72291bca SID: 47vib31m12331 Sources{ audio: [ssrc=2579947362 ] }@434537943 source_Groups{ }@1010409741
Jicofo 2020-01-10 10:44:11.388 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Region info, conference=ff3595 octo_enabled= false: [[null, null]]
Jicofo 2020-01-10 10:44:11.388 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Expiring channels for: test@conference.conference.domain.tld/186b4953 on: Bridge[jid=
jitsi-videobridge.conference.domain.tld, relayId=null, region=null]
Jicofo 2020-01-10 10:44:11.390 INFOS: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Removed participant: true, test@conference.conference.domain.tld/186b4953

Hello,

Still no idea?

My installation is in a dedicated VM, so I rolled-back a backup when I found that my last update broke my installation. Then, I made a snapshot and installed a package at a time to find that the problem is when I upgrade the jigasi package. At this time, all packages are up to date, except jigasi and my installation work fine. If I install the latest jigasi package, my installation broke again.