Jibri SEVERE: Name or service not found


#1

Hello,

after installing the Jitsi Meet server, tested the video rooms and worked properly. However, after configuring the Jibri service in another server it fails to connect and the Web App displays error “Recording Unavailable”

Attached Jicofo’s and Jibri’s logs and configuration files.

Jibri SEVERE error:

2018-12-04 16:18:42.139 SEVERE: [1] org.jitsi.jibri.api.xmpp.XmppApi.start() Error connecting to xmpp environment: org.jivesoftware.smack.SmackException$ConnectionException: The following addresses failed: 'prod.meet.lacajanegra.mx:5222' failed because: java.net.UnknownHostException: prod.meet.lacajanegra.mx: Name or service not known

For wich the Jitsi Meet’s firewall status is the following

root@ubuntu-jitsi:~# ufw status verbose
Status: active
Logging: on (low)
Default: deny (incoming), allow (outgoing), disabled (routed)
New profiles: skip

To                         Action      From
--                         ------      ----
22/tcp                     ALLOW IN    Anywhere
80/tcp                     ALLOW IN    Anywhere
443/tcp                    ALLOW IN    Anywhere
10000:20000/udp            ALLOW IN    Anywhere
5222                       ALLOW IN    Anywhere
22/tcp (v6)                ALLOW IN    Anywhere (v6)
80/tcp (v6)                ALLOW IN    Anywhere (v6)
443/tcp (v6)               ALLOW IN    Anywhere (v6)
10000:20000/udp (v6)       ALLOW IN    Anywhere (v6)
5222 (v6)                  ALLOW IN    Anywhere (v6)

Jicofo SEVERE error:

Jicofo 2018-12-03 19:31:49.188 SEVERE: [25] org.jitsi.jicofo.xmpp.BaseBrewery.start().196 Failed to create room: JibriBrewery@internal.auth.meet.lacajanegra.mx
net.java.sip.communicator.service.protocol.OperationFailedException: Failed to join the room
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.joinAs(ChatRoomImpl.java:298)
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.join(ChatRoomImpl.java:209)
	at org.jitsi.jicofo.xmpp.BaseBrewery.start(BaseBrewery.java:190)
	at org.jitsi.jicofo.xmpp.BaseBrewery.maybeStart(BaseBrewery.java:141)
	at org.jitsi.jicofo.xmpp.BaseBrewery.registrationStateChanged(BaseBrewery.java:166)
	at org.jitsi.jicofo.ProtocolProviderHandler.registrationStateChanged(ProtocolProviderHandler.java:141)
	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 org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.notifyConnected(XmppProtocolProvider.java:345)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.access$100(XmppProtocolProvider.java:62)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider$XmppConnectionListener.authenticated(XmppProtocolProvider.java:633)
	at org.jivesoftware.smack.AbstractXMPPConnection.callConnectionAuthenticatedListener(AbstractXMPPConnection.java:1263)
	at org.jivesoftware.smack.AbstractXMPPConnection.afterSuccessfulLogin(AbstractXMPPConnection.java:575)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection.afterSuccessfulLogin(XMPPTCPConnection.java:379)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection.loginInternal(XMPPTCPConnection.java:444)
	at org.jivesoftware.smack.AbstractXMPPConnection.login(AbstractXMPPConnection.java:494)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.doConnect(XmppProtocolProvider.java:297)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.access$000(XmppProtocolProvider.java:62)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider$1.call(XmppProtocolProvider.java:256)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider$1.call(XmppProtocolProvider.java:251)
	at org.jitsi.retry.RetryStrategy$TaskRunner.run(RetryStrategy.java:193)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
Caused by: org.jivesoftware.smack.XMPPException$XMPPErrorException: XMPP error reply received from internal.auth.meet.lacajanegra.mx: XMPPError:     remote-server-not-found - cancel
	at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:131)
	at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:256)
	at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:208)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.discoverInfo(ServiceDiscoveryManager.java:542)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.discoverInfo(ServiceDiscoveryManager.java:508)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.supportsFeatures(ServiceDiscoveryManager.java:738)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.supportsFeatures(ServiceDiscoveryManager.java:734)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.supportsFeature(ServiceDiscoveryManager.java:730)
	at org.jivesoftware.smackx.muc.MultiUserChatManager.providesMucService(MultiUserChatManager.java:360)
	at org.jivesoftware.smackx.muc.MultiUserChat.enter(MultiUserChat.java:311)
	at org.jivesoftware.smackx.muc.MultiUserChat.createOrJoin(MultiUserChat.java:498)
	at org.jivesoftware.smackx.muc.MultiUserChat.createOrJoin(MultiUserChat.java:444)
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.joinAs(ChatRoomImpl.java:240)
	... 27 more

Jicofo 2018-12-03 22:00:37.412 SEVERE: [123] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start

Jitsi config

var config = {
    hosts: {
        // XMPP domain.
        domain: 'meet.lacajanegra.mx',

        // XMPP MUC domain. FIXME: use XEP-0030 to discover it.
        muc: 'conference.meet.lacajanegra.mx'
    },

    // BOSH URL. FIXME: use XEP-0156 to discover it.
    bosh: '//meet.lacajanegra.mx/http-bind',

    // The name of client node advertised in XEP-0115 'c' stanza
    clientNode: 'http://jitsi.org/jitsimeet',
    testing: {
        // Enables experimental simulcast support on Firefox.
        enableFirefoxSimulcast: false,
        p2pTestMode: false
    },

    disableSuspendVideo: true,
    desktopSharingChromeExtId: null,

    // Whether desktop sharing should be disabled on Chrome.
    desktopSharingChromeDisabled: true,

    // The media sources to use when using screen sharing with the Chrome
    // extension.
    desktopSharingChromeSources: [ 'screen', 'window', 'tab' ],

    // Required version of Chrome extension
    desktopSharingChromeMinExtVersion: '0.1',

    // Whether desktop sharing should be disabled on Firefox.
    desktopSharingFirefoxDisabled: false,
    fileRecordingsEnabled: true,

    // Whether to enable live streaming or not.
    liveStreamingEnabled: true,

    hiddenDomain: 'recorder.meet.lacajanegra.mx',
    channelLastN: -1,

    enableUserRolesBasedOnToken: false,
    p2p: {
        enabled: true,

        stunServers: [
            { urls: 'stun:stun.l.google.com:19302' },
            { urls: 'stun:stun1.l.google.com:19302' },
            { urls: 'stun:stun2.l.google.com:19302' }
        ],
        preferH264: true
    },

    deploymentInfo: {
    }

};    

Jibri config

{
    // NOTE: this is a *SAMPLE* config file, it will need to be configured with
    // values from your environment

    // Where recording files should be temporarily stored
    "recording_directory":"/srv/recordings",
    // The path to the script which will be run on completed recordings
    "finalize_recording_script_path": "/path/to/finalize_recording.sh",
    "xmpp_environments": [
        {
            // A friendly name for this environment which can be used
            //  for logging, stats, etc.
            "name": "prod environment",
            // The hosts of the XMPP servers to connect to as part of
            //  this environment
            "xmpp_server_hosts": [
                "prod.meet.lacajanegra.mx"
            ],
            // The xmpp domain we'll connect to on the XMPP server
            "xmpp_domain": "meet.lacajanegra.mx",
            // Jibri will login to the xmpp server as a privileged user 
            "control_login": {
                // The domain to use for logging in
                "domain": "auth.meet.lacajanegra.mx",
                // The credentials for logging in
                "username": "jibri",
                "password": "XXXXXXX"
            },
            // Using the control_login information above, Jibri will join 
            //  a control muc as a means of announcing its availability 
            //  to provide services for a given environment
            "control_muc": {
                "domain": "internal.auth.meet.lacajanegra.mx",
                "room_name": "JibriBrewery",
                "nickname": "jibri-nickname"
            },
            // All participants in a call join a muc so they can exchange
            //  information.  Jibri can be instructed to join a special muc
            //  with credentials to give it special abilities (e.g. not being
            //  displayed to other users like a normal participant)
            "call_login": {
                "domain": "recorder.meet.lacajanegra.mx",
                "username": "recorder",
                "password": "XXXXXXX"
            },
            // When jibri gets a request to start a service for a room, the room
            //  jid will look like:
            //  roomName@optional.prefixes.subdomain.xmpp_domain
            // We'll build the url for the call by transforming that into:
            //  https://xmpp_domain/subdomain/roomName
            // So if there are any prefixes in the jid (like jitsi meet, which
            //  has its participants join a muc at conference.xmpp_domain) then
            //  list that prefix here so it can be stripped out to generate
            //  the call url correctly
            "room_jid_domain_string_to_strip_from_start": "conference.",
            // The amount of time, in minutes, a service is allowed to continue.
            //  Once a service has been running for this long, it will be
            //  stopped (cleanly).  A value of 0 means an indefinite amount
            //  of time is allowed
            "usage_timeout": "0"
        }
    ]
}

Full logs below

Jibri

2018-12-04 16:18:38.532 INFO: [1] org.jitsi.jibri.Main.main() Using config file /etc/jitsi/jibri/config.json
2018-12-04 16:18:39.808 INFO: [1] org.jitsi.jibri.Main.loadConfig() Parsed config:
JibriConfig(recordingDirectory=/srv/recordings, enabledStatsD=true, finalizeRecordingScriptPath=/path/to/finalize_recording.sh, xmppEnvironments=[XmppEnvironmentConfig(name=prod environment, xmppServerHosts=[prod.meet.lacajanegra.mx], xmppDomain=meet.lacajanegra.mx, controlLogin=XmppCredentials(domain=auth.meet.lacajanegra.mx, username=jibri, password=jibriauthpass), controlMuc=XmppMuc(domain=internal.auth.meet.lacajanegra.mx, roomName=JibriBrewery, nickname=jibri-nickname), sipControlMuc=null, callLogin=XmppCredentials(domain=recorder.meet.lacajanegra.mx, username=recorder, password=jibrirecorderpass), stripFromRoomDomain=conference., usageTimeoutMins=0, trustAllXmppCerts=true)])
2018-12-04 16:18:40.920 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime() A provider org.jitsi.jibri.api.http.internal.InternalHttpApi registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.jibri.api.http.internal.InternalHttpApi will be ignored. 
2018-12-04 16:18:41.288 WARNING: [1] org.glassfish.jersey.internal.Errors.logErrors() The following warnings have been detected: WARNING: HK2 service reification failed for [org.glassfish.jersey.message.internal.DataSourceProvider] with an exception:
MultiException stack 1 of 2
java.lang.NoClassDefFoundError: javax/activation/DataSource
    at java.base/java.lang.Class.getDeclaredConstructors0(Native Method)
    at java.base/java.lang.Class.privateGetDeclaredConstructors(Class.java:3090)
    at java.base/java.lang.Class.getDeclaredConstructors(Class.java:2316)
    at org.jvnet.hk2.internal.Utilities$3.run(Utilities.java:1378)
    at org.jvnet.hk2.internal.Utilities$3.run(Utilities.java:1374)
    at java.base/java.security.AccessController.doPrivileged(Native Method)
    at org.jvnet.hk2.internal.Utilities.getAllConstructors(Utilities.java:1374)
    at org.jvnet.hk2.internal.Utilities.findProducerConstructor(Utilities.java:1317)
    at org.jvnet.hk2.internal.DefaultClassAnalyzer.getConstructor(DefaultClassAnalyzer.java:83)
    at org.glassfish.jersey.inject.hk2.JerseyClassAnalyzer.getConstructor(JerseyClassAnalyzer.java:148)
    at org.jvnet.hk2.internal.Utilities.getConstructor(Utilities.java:180)
    at org.jvnet.hk2.internal.ClazzCreator.initialize(ClazzCreator.java:129)
    at org.jvnet.hk2.internal.ClazzCreator.initialize(ClazzCreator.java:180)
    at org.jvnet.hk2.internal.SystemDescriptor.internalReify(SystemDescriptor.java:740)
    at org.jvnet.hk2.internal.SystemDescriptor.reify(SystemDescriptor.java:694)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.reifyDescriptor(ServiceLocatorImpl.java:464)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.narrow(ServiceLocatorImpl.java:2310)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.access$1200(ServiceLocatorImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1395)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1390)
    at org.glassfish.hk2.utilities.cache.internal.WeakCARCacheImpl.compute(WeakCARCacheImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.internalGetAllServiceHandles(ServiceLocatorImpl.java:1452)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1377)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1366)
    at org.glassfish.jersey.inject.hk2.AbstractHk2InjectionManager.getAllServiceHolders(AbstractHk2InjectionManager.java:158)
    at org.glassfish.jersey.inject.hk2.ImmediateHk2InjectionManager.getAllServiceHolders(ImmediateHk2InjectionManager.java:54)
    at org.glassfish.jersey.internal.inject.Providers.getServiceHolders(Providers.java:337)
    at org.glassfish.jersey.internal.inject.Providers.getCustomProviders(Providers.java:175)
    at org.glassfish.jersey.message.internal.MessageBodyFactory.initialize(MessageBodyFactory.java:238)
    at org.glassfish.jersey.message.internal.MessageBodyFactory$MessageBodyWorkersConfigurator.postInit(MessageBodyFactory.java:136)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$2(ApplicationHandler.java:372)
    at java.base/java.util.Arrays$ArrayList.forEach(Arrays.java:4389)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:372)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$1(ApplicationHandler.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:298)
    at org.glassfish.jersey.internal.Errors.processWithException(Errors.java:256)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:315)
    at org.glassfish.jersey.server.ApplicationHandler.<init>(ApplicationHandler.java:282)
    at org.glassfish.jersey.servlet.WebComponent.<init>(WebComponent.java:335)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:178)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:370)
    at javax.servlet.GenericServlet.init(GenericServlet.java:244)
    at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:600)
    at org.eclipse.jetty.servlet.ServletHolder.initialize(ServletHolder.java:389)
    at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:869)
    at org.eclipse.jetty.servlet.ServletContextHandler.startContext(ServletContextHandler.java:298)
    at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:741)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:132)
    at org.eclipse.jetty.server.Server.start(Server.java:387)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:114)
    at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:61)
    at org.eclipse.jetty.server.Server.doStart(Server.java:354)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.jitsi.jibri.MainKt.launchHttpServer(Main.kt:151)
    at org.jitsi.jibri.MainKt.main(Main.kt:125)
Caused by: java.lang.ClassNotFoundException: javax.activation.DataSource
    at java.base/jdk.internal.loader.BuiltinClassLoader.loadClass(BuiltinClassLoader.java:583)
    at java.base/jdk.internal.loader.ClassLoaders$AppClassLoader.loadClass(ClassLoaders.java:190)
    at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:499)
    ... 57 more
MultiException stack 2 of 2
java.lang.IllegalArgumentException: Errors were discovered while reifying SystemDescriptor(
    implementation=org.glassfish.jersey.message.internal.DataSourceProvider
    contracts={javax.ws.rs.ext.MessageBodyReader,javax.ws.rs.ext.MessageBodyWriter}
    scope=javax.inject.Singleton
    qualifiers={}
    descriptorType=CLASS
    descriptorVisibility=NORMAL
    metadata=
    rank=0
    loader=null
    proxiable=null
    proxyForSameScope=null
    analysisName=null
    id=106
    locatorId=0
    identityHashCode=990226843
    reified=false)
    at org.jvnet.hk2.internal.SystemDescriptor.reify(SystemDescriptor.java:705)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.reifyDescriptor(ServiceLocatorImpl.java:464)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.narrow(ServiceLocatorImpl.java:2310)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.access$1200(ServiceLocatorImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1395)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1390)
    at org.glassfish.hk2.utilities.cache.internal.WeakCARCacheImpl.compute(WeakCARCacheImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.internalGetAllServiceHandles(ServiceLocatorImpl.java:1452)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1377)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1366)
    at org.glassfish.jersey.inject.hk2.AbstractHk2InjectionManager.getAllServiceHolders(AbstractHk2InjectionManager.java:158)
    at org.glassfish.jersey.inject.hk2.ImmediateHk2InjectionManager.getAllServiceHolders(ImmediateHk2InjectionManager.java:54)
    at org.glassfish.jersey.internal.inject.Providers.getServiceHolders(Providers.java:337)
    at org.glassfish.jersey.internal.inject.Providers.getCustomProviders(Providers.java:175)
    at org.glassfish.jersey.message.internal.MessageBodyFactory.initialize(MessageBodyFactory.java:238)
    at org.glassfish.jersey.message.internal.MessageBodyFactory$MessageBodyWorkersConfigurator.postInit(MessageBodyFactory.java:136)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$2(ApplicationHandler.java:372)
    at java.base/java.util.Arrays$ArrayList.forEach(Arrays.java:4389)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:372)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$1(ApplicationHandler.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:298)
    at org.glassfish.jersey.internal.Errors.processWithException(Errors.java:256)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:315)
    at org.glassfish.jersey.server.ApplicationHandler.<init>(ApplicationHandler.java:282)
    at org.glassfish.jersey.servlet.WebComponent.<init>(WebComponent.java:335)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:178)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:370)
    at javax.servlet.GenericServlet.init(GenericServlet.java:244)
    at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:600)
    at org.eclipse.jetty.servlet.ServletHolder.initialize(ServletHolder.java:389)
    at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:869)
    at org.eclipse.jetty.servlet.ServletContextHandler.startContext(ServletContextHandler.java:298)
    at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:741)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:132)
    at org.eclipse.jetty.server.Server.start(Server.java:387)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:114)
    at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:61)
    at org.eclipse.jetty.server.Server.doStart(Server.java:354)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.jitsi.jibri.MainKt.launchHttpServer(Main.kt:151)
    at org.jitsi.jibri.MainKt.main(Main.kt:125)
.
.
.
2018-12-04 16:18:41.861 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.start() Connecting to xmpp environment on prod.meet.lacajanegra.mx with config XmppEnvironmentConfig(name=prod environment, xmppServerHosts=[prod.meet.lacajanegra.mx], xmppDomain=meet.lacajanegra.mx, controlLogin=XmppCredentials(domain=auth.meet.lacajanegra.mx, username=jibri, password=jibriauthpass), controlMuc=XmppMuc(domain=internal.auth.meet.lacajanegra.mx, roomName=JibriBrewery, nickname=jibri-nickname), sipControlMuc=null, callLogin=XmppCredentials(domain=recorder.meet.lacajanegra.mx, username=recorder, password=jibrirecorderpass), stripFromRoomDomain=conference., usageTimeoutMins=0, trustAllXmppCerts=true)
2018-12-04 16:18:41.875 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.start() The trustAllXmppCerts config is enabled for this domain, all XMPP server provided certificates will be accepted
2018-12-04 16:18:42.139 SEVERE: [1] org.jitsi.jibri.api.xmpp.XmppApi.start() Error connecting to xmpp environment: org.jivesoftware.smack.SmackException$ConnectionException: The following addresses failed: 'prod.meet.lacajanegra.mx:5222' failed because: java.net.UnknownHostException: prod.meet.lacajanegra.mx: Name or service not known
2018-12-04 16:18:42.272 WARNING: [1] org.glassfish.jersey.internal.inject.Providers.checkProviderRuntime() A provider org.jitsi.jibri.api.http.HttpApi registered in SERVER runtime does not implement any provider interfaces applicable in the SERVER runtime. Due to constraint configuration problems the provider org.jitsi.jibri.api.http.HttpApi will be ignored. 
2018-12-04 16:18:42.387 WARNING: [1] org.glassfish.jersey.internal.Errors.logErrors() The following warnings have been detected: WARNING: HK2 service reification failed for [org.glassfish.jersey.message.internal.DataSourceProvider] with an exception:
MultiException stack 1 of 2
java.lang.NoClassDefFoundError: javax/activation/DataSource
    at java.base/java.lang.Class.getDeclaredConstructors0(Native Method)
    at java.base/java.lang.Class.privateGetDeclaredConstructors(Class.java:3090)
    at java.base/java.lang.Class.getDeclaredConstructors(Class.java:2316)
    at org.jvnet.hk2.internal.Utilities$3.run(Utilities.java:1378)
    at org.jvnet.hk2.internal.Utilities$3.run(Utilities.java:1374)
    at java.base/java.security.AccessController.doPrivileged(Native Method)
    at org.jvnet.hk2.internal.Utilities.getAllConstructors(Utilities.java:1374)
    at org.jvnet.hk2.internal.Utilities.findProducerConstructor(Utilities.java:1317)
    at org.jvnet.hk2.internal.DefaultClassAnalyzer.getConstructor(DefaultClassAnalyzer.java:83)
    at org.glassfish.jersey.inject.hk2.JerseyClassAnalyzer.getConstructor(JerseyClassAnalyzer.java:148)
    at org.jvnet.hk2.internal.Utilities.getConstructor(Utilities.java:180)
    at org.jvnet.hk2.internal.ClazzCreator.initialize(ClazzCreator.java:129)
    at org.jvnet.hk2.internal.ClazzCreator.initialize(ClazzCreator.java:180)
    at org.jvnet.hk2.internal.SystemDescriptor.internalReify(SystemDescriptor.java:740)
    at org.jvnet.hk2.internal.SystemDescriptor.reify(SystemDescriptor.java:694)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.reifyDescriptor(ServiceLocatorImpl.java:464)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.narrow(ServiceLocatorImpl.java:2310)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.access$1200(ServiceLocatorImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1395)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1390)
    at org.glassfish.hk2.utilities.cache.internal.WeakCARCacheImpl.compute(WeakCARCacheImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.internalGetAllServiceHandles(ServiceLocatorImpl.java:1452)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1377)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1366)
    at org.glassfish.jersey.inject.hk2.AbstractHk2InjectionManager.getAllServiceHolders(AbstractHk2InjectionManager.java:158)
    at org.glassfish.jersey.inject.hk2.ImmediateHk2InjectionManager.getAllServiceHolders(ImmediateHk2InjectionManager.java:54)
    at org.glassfish.jersey.internal.inject.Providers.getServiceHolders(Providers.java:337)
    at org.glassfish.jersey.internal.inject.Providers.getCustomProviders(Providers.java:175)
    at org.glassfish.jersey.message.internal.MessageBodyFactory.initialize(MessageBodyFactory.java:238)
    at org.glassfish.jersey.message.internal.MessageBodyFactory$MessageBodyWorkersConfigurator.postInit(MessageBodyFactory.java:136)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$2(ApplicationHandler.java:372)
    at java.base/java.util.Arrays$ArrayList.forEach(Arrays.java:4389)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:372)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$1(ApplicationHandler.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:298)
    at org.glassfish.jersey.internal.Errors.processWithException(Errors.java:256)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:315)
    at org.glassfish.jersey.server.ApplicationHandler.<init>(ApplicationHandler.java:282)
    at org.glassfish.jersey.servlet.WebComponent.<init>(WebComponent.java:335)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:178)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:370)
    at javax.servlet.GenericServlet.init(GenericServlet.java:244)
    at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:600)
    at org.eclipse.jetty.servlet.ServletHolder.initialize(ServletHolder.java:389)
    at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:869)
    at org.eclipse.jetty.servlet.ServletContextHandler.startContext(ServletContextHandler.java:298)
    at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:741)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:132)
    at org.eclipse.jetty.server.Server.start(Server.java:387)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:114)
    at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:61)
    at org.eclipse.jetty.server.Server.doStart(Server.java:354)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.jitsi.jibri.MainKt.launchHttpServer(Main.kt:151)
    at org.jitsi.jibri.MainKt.main(Main.kt:136)
Caused by: java.lang.ClassNotFoundException: javax.activation.DataSource
    at java.base/jdk.internal.loader.BuiltinClassLoader.loadClass(BuiltinClassLoader.java:583)
    at java.base/jdk.internal.loader.ClassLoaders$AppClassLoader.loadClass(ClassLoaders.java:190)
    at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:499)
    ... 57 more
MultiException stack 2 of 2
java.lang.IllegalArgumentException: Errors were discovered while reifying SystemDescriptor(
    implementation=org.glassfish.jersey.message.internal.DataSourceProvider
    contracts={javax.ws.rs.ext.MessageBodyReader,javax.ws.rs.ext.MessageBodyWriter}
    scope=javax.inject.Singleton
    qualifiers={}
    descriptorType=CLASS
    descriptorVisibility=NORMAL
    metadata=
    rank=0
    loader=null
    proxiable=null
    proxyForSameScope=null
    analysisName=null
    id=106
    locatorId=1
    identityHashCode=1350395050
    reified=false)
    at org.jvnet.hk2.internal.SystemDescriptor.reify(SystemDescriptor.java:705)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.reifyDescriptor(ServiceLocatorImpl.java:464)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.narrow(ServiceLocatorImpl.java:2310)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.access$1200(ServiceLocatorImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1395)
    at org.jvnet.hk2.internal.ServiceLocatorImpl$9.compute(ServiceLocatorImpl.java:1390)
    at org.glassfish.hk2.utilities.cache.internal.WeakCARCacheImpl.compute(WeakCARCacheImpl.java:128)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.internalGetAllServiceHandles(ServiceLocatorImpl.java:1452)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1377)
    at org.jvnet.hk2.internal.ServiceLocatorImpl.getAllServiceHandles(ServiceLocatorImpl.java:1366)
    at org.glassfish.jersey.inject.hk2.AbstractHk2InjectionManager.getAllServiceHolders(AbstractHk2InjectionManager.java:158)
    at org.glassfish.jersey.inject.hk2.ImmediateHk2InjectionManager.getAllServiceHolders(ImmediateHk2InjectionManager.java:54)
    at org.glassfish.jersey.internal.inject.Providers.getServiceHolders(Providers.java:337)
    at org.glassfish.jersey.internal.inject.Providers.getCustomProviders(Providers.java:175)
    at org.glassfish.jersey.message.internal.MessageBodyFactory.initialize(MessageBodyFactory.java:238)
    at org.glassfish.jersey.message.internal.MessageBodyFactory$MessageBodyWorkersConfigurator.postInit(MessageBodyFactory.java:136)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$2(ApplicationHandler.java:372)
    at java.base/java.util.Arrays$ArrayList.forEach(Arrays.java:4389)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:372)
    at org.glassfish.jersey.server.ApplicationHandler.lambda$initialize$1(ApplicationHandler.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:316)
    at org.glassfish.jersey.internal.Errors.process(Errors.java:298)
    at org.glassfish.jersey.internal.Errors.processWithException(Errors.java:256)
    at org.glassfish.jersey.server.ApplicationHandler.initialize(ApplicationHandler.java:315)
    at org.glassfish.jersey.server.ApplicationHandler.<init>(ApplicationHandler.java:282)
    at org.glassfish.jersey.servlet.WebComponent.<init>(WebComponent.java:335)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:178)
    at org.glassfish.jersey.servlet.ServletContainer.init(ServletContainer.java:370)
    at javax.servlet.GenericServlet.init(GenericServlet.java:244)
    at org.eclipse.jetty.servlet.ServletHolder.initServlet(ServletHolder.java:600)
    at org.eclipse.jetty.servlet.ServletHolder.initialize(ServletHolder.java:389)
    at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:869)
    at org.eclipse.jetty.servlet.ServletContextHandler.startContext(ServletContextHandler.java:298)
    at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:741)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:132)
    at org.eclipse.jetty.server.Server.start(Server.java:387)
    at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:114)
    at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:61)
    at org.eclipse.jetty.server.Server.doStart(Server.java:354)
    at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
    at org.jitsi.jibri.MainKt.launchHttpServer(Main.kt:151)
    at org.jitsi.jibri.MainKt.main(Main.kt:136)


2018-12-04 16:19:11.933 WARNING: [23] org.jivesoftware.smackx.ping.PingManager.pingServerIfNecessary() XMPPConnection was not authenticated
2018-12-04 18:14:00.469 INFO: [16] org.jitsi.jibri.api.http.internal.InternalHttpApi.gracefulShutdown() Jibri gracefully shutting down    

Jicofo

Jicofo 2018-12-03 17:32:54.114 INFO: [10] org.jitsi.service.libjitsi.LibJitsi.log() Successfully started LibJitsi using as implementation: org.jitsi.impl.libjitsi.LibJitsiOSGiImpl
Jicofo 2018-12-03 17:32:54.222 INFO: [10] impl.configuration.ConfigurationActivator.log() Using properties file configuration store.
Jicofo 2018-12-03 17:32:54.229 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.HOSTNAME=localhost
Jicofo 2018-12-03 17:32:54.237 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.runtime.name=OpenJDK Runtime Environment
Jicofo 2018-12-03 17:32:54.237 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.boot.library.path=/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/amd64
Jicofo 2018-12-03 17:32:54.244 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.version=25.191-b12
Jicofo 2018-12-03 17:32:54.244 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.vendor=Oracle Corporation
Jicofo 2018-12-03 17:32:54.244 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vendor.url=http://java.oracle.com/
Jicofo 2018-12-03 17:32:54.245 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() path.separator=:
Jicofo 2018-12-03 17:32:54.245 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.util.logging.config.file=/etc/jitsi/jicofo/logging.properties
Jicofo 2018-12-03 17:32:54.246 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.name=OpenJDK 64-Bit Server VM
Jicofo 2018-12-03 17:32:54.246 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() file.encoding.pkg=sun.io
Jicofo 2018-12-03 17:32:54.246 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.java.launcher=SUN_STANDARD
Jicofo 2018-12-03 17:32:54.246 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.os.patch.level=unknown
Jicofo 2018-12-03 17:32:54.247 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.service.media.DISABLE_AUDIO_SUPPORT=true
Jicofo 2018-12-03 17:32:54.253 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.specification.name=Java Virtual Machine Specification
Jicofo 2018-12-03 17:32:54.253 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() user.dir=/usr/share/jicofo
Jicofo 2018-12-03 17:32:54.253 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.FOCUS_USER_PASSWORD=**********
Jicofo 2018-12-03 17:32:54.253 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.runtime.version=1.8.0_191-8u191-b12-0ubuntu0.18.04.1-b12
Jicofo 2018-12-03 17:32:54.254 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment
Jicofo 2018-12-03 17:32:54.254 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.endorsed.dirs=/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/endorsed
Jicofo 2018-12-03 17:32:54.255 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() os.arch=amd64
Jicofo 2018-12-03 17:32:54.255 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.io.tmpdir=/tmp
Jicofo 2018-12-03 17:32:54.260 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() line.separator=
.
.
.
Jicofo 2018-12-03 19:31:49.188 SEVERE: [25] org.jitsi.jicofo.xmpp.BaseBrewery.start().196 Failed to create room: JibriBrewery@internal.auth.meet.lacajanegra.mx
net.java.sip.communicator.service.protocol.OperationFailedException: Failed to join the room
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.joinAs(ChatRoomImpl.java:298)
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.join(ChatRoomImpl.java:209)
	at org.jitsi.jicofo.xmpp.BaseBrewery.start(BaseBrewery.java:190)
	at org.jitsi.jicofo.xmpp.BaseBrewery.maybeStart(BaseBrewery.java:141)
	at org.jitsi.jicofo.xmpp.BaseBrewery.registrationStateChanged(BaseBrewery.java:166)
	at org.jitsi.jicofo.ProtocolProviderHandler.registrationStateChanged(ProtocolProviderHandler.java:141)
	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 org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.notifyConnected(XmppProtocolProvider.java:345)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.access$100(XmppProtocolProvider.java:62)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider$XmppConnectionListener.authenticated(XmppProtocolProvider.java:633)
	at org.jivesoftware.smack.AbstractXMPPConnection.callConnectionAuthenticatedListener(AbstractXMPPConnection.java:1263)
	at org.jivesoftware.smack.AbstractXMPPConnection.afterSuccessfulLogin(AbstractXMPPConnection.java:575)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection.afterSuccessfulLogin(XMPPTCPConnection.java:379)
	at org.jivesoftware.smack.tcp.XMPPTCPConnection.loginInternal(XMPPTCPConnection.java:444)
	at org.jivesoftware.smack.AbstractXMPPConnection.login(AbstractXMPPConnection.java:494)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.doConnect(XmppProtocolProvider.java:297)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.access$000(XmppProtocolProvider.java:62)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider$1.call(XmppProtocolProvider.java:256)
	at org.jitsi.impl.protocol.xmpp.XmppProtocolProvider$1.call(XmppProtocolProvider.java:251)
	at org.jitsi.retry.RetryStrategy$TaskRunner.run(RetryStrategy.java:193)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
Caused by: org.jivesoftware.smack.XMPPException$XMPPErrorException: XMPP error reply received from internal.auth.meet.lacajanegra.mx: XMPPError: remote-server-not-found - cancel
	at org.jivesoftware.smack.XMPPException$XMPPErrorException.ifHasErrorThenThrow(XMPPException.java:131)
	at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:256)
	at org.jivesoftware.smack.StanzaCollector.nextResultOrThrow(StanzaCollector.java:208)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.discoverInfo(ServiceDiscoveryManager.java:542)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.discoverInfo(ServiceDiscoveryManager.java:508)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.supportsFeatures(ServiceDiscoveryManager.java:738)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.supportsFeatures(ServiceDiscoveryManager.java:734)
	at org.jivesoftware.smackx.disco.ServiceDiscoveryManager.supportsFeature(ServiceDiscoveryManager.java:730)
	at org.jivesoftware.smackx.muc.MultiUserChatManager.providesMucService(MultiUserChatManager.java:360)
	at org.jivesoftware.smackx.muc.MultiUserChat.enter(MultiUserChat.java:311)
	at org.jivesoftware.smackx.muc.MultiUserChat.createOrJoin(MultiUserChat.java:498)
	at org.jivesoftware.smackx.muc.MultiUserChat.createOrJoin(MultiUserChat.java:444)
	at org.jitsi.impl.protocol.xmpp.ChatRoomImpl.joinAs(ChatRoomImpl.java:240)
	... 27 more

.
.
.
Jicofo 2018-12-03 23:37:31.076 INFO: [44] org.jitsi.jicofo.FocusManager.log() Created new focus for raredonkeysperformslyly@conference.meet.lacajanegra.mx@auth.meet.lacajanegra.mx. Conference count 1,options: 
Jicofo 2018-12-03 23:37:31.080 INFO: [44] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Lip-sync enabled in raredonkeysperformslyly@conference.meet.lacajanegra.mx
Jicofo 2018-12-03 23:37:31.094 INFO: [44] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Joining the room: raredonkeysperformslyly@conference.meet.lacajanegra.mx
Jicofo 2018-12-03 23:37:31.419 WARNING: [44] org.jitsi.xmpp.component.ComponentBase.verifyProcessingTime().540 PROCESSING TIME LIMIT EXCEEDED - it took 366ms to process: <iq type="set" to="focus.meet.lacajanegra.mx" from="75619cca-9405-4a96-87e2-996820be8884@meet.lacajanegra.mx/365639b5-8d13-4d4b-a374-68345d44bf61" id="689bd52a-a323-4347-b9e9-8a96b1f2f306:sendIQ"><conference xmlns="http://jitsi.org/protocol/focus" room="raredonkeysperformslyly@conference.meet.lacajanegra.mx" machine-uid="4323c008aed1de3593a013ee406ef53f"><property value="-1" name="channelLastN"/><property value="false" name="disableRtx"/><property value="true" name="enableLipSync"/><property value="true" name="openSctp"/></conference></iq>
Jicofo 2018-12-03 23:37:31.511 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberJoined sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@270a5942 member=ChatMember[raredonkeysperformslyly@conference.meet.lacajanegra.mx/75619cca, jid: null]@1579953177]
Jicofo 2018-12-03 23:37:31.523 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Granted owner to raredonkeysperformslyly@conference.meet.lacajanegra.mx/75619cca
Jicofo 2018-12-03 23:37:31.528 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member raredonkeysperformslyly@conference.meet.lacajanegra.mx/75619cca joined.
Jicofo 2018-12-03 23:37:39.061 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberLeft sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@270a5942 member=ChatMember[raredonkeysperformslyly@conference.meet.lacajanegra.mx/75619cca, jid: 75619cca-9405-4a96-87e2-996820be8884@meet.lacajanegra.mx/365639b5-8d13-4d4b-a374-68345d44bf61]@1579953177]
Jicofo 2018-12-03 23:37:39.061 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Owner has left the room !
Jicofo 2018-12-03 23:37:39.061 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member raredonkeysperformslyly@conference.meet.lacajanegra.mx/75619cca is leaving
Jicofo 2018-12-03 23:37:39.061 WARNING: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Participant not found for raredonkeysperformslyly@conference.meet.lacajanegra.mx/75619cca terminated already or never started ?
Jicofo 2018-12-03 23:37:39.081 INFO: [37] org.jitsi.jicofo.FocusManager.log() Disposed conference for room: raredonkeysperformslyly@conference.meet.lacajanegra.mx conference count: 0
Jicofo 2018-12-03 23:37:40.318 INFO: [52] org.jitsi.jicofo.xmpp.FocusComponent.handleConferenceIq().402 Focus request for room: raredonkeysperformslyly@conference.meet.lacajanegra.mx
Jicofo 2018-12-03 23:37:40.319 INFO: [52] org.jitsi.jicofo.FocusManager.log() Created new focus for raredonkeysperformslyly@conference.meet.lacajanegra.mx@auth.meet.lacajanegra.mx. Conference count 1,options: 
Jicofo 2018-12-03 23:37:40.322 INFO: [52] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Lip-sync enabled in raredonkeysperformslyly@conference.meet.lacajanegra.mx
Jicofo 2018-12-03 23:37:40.322 INFO: [52] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Joining the room: raredonkeysperformslyly@conference.meet.lacajanegra.mx
Jicofo 2018-12-03 23:37:40.492 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberJoined sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@72564b5f member=ChatMember[raredonkeysperformslyly@conference.meet.lacajanegra.mx/34c892fb, jid: null]@2146243860]
Jicofo 2018-12-03 23:37:40.495 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Granted owner to raredonkeysperformslyly@conference.meet.lacajanegra.mx/34c892fb
Jicofo 2018-12-03 23:37:40.495 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member raredonkeysperformslyly@conference.meet.lacajanegra.mx/34c892fb joined.
Jicofo 2018-12-03 23:37:45.413 SEVERE: [38] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start
Jicofo 2018-12-03 23:37:45.414 INFO: [38] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Failed to start a Jibri session, no Jibris available
Jicofo 2018-12-03 23:38:00.560 INFO: [42] org.jitsi.jicofo.ComponentsDiscovery.discoverServices().267 New component discovered: jitsi-videobridge.meet.lacajanegra.mx, IQ Stanza (query jabber:iq:version) [to=focus@auth.meet.lacajanegra.mx/focus13977422203,from=jitsi-videobridge.meet.lacajanegra.mx,id=XSWbn-104,type=result,]
Jicofo 2018-12-03 23:38:00.560 INFO: [42] org.jitsi.jicofo.BridgeSelector.addJvbAddress().215 Added videobridge: jitsi-videobridge.meet.lacajanegra.mx v: IQ Stanza (query jabber:iq:version) [to=focus@auth.meet.lacajanegra.mx/focus13977422203,from=jitsi-videobridge.meet.lacajanegra.mx,id=XSWbn-104,type=result,]
Jicofo 2018-12-04 15:55:21.052 INFO: [10] util.NetworkUtils.<clinit>().117 java.net.preferIPv6Addresses=null
Jicofo 2018-12-04 15:55:21.052 INFO: [10] util.NetworkUtils.<clinit>().118 java.net.preferIPv4Stack=null
Jicofo 2018-12-04 15:55:21.062 INFO: [10] impl.netaddr.NetworkAddressManagerServiceImpl.start().92 Network Address Manager         ...[  STARTED ]
Jicofo 2018-12-04 15:55:21.063 INFO: [10] impl.netaddr.NetworkAddressManagerServiceImpl.start().98 Network Address Manager Service ...[REGISTERED]
Jicofo 2018-12-04 15:55:21.077 INFO: [10] org.jitsi.version.AbstractVersionActivator.start().119 JiCoFo Version: JiCoFo 1.0.1.0-440
Jicofo 2018-12-04 15:55:21.506 INFO: [10] org.jitsi.jicofo.JitsiMeetGlobalConfig.init().170 Automatically grant 'owner' role: true
Jicofo 2018-12-04 15:55:21.507 INFO: [10] org.jitsi.jicofo.JitsiMeetGlobalConfig.init().183 Jibri requests in PENDING state will be timed out after: 90 seconds
Jicofo 2018-12-04 15:55:21.507 INFO: [10] org.jitsi.jicofo.JitsiMeetGlobalConfig.init().197 Lonely participants will be "terminated" after 20000 milliseconds
Jicofo 2018-12-04 15:55:21.539 INFO: [10] org.jitsi.jicofo.BridgeSelector.createBridgeSelectionStrategy().176 Using SingleBridgeSelectionStrategy
Jicofo 2018-12-04 15:55:21.539 INFO: [10] org.jitsi.jicofo.BridgeSelector.<init>().146 Using org.jitsi.jicofo.BridgeSelector$SingleBridgeSelectionStrategy
Jicofo 2018-12-04 15:55:21.539 INFO: [10] org.jitsi.jicofo.BridgeSelector.init().610 Bridge failure reset threshold: 300000
Jicofo 2018-12-04 15:55:21.543 INFO: [10] org.jitsi.jicofo.JitsiMeetServices.start().351 Using a Jibri detector with MUC: JibriBrewery@internal.auth.meet.lacajanegra.mx
Jicofo 2018-12-04 15:55:21.726 INFO: [10] org.eclipse.jetty.server.Server.doStart() jetty-8.1.16.v20140903
Jicofo 2018-12-04 15:55:21.824 INFO: [10] org.eclipse.jetty.server.AbstractConnector.doStart() Started SelectChannelConnector@0.0.0.0:8888
Jicofo 2018-12-04 15:55:21.843 INFO: [1] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.jibri.PENDING_TIMEOUT=90
Jicofo 2018-12-04 15:55:21.844 INFO: [1] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.jibri.BREWERY=JibriBrewery@internal.auth.meet.lacajanegra.mx
Jicofo 2018-12-04 15:55:21.861 INFO: [1] org.jitsi.xmpp.component.ComponentBase.loadConfig().202 Component org.jitsi.jicofo. config: 
Jicofo 2018-12-04 15:55:21.862 INFO: [1] org.jitsi.xmpp.component.ComponentBase.loadConfig().203   ping interval: 10000 ms
Jicofo 2018-12-04 15:55:21.865 INFO: [1] org.jitsi.xmpp.component.ComponentBase.loadConfig().204   ping timeout: 5000 ms
Jicofo 2018-12-04 15:55:21.865 INFO: [1] org.jitsi.xmpp.component.ComponentBase.loadConfig().205   ping threshold: 3
Jicofo 2018-12-04 15:55:22.609 INFO: [25] org.jitsi.jicofo.xmpp.BaseBrewery.start().192 Joined brewery room: JibriBrewery@internal.auth.meet.lacajanegra.mx
Jicofo 2018-12-04 15:55:22.631 INFO: [25] org.jitsi.jicofo.ComponentsDiscovery.discoverServices().267 New component discovered: jitsi-videobridge.meet.lacajanegra.mx, IQ Stanza (query jabber:iq:version) [to=focus@auth.meet.lacajanegra.mx/focus626838604302,from=jitsi-videobridge.meet.lacajanegra.mx,id=mkilc-34,type=result,]
Jicofo 2018-12-04 15:55:22.631 INFO: [25] org.jitsi.jicofo.BridgeSelector.addJvbAddress().215 Added videobridge: jitsi-videobridge.meet.lacajanegra.mx v: IQ Stanza (query jabber:iq:version) [to=focus@auth.meet.lacajanegra.mx/focus626838604302,from=jitsi-videobridge.meet.lacajanegra.mx,id=mkilc-34,type=result,]
Jicofo 2018-12-04 15:55:22.631 WARNING: [25] org.jitsi.jicofo.BridgeSelector.addJvbAddress().227 No pub-sub node mapped for jitsi-videobridge.meet.lacajanegra.mx
Jicofo 2018-12-04 15:55:22.640 INFO: [25] org.jitsi.jicofo.ComponentsDiscovery.discoverServices().267 New component discovered: recorder.meet.lacajanegra.mx, IQ Stanza (query jabber:iq:version) [to=focus@auth.meet.lacajanegra.mx/focus626838604302,from=recorder.meet.lacajanegra.mx,id=mkilc-39,type=result,]
Jicofo 2018-12-04 15:55:22.643 INFO: [25] org.jitsi.jicofo.ComponentsDiscovery.discoverServices().267 New component discovered: auth.meet.lacajanegra.mx, IQ Stanza (query jabber:iq:version) [to=focus@auth.meet.lacajanegra.mx/focus626838604302,from=auth.meet.lacajanegra.mx,id=mkilc-44,type=result,]
Jicofo 2018-12-04 15:55:22.643 INFO: [25] org.jitsi.jicofo.JitsiMeetServices.newNodeDiscovered().208 Detected XMPP server version: IQ Stanza (query jabber:iq:version) [to=focus@auth.meet.lacajanegra.mx/focus626838604302,from=auth.meet.lacajanegra.mx,id=mkilc-44,type=result,]
Jicofo 2018-12-04 15:55:22.647 INFO: [40] org.jitsi.jicofo.JvbDoctor.log() Scheduled health-check task for: jitsi-videobridge.meet.lacajanegra.mx
Jicofo 2018-12-04 15:55:22.672 INFO: [25] org.jitsi.jicofo.ComponentsDiscovery.discoverServices().267 New component discovered: focus.meet.lacajanegra.mx, null
Jicofo 2018-12-04 15:55:22.673 INFO: [25] org.jitsi.jicofo.ComponentsDiscovery.discoverServices().267 New component discovered: conference.meet.lacajanegra.mx, null
Jicofo 2018-12-04 15:55:22.673 INFO: [25] org.jitsi.jicofo.JitsiMeetServices.newNodeDiscovered().200 MUC component discovered: conference.meet.lacajanegra.mx
Jicofo 2018-12-04 15:55:22.674 INFO: [25] org.jitsi.jicofo.ComponentsDiscovery.scheduleRediscovery().189 Services re-discovery interval: 30000
Jicofo 2018-12-04 15:55:22.682 INFO: [25] org.jitsi.jicofo.FocusManager.log() XMPP provider reg state: RegistrationState=Registered
Jicofo 2018-12-04 15:55:22.701 INFO: [25] org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.doConnect().309 XMPP provider Jabber:focus@auth.meet.lacajanegra.mx/focus626838604302@localhost connected (JID: focus@auth.meet.lacajanegra.mx/focus626838604302)
Jicofo 2018-12-04 16:19:06.039 INFO: [66] org.jitsi.jicofo.xmpp.FocusComponent.handleConferenceIq().402 Focus request for room: accurategalaxiesterminateaccusingly@conference.meet.lacajanegra.mx
Jicofo 2018-12-04 16:19:06.057 INFO: [66] org.jitsi.jicofo.FocusManager.log() Created new focus for accurategalaxiesterminateaccusingly@conference.meet.lacajanegra.mx@auth.meet.lacajanegra.mx. Conference count 1,options: 
Jicofo 2018-12-04 16:19:06.059 INFO: [66] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Lip-sync enabled in accurategalaxiesterminateaccusingly@conference.meet.lacajanegra.mx
Jicofo 2018-12-04 16:19:06.060 INFO: [66] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Joining the room: accurategalaxiesterminateaccusingly@conference.meet.lacajanegra.mx
Jicofo 2018-12-04 16:19:06.226 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberJoined sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@2935b1cc member=ChatMember[accurategalaxiesterminateaccusingly@conference.meet.lacajanegra.mx/ab264b54, jid: null]@1365173707]
Jicofo 2018-12-04 16:19:06.231 INFO: [37] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Granted owner to accurategalaxiesterminateaccusingly@conference.meet.lacajanegra.mx/ab264b54
Jicofo 2018-12-04 16:19:06.231 INFO: [37] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member accurategalaxiesterminateaccusingly@conference.meet.lacajanegra.mx/ab264b54 joined.
Jicofo 2018-12-04 16:20:04.770 SEVERE: [106] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start
Jicofo 2018-12-04 16:20:04.771 INFO: [106] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Failed to start a Jibri session, no Jibris available
Jicofo 2018-12-04 16:29:14.538 SEVERE: [107] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start
Jicofo 2018-12-04 16:29:14.540 INFO: [107] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Failed to start a Jibri session, no Jibris available
Jicofo 2018-12-04 17:17:29.599 SEVERE: [108] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start
Jicofo 2018-12-04 17:17:29.600 INFO: [108] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Failed to start a Jibri session, no Jibris available
Jicofo 2018-12-04 17:37:26.790 SEVERE: [109] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start
Jicofo 2018-12-04 17:37:26.791 INFO: [109] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Failed to start a Jibri session, no Jibris available    

Any suggestions in how to tackle this?


#2

which version of jibri u r using?


#3

Hi @bafacandiav ,

The xmpp server host will only be meet.lacajanegra.mx . Drop the prod and it should work.

Regards,
Abhijit


#4

Thanks for pointing this out @abhijitnathwani, now this popup appears and remains static.

image

Jicofo log

Jicofo 2018-12-06 17:55:53.026 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 17:55:53.026 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 17:55:53.027 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:55:53.031 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 17:55:53.031 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 17:55:53.031 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 17:55:54.123 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:55:54.123 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 17:56:00.043 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Trying to stop: <iq to='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='QDQ7e-64205' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='stop'/></iq>
Jicofo 2018-12-06 17:56:11.999 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Trying to stop: <iq to='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='QDQ7e-64212' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='stop'/></iq>
Jicofo 2018-12-06 17:56:27.863 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:56:27.864 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 17:56:27.865 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason null
Jicofo 2018-12-06 17:56:27.865 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 17:56:27.865 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri session ended cleanly, notifying owner and cleaning up session
Jicofo 2018-12-06 17:56:27.865 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Got jibri status off and failure null
Jicofo 2018-12-06 17:56:27.865 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Publishing new jibri-recording-status: <jibri-recording-status xmlns='http://jitsi.org/protocol/jibri' status='off' session_id='nehyhtyiuwmeulkv' recording_mode='file'/> in: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:56:27.865 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Cleaning up current JibriSession
Jicofo 2018-12-06 17:56:27.910 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri null has status off and failure reason null
Jicofo 2018-12-06 17:56:27.910 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri session ended cleanly, notifying owner and cleaning up session
Jicofo 2018-12-06 17:56:27.910 SEVERE: [130] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() onSessionStateChanged for unknown session: org.jitsi.jicofo.recording.jibri.JibriSession@57697652
Jicofo 2018-12-06 17:56:27.910 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Cleaning up current JibriSession
Jicofo 2018-12-06 17:56:34.600 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:56:34.606 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 17:56:34.606 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 17:56:34.606 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Started Jibri session
Jicofo 2018-12-06 17:56:35.636 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:56:35.637 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 17:57:08.915 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:57:08.916 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.meet.lacajanegra.mx/focus1565236856849' from='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='Zm9jdXNAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14L2ZvY3VzMTU2NTIzNjg1Njg0OQAxN1BZTy0xNTIAHI/lSmaNu6Be85FZS46yxg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:57:08.916 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason error
Jicofo 2018-12-06 17:57:08.917 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 17:57:08.917 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 17:57:08.917 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 17:57:08.917 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:57:08.924 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 17:57:08.924 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 17:57:08.925 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 17:57:10.001 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:57:10.001 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 17:57:42.953 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:57:42.954 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 17:57:42.954 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.meet.lacajanegra.mx/focus1565236856849' from='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='Zm9jdXNAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14L2ZvY3VzMTU2NTIzNjg1Njg0OQAxN1BZTy0xNjEAHI/lSmaNu6Be85FZS46yxg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:57:42.954 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason error
Jicofo 2018-12-06 17:57:42.954 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 17:57:42.955 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 17:57:42.955 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:57:42.958 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 17:57:42.958 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 17:57:42.958 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 17:57:44.084 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:57:44.085 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 17:58:17.282 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:58:17.283 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 17:58:17.284 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.meet.lacajanegra.mx/focus1565236856849' from='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='Zm9jdXNAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14L2ZvY3VzMTU2NTIzNjg1Njg0OQAxN1BZTy0xNzEAHI/lSmaNu6Be85FZS46yxg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:58:17.284 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason error
Jicofo 2018-12-06 17:58:17.284 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 17:58:17.284 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 17:58:17.285 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:58:17.288 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 17:58:17.288 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 17:58:17.288 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 17:58:18.400 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:58:18.401 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 17:58:51.765 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:58:51.766 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 17:58:51.768 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.meet.lacajanegra.mx/focus1565236856849' from='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='Zm9jdXNAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14L2ZvY3VzMTU2NTIzNjg1Njg0OQAxN1BZTy0xODEAHI/lSmaNu6Be85FZS46yxg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:58:51.768 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason error
Jicofo 2018-12-06 17:58:51.768 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 17:58:51.768 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 17:58:51.768 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:58:51.772 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 17:58:51.772 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 17:58:51.772 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 17:58:53.123 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:58:53.124 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 17:59:26.272 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:59:26.273 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.meet.lacajanegra.mx/focus1565236856849' from='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='Zm9jdXNAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14L2ZvY3VzMTU2NTIzNjg1Njg0OQAxN1BZTy0xOTEAHI/lSmaNu6Be85FZS46yxg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:59:26.274 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason error
Jicofo 2018-12-06 17:59:26.274 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 17:59:26.274 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 17:59:26.274 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 17:59:26.276 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 17:59:26.279 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 17:59:26.279 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 17:59:26.280 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 17:59:27.384 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 17:59:27.384 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 18:00:00.880 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 18:00:00.880 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 18:00:00.882 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.meet.lacajanegra.mx/focus1565236856849' from='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='Zm9jdXNAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14L2ZvY3VzMTU2NTIzNjg1Njg0OQAxN1BZTy0yMDEAHI/lSmaNu6Be85FZS46yxg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 18:00:00.882 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason error
Jicofo 2018-12-06 18:00:00.882 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 18:00:00.882 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 18:00:00.882 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 18:00:00.886 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 18:00:00.886 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 18:00:00.886 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 18:00:02.227 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 18:00:02.228 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 18:00:35.869 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 18:00:35.870 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 18:00:35.870 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.meet.lacajanegra.mx/focus1565236856849' from='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='Zm9jdXNAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14L2ZvY3VzMTU2NTIzNjg1Njg0OQAxN1BZTy0yMTEAHI/lSmaNu6Be85FZS46yxg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 18:00:35.870 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason error
Jicofo 2018-12-06 18:00:35.870 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 18:00:35.871 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2018-12-06 18:00:35.871 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname for stream ID: null in room: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 18:00:35.874 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status pending and failure reason null
Jicofo 2018-12-06 18:00:35.875 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname
Jicofo 2018-12-06 18:00:35.875 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Successfully resumed session with another Jibri
Jicofo 2018-12-06 18:00:37.006 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='busy'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 18:00:37.006 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: false
Jicofo 2018-12-06 18:01:00.129 INFO: [131] org.jitsi.jicofo.recording.jibri.JibriSession.log() Trying to stop: <iq to='jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname' id='QDQ7e-64504' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='stop'/></iq>
Jicofo 2018-12-06 18:01:10.210 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.onInstanceStatusChanged().136 Received Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname status <jibri-status xmlns='http://jitsi.org/protocol/jibri'><busy-status xmlns='http://jitsi.org/protocol/jibri' status='idle'/><health-status xmlns='http://jitsi.org/protocol/health' status='healthy'/></jibri-status>
Jicofo 2018-12-06 18:01:10.211 INFO: [37] org.jitsi.jicofo.recording.jibri.JibriDetector.notifyJibriStatus().174 Jibri: jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname available: true
Jicofo 2018-12-06 18:01:10.212 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.meet.lacajanegra.mx/jibri-nickname has status off and failure reason null
Jicofo 2018-12-06 18:01:10.212 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2018-12-06 18:01:10.212 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri session ended cleanly, notifying owner and cleaning up session
Jicofo 2018-12-06 18:01:10.213 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Got jibri status off and failure null
Jicofo 2018-12-06 18:01:10.213 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Publishing new jibri-recording-status: <jibri-recording-status xmlns='http://jitsi.org/protocol/jibri' status='off' session_id='iwcbbxxsmjgjqteg' recording_mode='file'/> in: easygorillasapproachobsessively@conference.meet.lacajanegra.mx
Jicofo 2018-12-06 18:01:10.213 INFO: [130] org.jitsi.jicofo.recording.jibri.JibriSession.log() Cleaning up current JibriSession

Jibri log

2018-12-06 17:58:51.758 INFO: [40] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: BUSY -> IDLE
2018-12-06 17:58:51.758 INFO: [40] org.jitsi.jibri.api.xmpp.XmppApi.invoke() Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(    healthStatus=HEALTHY, details={})), publishing presence to connection prod environment
2018-12-06 17:58:51.760 FINE: [40] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: error:recording
2018-12-06 17:58:51.761 INFO: [40] org.jitsi.jibri.api.xmpp.XmppApi.run() Sending start service response iq: <iq     to='jibribrewery@internal.auth.meet.lacajanegra.mx/focus' id='17PYO-181' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off'     failure_reason='error'/></iq>
2018-12-06 17:58:51.767 INFO: [28] org.jitsi.jibri.api.xmpp.XmppApi.handleJibriIq() Received JibriIq <iq     to='jibri@auth.meet.lacajanegra.mx/22cd44a3-fa66-4bce-b4b8-919704a14690' from='jibribrewery@internal.auth.meet.lacajanegra.mx/focus'     id='amlicmlAYXV0aC5tZWV0LmxhY2FqYW5lZ3JhLm14LzIyY2Q0NGEzLWZhNjYtNGJjZS1iNGI4LTkxOTcwNGExNDY5MABRRFE3ZS02NDM4MQCNJ9sqF7bmrPla9r4rmdGc' type='set'><jibri     xmlns='http://jitsi.org/protocol/jibri' action='start' recording_mode='file' room='easygorillasapproachobsessively@conference.meet.lacajanegra.mx'     session_id='iwcbbxxsmjgjqteg'/></iq> from environment prod environment
2018-12-06 17:58:51.767 INFO: [28] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Received start request
2018-12-06 17:58:51.767 INFO: [28] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Sending 'pending' response to start IQ
2018-12-06 17:58:51.767 INFO: [40] org.jitsi.jibri.api.xmpp.XmppApi.run() Starting service
2018-12-06 17:58:51.768 INFO: [40] org.jitsi.jibri.api.xmpp.XmppApi.handleStartService() Parsed call url info: CallUrlInfo(baseUrl=https://meet.lacajanegra.mx,     callName=easygorillasapproachobsessively, urlParams=[])
2018-12-06 17:58:51.768 INFO: [40] org.jitsi.jibri.JibriManager.startFileRecording() Starting a file recording with params: FileRecordingRequestParams(    callParams=CallParams(callUrlInfo=CallUrlInfo(baseUrl=https://meet.lacajanegra.mx, callName=easygorillasapproachobsessively, urlParams=[])),     sessionId=iwcbbxxsmjgjqteg, callLoginParams=XmppCredentials(domain=recorder.meet.lacajanegra.mx, username=recorder, password=jibrirecorderpass)) finalize     script path: /srv/scripts/finalize.sh and recordings directory: /srv/recordings
2018-12-06 17:58:53.108 INFO: [40] org.openqa.selenium.remote.ProtocolHandshake.createSession() Detected dialect: OSS
2018-12-06 17:58:53.110 FINE: [40] org.jitsi.jibri.capture.ffmpeg.FfmpegCapturer.<init>() Detected os as OS: LINUX
2018-12-06 17:58:53.111 INFO: [40] org.jitsi.jibri.service.impl.FileRecordingJibriService.<init>() Writing recording to /srv/recordings/iwcbbxxsmjgjqteg
2018-12-06 17:58:53.111 FINE: [40] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: start:recording
2018-12-06 17:58:53.116 INFO: [40] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: IDLE -> BUSY
2018-12-06 17:58:53.116 INFO: [40] org.jitsi.jibri.api.xmpp.XmppApi.invoke() Jibri reports its status is now JibriStatus(busyStatus=BUSY, health=OverallHealth(    healthStatus=HEALTHY, details={})), publishing presence to connection prod environment
2018-12-06 17:58:54.741 FINE: [40] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Visiting url https://meet.lacajanegra.mx/easygorillasapproachobsessivel    y#config.iAmRecorder=true&config.externalConnectUrl=null&config.startWithAudioMuted=true&config.startWithVideoMuted=true&interfaceConfig.APP_NAME="Jibri"
2018-12-06 17:59:25.919 SEVERE: [40] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Timed out waiting for call page to load
2018-12-06 17:59:25.920 SEVERE: [40] org.jitsi.jibri.service.impl.FileRecordingJibriService.start() Selenium failed to join the call
2018-12-06 17:59:25.920 FINE: [40] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: stop:recording
2018-12-06 17:59:25.921 INFO: [40] org.jitsi.jibri.JibriManager.stopService() Stopping the current service
2018-12-06 17:59:25.922 INFO: [40] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Stopping capturer
2018-12-06 17:59:25.922 INFO: [40] org.jitsi.jibri.capture.ffmpeg.executor.FfmpegExecutor.stopFfmpeg() Stopping ffmpeg process
2018-12-06 17:59:25.922 INFO: [40] org.jitsi.jibri.capture.ffmpeg.executor.FfmpegExecutor.stopFfmpeg() Ffmpeg exited with value null
2018-12-06 17:59:25.922 INFO: [40] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Quitting selenium
2018-12-06 17:59:25.929 INFO: [40] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Participants in this recording: []
2018-12-06 17:59:25.991 INFO: [40] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 12 log entries for type browser
2018-12-06 17:59:26.068 INFO: [40] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 943 log entries for type driver
2018-12-06 17:59:26.170 INFO: [40] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 0 log entries for type client
2018-12-06 17:59:26.170 INFO: [40] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Leaving web call
2018-12-06 17:59:26.176 INFO: [40] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Quitting chrome driver
2018-12-06 17:59:26.262 INFO: [40] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Chrome driver quit
2018-12-06 17:59:26.263 INFO: [40] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Finalizing the recording

#5

Jibri is waiting for the finalize script to finish, what do you have in there? Whatever it is seems like it’s taking a long time/forever.


#6

Hi,

The popup remains static as it is not able to start a Jibri recording. The selenium connection is failing.
Before the finalize script, the Selenium is failing to connect, hence the ffmpeg command isn’t triggered. I don’t know the cause for this. But there should be a problem with Chromedriver/Selenium.

Abhijit,


#7

This is not the case. You can see in the Jibri logs that it fails to join the call (waiting for the call page to load times out). Jibri then quits selenium and finalizes the recording by running the finalize script (which doesn’t make sense here, but Jibri doesn’t know that). No logs are printed after that, so I’m thinking at this point that whatever is in the finalize script is hung. Jibri won’t report the final error until that finishes.


#8

Hello @bbaldino, the script is an empty file, just the line:

#!/bin/bash

#9

Ok, if that’s the case then it must have gotten hung up after that. We don’t do much after the service has stopped before publishing that it’s idle again (which should clear that pop up): https://github.com/jitsi/jibri/blob/master/src/main/kotlin/org/jitsi/jibri/JibriManager.kt#L244, so it’s odd we don’t see the publishing of the IDLE status after the service is stopped.