Preparing to record the meeting -> Recording failed to start

Hello all. I am stuck with this error for a while. Any help or guide would be great. I setup Jitsi and Jibri into separate instances. Both of them are running AWS and both of them have 2 CPUs and 4GB RAM.

Video Bridge is running on another instance. I tried to telnet from the Jibri server to video bridge over UDP port 10000 and it succeeded.

When I try to start recording, I am getting an error. It says “Preparing for recording” but after some time, it says recording has stopped. I have double-checked configuration files. All looks good but let me share those with you. Maybe I am missing something.

/etc/jitsi/jibri/jibri.conf

jibri {
  api {
    http {
      external-api-port = 2222
      internal-api-port = 3333
    }
    xmpp {
      environments = [
        {   
          name = "room-test5" 
          xmpp-server-hosts = [ "room-test4.mydomain.com" ]
          xmpp-domain = "room-test4.mydomain.com"
          control-muc {
            domain = "internal.auth.room-test4.mydomain.com"
            room-name = "JibriBrewery"
            nickname = "jibrinickname"
          }
          control-login {
            domain = "auth.room-test4.mydomain.com"
            username = "jibri"
            password = "jibriauthpass"
          }
          sip-control-muc {
            domain = "internal.auth.room-test4.mydomain.com"
            room-name = "JibriBrewery"
            nickname = "jibrinickname"
          }
          call-login { 
            domain = "recorder.room-test4.mydomain.com"
            username = "recorder"
            password = "jibrirecorderpass"
          }
          strip-from-room-domain = "conference."
          usage-timeout = 0
          trust-all-xmpp-certs = true
        }
      ]
    }
  }
  recording {
    recordings-directory = "/tmp/recordings"
    finalize-script = "/home/finalize_recording.sh"
  }
  stats {
    enable-stats-d = true
  }
  chrome {
    flags = [
      "--use-fake-ui-for-media-stream",
      "--start-maximized",
      "--kiosk",
      "--enabled",
      "--disable-infobars",
      "--autoplay-policy=no-user-gesture-required",
      "--disable-setuid-sandbox",
      "--disable-web-security",
      "--disable-dev-shm-usage",
      "--ignore-certificate-errors",
      "--no-sandbox"
    ]
  }
}

/etc/prosody/conf.avail/room-test4.mydomain.com.cfg.lua

-- internal muc component
Component "internal.auth.room-test4.mydomain.com" "muc"
    storage = "memory"
    muc_room_cache_size = 1000
    modules_enabled = {
      "ping";
    }
    admins = { "focus@auth.room-test4.mydomain.com", "jvb@auth.room-test4.mydomain.com" }
    muc_room_locking = false
    muc_room_default_public_jids = true

VirtualHost "recorder.room-test4.mydomain.com"
  modules_enabled = {
     "ping";
  }
  authentication = "internal_plain"

/var/lib/prosody/auth.room-test4.mydomain.com/accounts/jibri.dat

return {
	["password"] = "jibriauthpass";
};

/var/lib/prosody/recorder.room-test4.mydomain.com/accounts/recorder.dat

return {
	["password"] = "jibrirecorderpass";
};

jicofo.log

Jicofo 2020-11-30 15:42:26.202 INFO: [364] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname
Jicofo 2020-11-30 15:42:26.202 INFO: [364] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname for stream ID: null in room: recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 15:42:26.613 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname 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 2020-11-30 15:42:26.613 INFO: [364] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.room-test4.mydomain.com/focus147327848174237' from='jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname' id='cVafs-13627' type='result'><jibri xmlns='http://jitsi.org/protocol/jibri' status='pending'/></iq> for recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 15:42:26.613 INFO: [364] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname has status pending and failure reason null, current Jibri jid is jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname
Jicofo 2020-11-30 15:42:26.613 INFO: [364] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Started Jibri session
Jicofo 2020-11-30 15:42:26.613 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname available: false
Jicofo 2020-11-30 15:42:58.451 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.room-test4.mydomain.com/focus147327848174237' from='jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname' id='Zm9jdXNAYXV0aC5yb29tLXRlc3Q0LjExc2lnaHQuY29tL2ZvY3VzMTQ3MzI3ODQ4MTc0MjM3AHNPTWZKLTUxNgDLymDsbEqllectyBzeInjz' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq> for recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 15:42:58.451 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname has status off and failure reason error, current Jibri jid is jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname
Jicofo 2020-11-30 15:42:58.451 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2020-11-30 15:42:58.451 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2020-11-30 15:42:58.451 SEVERE: [373] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start
Jicofo 2020-11-30 15:42:58.452 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriSession.log() Failed to fall back to another Jibri, this session has now failed: org.jitsi.jicofo.recording.jibri.JibriSession$StartException: All Jibri instances are busy
org.jitsi.jicofo.recording.jibri.JibriSession$StartException: All Jibri instances are busy
	at org.jitsi.jicofo.recording.jibri.JibriSession.startInternal(JibriSession.java:359)
	at org.jitsi.jicofo.recording.jibri.JibriSession.start(JibriSession.java:335)
	at org.jitsi.jicofo.recording.jibri.JibriSession.retryRequestWithAnotherJibri(JibriSession.java:649)
	at org.jitsi.jicofo.recording.jibri.JibriSession.handleJibriStatusUpdate(JibriSession.java:719)
	at org.jitsi.jicofo.recording.jibri.JibriSession.processJibriIqFromJibri(JibriSession.java:501)
	at org.jitsi.jicofo.recording.jibri.JibriSession.processJibriIqRequestFromJibri(JibriSession.java:482)
	at org.jitsi.jicofo.recording.jibri.CommonJibriStuff.handleIQRequest(CommonJibriStuff.java:247)
	at org.jitsi.jicofo.recording.jibri.OperationSetJibri.handleIQRequest(OperationSetJibri.java:98)
	at org.jivesoftware.smack.AbstractXMPPConnection$4.run(AbstractXMPPConnection.java:1188)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
Jicofo 2020-11-30 15:42:58.452 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Got jibri status off and failure error
Jicofo 2020-11-30 15:42:58.452 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Publishing new jibri-recording-status: <jibri-recording-status xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' session_id='ulvxsztmdjskokmk' recording_mode='file'/> in: recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 15:42:58.453 INFO: [373] org.jitsi.jicofo.recording.jibri.JibriSession.log() Cleaning up current JibriSession
Jicofo 2020-11-30 15:42:58.940 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname 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 2020-11-30 15:42:58.940 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname available: true

jibri.log (log.0.txt)

2020-11-30 15:42:08.964 FINE: [16] org.jitsi.jibri.webhooks.v1.WebhookClient.invokeSuspend() Updating 0 subscribers of status
2020-11-30 15:42:26.203 INFO: [298] org.jitsi.jibri.api.xmpp.XmppApi.handleJibriIq() Received JibriIq <iq to='jibri@auth.room-test4.mydomain.com/iJxHhix8' from='jibribrewery@internal.auth.room-test4.mydomain.com/focus' id='amlicmlAYXV0aC5yb29tLXRlc3Q0LjExc2lnaHQuY29tL2lKeEhoaXg4AGNWYWZzLTEzNjI3AAMBAebkMkiSGyjur/tOsrU=' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='start' recording_mode='file' room='recording-test@conference.r.room-test4.mydomain.com' session_id='ulvxsztmdjskokmk' app_data='{"file_recording_metadata":{"share":true}}'/></iq> from environment [MucClient id=room-test4.mydomain.com hostname=room-test4.mydomain.com]
2020-11-30 15:42:26.204 INFO: [298] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Received start request, starting service
2020-11-30 15:42:26.209 INFO: [298] org.jitsi.jibri.api.xmpp.XmppApi.handleStartService() Parsed call url info: CallUrlInfo(baseUrl=https://room-test4.mydomain.com/r, callName=recording-test, urlParams=[])
2020-11-30 15:42:26.209 INFO: [298] org.jitsi.jibri.JibriManager.startFileRecording() Starting a file recording with params: FileRecordingRequestParams(callParams=CallParams(callUrlInfo=CallUrlInfo(baseUrl=https://room-test4.mydomain.com/r, callName=recording-test, urlParams=[])), sessionId=ulvxsztmdjskokmk, callLoginParams=XmppCredentials(domain=recorder.room-test4.mydomain.com, username=recorder, password=jibrirecorderpass))
2020-11-30 15:42:26.596 INFO: [298] org.openqa.selenium.remote.ProtocolHandshake.createSession() Detected dialect: OSS
2020-11-30 15:42:26.605 FINE: [298] org.jitsi.jibri.capture.ffmpeg.FfmpegCapturer.<init>() Detected os as OS: LINUX
2020-11-30 15:42:26.607 INFO: [298] org.jitsi.jibri.service.impl.FileRecordingJibriService.<init>() Writing recording to /tmp/recordings/ulvxsztmdjskokmk, finalize script path /home/finalize_recording.sh
2020-11-30 15:42:26.607 FINE: [298] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: start:recording
2020-11-30 15:42:26.608 INFO: [298] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: IDLE -> BUSY
2020-11-30 15:42:26.608 FINE: [298] org.jitsi.jibri.webhooks.v1.WebhookClient.invokeSuspend() Updating 0 subscribers of status
2020-11-30 15:42:26.608 INFO: [298] org.jitsi.jibri.api.xmpp.XmppApi.updatePresence() Jibri reports its status is now JibriStatus(busyStatus=BUSY, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2020-11-30 15:42:26.609 INFO: [298] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Sending 'pending' response to start IQ
2020-11-30 15:42:27.432 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Visiting url https://room-test4.mydomain.com/r/recording-test#config.iAmRecorder=true&config.externalConnectUrl=null&config.startWithAudioMuted=true&config.startWithVideoMuted=true&interfaceConfig.APP_NAME="Jibri"&config.analytics.disabled=true&config.p2p.enabled=false&config.prejoinPageEnabled=false&config.requireDisplayName=false
2020-11-30 15:42:27.959 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:28.466 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:28.978 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:29.485 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:29.993 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:30.501 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:31.008 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:31.516 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:32.025 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:32.533 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:33.043 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:33.550 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:34.058 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:34.565 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:35.073 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:35.580 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:36.087 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:36.596 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:37.110 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:37.618 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:38.125 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:38.635 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:39.143 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:39.651 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:40.160 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:40.668 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:41.177 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:41.685 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:42.192 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:42.700 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:43.208 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:43.716 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:44.223 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:44.732 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:45.239 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:45.748 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:46.255 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:46.762 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:47.271 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:47.781 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:48.287 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:48.803 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:49.311 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:49.818 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:50.327 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:50.835 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:51.342 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:51.849 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:52.356 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:52.863 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:53.371 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:53.878 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:54.385 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:54.894 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:55.402 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:55.909 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:56.417 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:56.925 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:57.433 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:57.940 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:58.447 FINE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 15:42:58.448 SEVERE: [309] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Timed out waiting for call page to load
2020-11-30 15:42:58.448 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.onSeleniumStateChange() Transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
2020-11-30 15:42:58.448 INFO: [309] org.jitsi.jibri.service.impl.FileRecordingJibriService.onServiceStateChange() File recording service transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
2020-11-30 15:42:58.448 INFO: [309] org.jitsi.jibri.api.xmpp.XmppApi.invoke() Current service had an error Error: FailedToJoinCall SESSION Failed to join the call, sending error iq <iq to='jibribrewery@internal.auth.room-test4.mydomain.com/focus' id='sOMfJ-516' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq>
2020-11-30 15:42:58.448 FINE: [309] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: stop:recording
2020-11-30 15:42:58.449 INFO: [309] org.jitsi.jibri.JibriManager.stopService() Stopping the current service
2020-11-30 15:42:58.449 INFO: [309] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Stopping capturer
2020-11-30 15:42:58.453 INFO: [309] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() Stopping ffmpeg process
2020-11-30 15:42:58.453 INFO: [309] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() ffmpeg exited with value null
2020-11-30 15:42:58.453 INFO: [309] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Quitting selenium
2020-11-30 15:42:58.462 INFO: [309] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Participants in this recording: []
2020-11-30 15:42:58.468 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Leaving call and quitting browser
2020-11-30 15:42:58.468 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Recurring call status checks cancelled
2020-11-30 15:42:58.483 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 6 log entries for type browser
2020-11-30 15:42:58.511 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 973 log entries for type driver
2020-11-30 15:42:58.799 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 0 log entries for type client
2020-11-30 15:42:58.800 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Leaving web call
2020-11-30 15:42:58.852 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Quitting chrome driver
2020-11-30 15:42:58.923 INFO: [309] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Chrome driver quit
2020-11-30 15:42:58.923 INFO: [309] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Finalizing the recording
2020-11-30 15:42:58.926 INFO: [309] org.jitsi.jibri.service.impl.FileRecordingJibriService.finalize() Recording finalize script finished with exit value 0
2020-11-30 15:42:58.927 INFO: [309] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: BUSY -> IDLE
2020-11-30 15:42:58.927 FINE: [309] org.jitsi.jibri.webhooks.v1.WebhookClient.invokeSuspend() Updating 0 subscribers of status
2020-11-30 15:42:58.927 INFO: [309] org.jitsi.jibri.api.xmpp.XmppApi.updatePresence() Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections

Both Jibri and Jitsi instances are using the same AWS security group:

Where should I keep looking? It looks like it is a connection issue. I assume Jibri is not able to connect after getting the start recording signal. Or maybe it is something else? Does anyone have any idea?

P.S In jibri.conf I have set external-api-port = 2222 and internal-api-port = 3333 so should I allow those ports in AWS Securty Group as well?

Thanks in advance.

Did you check this?

Yes, I forgot to mention that tutorial. I have checked it and your comments under that thread. jibri user has permission to write recordings folder. Actually, it creates folder and puts metadata.json under the folder but of course mp4 is missing.

I believe your cfg.lua is not properly configured. Check that segment in the tutorial.

Btw, you’re using java 8, right?

Let me share my cfg.lua file:

plugin_paths = { "/usr/share/jitsi-meet/prosody-plugins/" }

-- domain mapper options, must at least have domain base set to use the mapper
muc_mapper_domain_base = "room-test4.mydomain.com";

turncredentials_secret = "cwrnKvsiUNKXa6g4";

turncredentials = {
  { type = "stun", host = "room-test4.mydomain.com", port = "3478" },
  { type = "turn", host = "room-test4.mydomain.com", port = "3478", transport = "udp" },
  { type = "turns", host = "room-test4.mydomain.com", port = "5349", transport = "tcp" }
};

cross_domain_bosh = false;
consider_bosh_secure = true;
https_ports = { }; -- Remove this line to prevent listening on port 5284

-- https://ssl-config.mozilla.org/#server=haproxy&version=2.1&config=intermediate&openssl=1.1.0g&guideline=5.4
ssl = {
  protocol = "tlsv1_2+";
  ciphers = "ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-CHACHA20-POLY1305:DHE-RSA-AES128-GCM-SHA256:DHE-RSA-AES256-GCM-SHA384"
}

VirtualHost "room-test4.mydomain.com"
        -- enabled = false -- Remove this line to enable this host
        authentication = "anonymous"
        -- Properties below are modified by jitsi-meet-tokens package config
        -- and authentication above is switched to "token"
        --app_id="example_app_id"
        --app_secret="example_app_secret"
	 app_id=""
	 app_secret=""
        -- Assign this host a certificate for TLS, otherwise it would use the one
        -- set in the global section (if any).
        -- Note that old-style SSL on port 5223 only supports one certificate, and will always
        -- use the global one.
        ssl = {
                key = "/etc/prosody/certs/room-test4.mydomain.com.key";
                certificate = "/etc/prosody/certs/room-test4.mydomain.com.crt";
        }
        speakerstats_component = "speakerstats.room-test4.mydomain.com"
        conference_duration_component = "conferenceduration.room-test4.mydomain.com"
        -- we need bosh
        modules_enabled = {
            "bosh";
            "pubsub";
            "ping"; -- Enable mod_ping
            "speakerstats";
            "turncredentials";
            "conference_duration";
           -- "muc_lobby_rooms";
	    "muc_size";
        }
        c2s_require_encryption = false
        --lobby_muc = "lobby.room-test4.mydomain.com"
       -- main_muc = "conference.room-test4.mydomain.com"
        -- muc_lobby_whitelist = { "recorder.room-test4.mydomain.com" } -- Here we can whitelist jibri to enter lobby enabled rooms

Component "conference.room-test4.mydomain.com" "muc"
    storage = "memory"
    modules_enabled = {
        "muc_meeting_id";
        "muc_domain_mapper";
        -- "token_verification";
    }
    admins = { "focus@auth.room-test4.mydomain.com","focus@room-test4.mydomain.com" }
    muc_room_locking = false
    muc_room_default_public_jids = true


VirtualHost "auth.room-test4.mydomain.com"
    ssl = {
        key = "/etc/prosody/certs/auth.room-test4.mydomain.com.key";
        certificate = "/etc/prosody/certs/auth.room-test4.mydomain.com.crt";
    }
    authentication = "internal_plain"


VirtualHost "guest.room-test4.mydomain.com"
    app_id=""
    app_secret=""
    authentication = "anonymous"
    ssl = {
            key = "/etc/prosody/certs/auth.room-test4.mydomain.com.key";
            certificate = "/etc/prosody/certs/auth.room-test4.mydomain.com.crt";
        }

 modules_enabled = {
                 "bosh";
                 "pubsub";
                 "ping"; -- Enable mod_ping
                 "muc_size";
             }
 allow_empty_token = true
 c2s_require_encryption = false

Component "focus.room-test4.mydomain.com"
    component_secret = "@lFYnWb1"

Component "speakerstats.room-test4.mydomain.com" "speakerstats_component"
    muc_component = "conference.room-test4.mydomain.com"

Component "conferenceduration.room-test4.mydomain.com" "conference_duration_component"
    muc_component = "conference.room-test4.mydomain.com"

Component "lobby.room-test4.mydomain.com" "muc"
    storage = "memory"
    muc_room_cache_size = 100
    restrict_room_creation = true
    muc_room_locking = false
    muc_room_default_public_jids = true

-- internal muc component
Component "internal.auth.room-test4.mydomain.com" "muc"
    storage = "memory"
    muc_room_cache_size = 1000
    modules_enabled = {
      "ping";
    }
    admins = { "focus@auth.room-test4.mydomain.com", "jvb@auth.room-test4.mydomain.com", "jibri@auth.room-test4.mydomain.com" }
    muc_room_locking = false
    muc_room_default_public_jids = true

VirtualHost "recorder.room-test4.mydomain.com"
  modules_enabled = {
     "ping";
  }
  authentication = "internal_plain"

Yes, I am using Java 8. Here is output of java -version

openjdk version "1.8.0_275"
OpenJDK Runtime Environment (build 1.8.0_275-8u275-b01-0ubuntu1~18.04-b01)
OpenJDK 64-Bit Server VM (build 25.275-b01, mixed mode)

Again, it’s wrong. Check that section in the tutorial and compare. The entire block has to be added to the end of your cfg.lua.

Would you mind if I ask you to elaborate a bit more? Because if I am not mistaken this following block is at the end of the file:

Component "internal.auth.room-test4.mydomain.com" "muc"
    storage = "memory"
    muc_room_cache_size = 1000
    modules_enabled = {
      "ping";
    }
    admins = { "focus@auth.room-test4.mydomain.com", "jvb@auth.room-test4.mydomain.com", "jibri@auth.room-test4.mydomain.com" }
    muc_room_locking = false
    muc_room_default_public_jids = true

VirtualHost "recorder.room-test4.mydomain.com"
  modules_enabled = {
     "ping";
  }
  authentication = "internal_plain"

Nothing comes after it. Is it what you mean?

Screen Shot 2020-11-22 at 4.40.41 AM

Do you see a difference? I don’t want to just point it out because I want to help you to become astute at discovering these errors. That way you can easily fix it for yourself - or someone else - next time.

Pls forgive me but only difference I see, some additional attributes and order of the attributes. So I commented out those additional attributes and made same ordering of the attributes. Last state is looking like this:

-- internal muc component
Component "internal.auth.room-test4.mydomain.com" "muc"
    modules_enabled = {
      "ping";
    }
    storage = "memory"
    muc_room_cache_size = 1000
    -- admins = { "focus@auth.room-test4.mydomain.com", "jvb@auth.room-test4.mydomain.com", "jibri@auth.room-test4.mydomain.com" }
    -- muc_room_locking = false
    -- muc_room_default_public_jids = true

VirtualHost "recorder.room-test4.mydomain.com"
  modules_enabled = {
     "ping";
  }
  authentication = "internal_plain"

Maybe I went blind after working on this issue for hours.

LOL… no problem.

So your cfg.lua already contains this block

You don’t touch that. At the end of your cfg.lua file, you should add EVERYTHING here:

The confusion for you appears to be the fact that both start with "Component “internal.auth…” but they’re different. You need both.

Please correct me if I am wrong. Currently, I have /etc/prosody/prosody.cfg.lua file and at the end of the file there is Include command

Include "conf.d/*.cfg.lua"

And in the /etc/prosody/conf.avail/room-test4.mydomain.com.cfg.lua file and at the end of that file there is exactly this block:

-- internal muc component
Component "internal.auth.room-test4.mydomain.com" "muc"
    storage = "memory"
    muc_room_cache_size = 1000
    modules_enabled = {
      "ping";
    }
    admins = { "focus@auth.room-test4.mydomain.com", "jvb@auth.room-test4.mydomain.com" }
    muc_room_locking = false
    muc_room_default_public_jids = true

VirtualHost "recorder.room-test4.mydomain.com"
  modules_enabled = {
     "ping";
  }
  authentication = "internal_plain"

So what you are saying is don’t touch the block that starts with Component "internal.auth... and add another one which you mentioned in a previous comment of yours, right before VirtualHost "recorder.mydomain.com". Am I correct? So there will be two
Component "internal.auth.room-test4.mydomain.com" "muc"?
Sorry if that is dumb question

Exactly.
Don’t touch what’s there already, then add the entire thing in the screenshot.

Currently, my /etc/prosody/conf.avail/room-test4.mydomain.com.cfg.lua file looks like this:

...
Component "internal.auth.room-test4.mydomain.com" "muc"
    modules_enabled = {
      "ping";
    }
    storage = "memory"
    muc_room_cache_size = 1000
    admins = { "focus@auth.room-test4.mydomain.com", "jvb@auth.room-test4.mydomain.com", "jibri@auth.room-test4.mydomain.com" }
    muc_room_locking = false
    muc_room_default_public_jids = true

Component "internal.auth.room-test4.mydomain.com" "muc"
    storage = "memory"
    muc_room_cache_size = 1000
    modules_enabled = {
      "ping";
    }

VirtualHost "recorder.room-test4.mydomain.com"
    modules_enabled = {
      "ping";
    }
    authentication = "internal_plain"

So there is two Component "internal.auth.room-test4.mydomain.com" and the end of file is looking exactly same with screenshot. However, I keep getting the same error. After some time selenium throws an error and says

2020-11-30 17:16:15.050 INFO: [570] org.jitsi.jibri.selenium.JibriSelenium.onSeleniumStateChange() Transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call

Do you any idea that I should keep digging?

Share your Jibri log.

This is unfamiliar. Did you explicitly call this somewhere?

here is jibri log right after restarting and trying to record the call.

2020-11-30 17:33:24.286 INFO: [1] org.jitsi.jibri.Main.handleCommandLineArgs() Jibri run with args [--config, /etc/jitsi/jibri/config.json]
2020-11-30 17:33:24.389 INFO: [1] org.jitsi.jibri.Main.setupLegacyConfig() Checking legacy config file /etc/jitsi/jibri/config.json
2020-11-30 17:33:24.389 INFO: [1] org.jitsi.jibri.Main.setupLegacyConfig() Legacy config file /etc/jitsi/jibri/config.json doesn't exist
2020-11-30 17:33:25.250 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.Companion.fromConfig() got jwtConfig: {}

2020-11-30 17:33:25.250 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.Companion.fromConfig() Unable to create JwtInfo: com.typesafe.config.ConfigException$Missing: reference.conf @ jar:file:/opt/jitsi/jibri/jibri.jar!/reference.conf: 52: No configuration setting found for key 'signing-key-path'
2020-11-30 17:33:25.408 INFO: [1] org.jitsi.jibri.Main.main() Using port 3333 for internal HTTP API
2020-11-30 17:33:25.429 FINE: [16] org.jitsi.jibri.webhooks.v1.WebhookClient.invokeSuspend() Updating 0 subscribers of status
2020-11-30 17:33:25.817 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.updatePresence() Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2020-11-30 17:33:25.826 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.start() Connecting to xmpp environment on room-test4.mydomain.com with config XmppEnvironmentConfig(name=room-test5, xmppServerHosts=[room-test4.mydomain.com], xmppDomain=room-test4.mydomain.com, controlLogin=XmppCredentials(domain=auth.room-test4.mydomain.com, username=jibri, password=jibriauthpass), controlMuc=XmppMuc(domain=internal.auth.room-test4.mydomain.com, roomName=JibriBrewery, nickname=jibrinickname), sipControlMuc=XmppMuc(domain=internal.auth.room-test4.mydomain.com, roomName=JibriBrewery, nickname=jibrinickname), callLogin=XmppCredentials(domain=recorder.room-test4.mydomain.com, username=recorder, password=jibrirecorderpass), stripFromRoomDomain=conference., usageTimeoutMins=0, trustAllXmppCerts=true)
2020-11-30 17:33:25.827 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
2020-11-30 17:33:25.854 INFO: [1] org.jitsi.jibri.Main.main() Using port 2222 for HTTP API
2020-11-30 17:33:25.863 WARNING: [25] org.jitsi.xmpp.mucclient.MucClient.log() Disabling certificate verification!
2020-11-30 17:33:26.185 INFO: [25] org.jitsi.xmpp.mucclient.MucClient.log() Connected.
2020-11-30 17:33:26.185 INFO: [25] org.jitsi.xmpp.mucclient.MucClient.log() Logging in.
2020-11-30 17:33:26.315 INFO: [25] org.jitsi.xmpp.mucclient.MucClient.log() Joined MUC: jibribrewery@internal.auth.room-test4.mydomain.com
2020-11-30 17:33:26.321 INFO: [25] org.jitsi.xmpp.mucclient.MucClient.log() Leaving a MUC we already occupy.
2020-11-30 17:33:26.331 INFO: [25] org.jitsi.xmpp.mucclient.MucClient.log() Joined MUC: jibribrewery@internal.auth.room-test4.mydomain.com
2020-11-30 17:33:41.404 INFO: [41] org.jitsi.jibri.api.xmpp.XmppApi.handleJibriIq() Received JibriIq <iq to='jibri@auth.room-test4.mydomain.com/Hsbzwfqu' from='jibribrewery@internal.auth.room-test4.mydomain.com/focus' id='amlicmlAYXV0aC5yb29tLXRlc3Q0LjExc2lnaHQuY29tL0hzYnp3ZnF1AGNWYWZzLTIwNzk0AAMBAebkMkiSGyjur/tOsrU=' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='start' recording_mode='file' room='recording-test@conference.r.room-test4.mydomain.com' session_id='vazdicabbvmwmjii' app_data='{"file_recording_metadata":{"share":true}}'/></iq> from environment [MucClient id=room-test4.mydomain.com hostname=room-test4.mydomain.com]
2020-11-30 17:33:41.405 INFO: [41] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Received start request, starting service
2020-11-30 17:33:41.575 INFO: [41] org.jitsi.jibri.api.xmpp.XmppApi.handleStartService() Parsed call url info: CallUrlInfo(baseUrl=https://room-test4.mydomain.com/r, callName=recording-test, urlParams=[])
2020-11-30 17:33:41.577 INFO: [41] org.jitsi.jibri.JibriManager.startFileRecording() Starting a file recording with params: FileRecordingRequestParams(callParams=CallParams(callUrlInfo=CallUrlInfo(baseUrl=https://room-test4.mydomain.com/r, callName=recording-test, urlParams=[])), sessionId=vazdicabbvmwmjii, callLoginParams=XmppCredentials(domain=recorder.room-test4.mydomain.com, username=recorder, password=jibrirecorderpass))
2020-11-30 17:33:42.453 INFO: [41] org.openqa.selenium.remote.ProtocolHandshake.createSession() Detected dialect: OSS
2020-11-30 17:33:42.487 FINE: [41] org.jitsi.jibri.capture.ffmpeg.FfmpegCapturer.<init>() Detected os as OS: LINUX
2020-11-30 17:33:42.496 INFO: [41] org.jitsi.jibri.service.impl.FileRecordingJibriService.<init>() Writing recording to /tmp/recordings/vazdicabbvmwmjii, finalize script path /home/finalize_recording.sh
2020-11-30 17:33:42.505 FINE: [41] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: start:recording
2020-11-30 17:33:42.507 INFO: [41] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: IDLE -> BUSY
2020-11-30 17:33:42.507 FINE: [41] org.jitsi.jibri.webhooks.v1.WebhookClient.invokeSuspend() Updating 0 subscribers of status
2020-11-30 17:33:42.508 INFO: [41] org.jitsi.jibri.api.xmpp.XmppApi.updatePresence() Jibri reports its status is now JibriStatus(busyStatus=BUSY, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2020-11-30 17:33:42.510 INFO: [41] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Sending 'pending' response to start IQ
2020-11-30 17:33:43.378 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Visiting url https://room-test4.mydomain.com/r/recording-test#config.iAmRecorder=true&config.externalConnectUrl=null&config.startWithAudioMuted=true&config.startWithVideoMuted=true&interfaceConfig.APP_NAME="Jibri"&config.analytics.disabled=true&config.p2p.enabled=false&config.prejoinPageEnabled=false&config.requireDisplayName=false
2020-11-30 17:33:43.866 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:44.375 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:44.884 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:45.394 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:45.903 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:46.416 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:46.929 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:47.438 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:47.947 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:48.460 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:48.970 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:49.478 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:49.987 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:50.495 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:51.004 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:51.513 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:52.021 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:52.530 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:53.039 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:53.548 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:54.056 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:54.565 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:55.075 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:55.584 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:56.092 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:56.600 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:57.109 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:57.617 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:58.126 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:58.635 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:59.143 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:33:59.653 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:00.162 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:00.675 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:01.183 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:01.692 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:02.201 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:02.709 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:03.218 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:03.730 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:04.241 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:04.750 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:05.262 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:05.770 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:06.278 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:06.791 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:07.299 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:07.816 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:08.323 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:08.831 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:09.340 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:09.848 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:10.356 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:10.865 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:11.377 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:11.887 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:12.396 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:12.904 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:13.412 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:13.923 FINE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.apply() Not joined yet: Cannot read property 'isJoined' of undefined
2020-11-30 17:34:13.935 SEVERE: [51] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Timed out waiting for call page to load
2020-11-30 17:34:13.937 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.onSeleniumStateChange() Transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
2020-11-30 17:34:13.939 INFO: [51] org.jitsi.jibri.service.impl.FileRecordingJibriService.onServiceStateChange() File recording service transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
2020-11-30 17:34:13.940 INFO: [51] org.jitsi.jibri.api.xmpp.XmppApi.invoke() Current service had an error Error: FailedToJoinCall SESSION Failed to join the call, sending error iq <iq to='jibribrewery@internal.auth.room-test4.mydomain.com/focus' id='0yzAq-32' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq>
2020-11-30 17:34:13.949 FINE: [51] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: stop:recording
2020-11-30 17:34:13.950 INFO: [51] org.jitsi.jibri.JibriManager.stopService() Stopping the current service
2020-11-30 17:34:13.950 INFO: [51] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Stopping capturer
2020-11-30 17:34:13.951 INFO: [51] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() Stopping ffmpeg process
2020-11-30 17:34:13.951 INFO: [51] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() ffmpeg exited with value null
2020-11-30 17:34:13.951 INFO: [51] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Quitting selenium
2020-11-30 17:34:13.967 INFO: [51] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Participants in this recording: []
2020-11-30 17:34:14.075 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Leaving call and quitting browser
2020-11-30 17:34:14.076 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Recurring call status checks cancelled
2020-11-30 17:34:14.123 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 6 log entries for type browser
2020-11-30 17:34:14.233 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 973 log entries for type driver
2020-11-30 17:34:14.762 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 0 log entries for type client
2020-11-30 17:34:14.764 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Leaving web call
2020-11-30 17:34:14.805 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Quitting chrome driver
2020-11-30 17:34:14.877 INFO: [51] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Chrome driver quit
2020-11-30 17:34:14.877 INFO: [51] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Finalizing the recording

No, I didn’t call it explicitly. On jibri installation, I only edited configurations.

Ok… maybe I’ve just never paid attention to it.
Can you please share your current jicofo log?

Btw did you restart services after making the change in cfg.lua?

I restarted prosody and jicofo services. VideoBridge is running on another instance. I didn’t restarted that one.

Here is jicofo.log after restarting and starting recording.

Jicofo 2020-11-30 17:40:52.738 INFO: [10] org.jitsi.service.libjitsi.LibJitsi.log() Successfully started LibJitsi using as implementation: org.jitsi.impl.libjitsi.LibJitsiOSGiImpl
Jicofo 2020-11-30 17:40:52.759 INFO: [10] impl.configuration.ConfigurationActivator.log() Using properties file configuration store.
Jicofo 2020-11-30 17:40:52.766 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.HOSTNAME=localhost
Jicofo 2020-11-30 17:40:52.767 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.runtime.name=OpenJDK Runtime Environment
Jicofo 2020-11-30 17:40:52.767 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.boot.library.path=/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/amd64
Jicofo 2020-11-30 17:40:52.767 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.version=25.275-b01
Jicofo 2020-11-30 17:40:52.767 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.vendor=Private Build
Jicofo 2020-11-30 17:40:52.768 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vendor.url=http://java.oracle.com/
Jicofo 2020-11-30 17:40:52.768 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() path.separator=:
Jicofo 2020-11-30 17:40:52.769 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.util.logging.config.file=/etc/jitsi/jicofo/logging.properties
Jicofo 2020-11-30 17:40:52.769 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.name=OpenJDK 64-Bit Server VM
Jicofo 2020-11-30 17:40:52.769 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() file.encoding.pkg=sun.io
Jicofo 2020-11-30 17:40:52.769 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.java.launcher=SUN_STANDARD
Jicofo 2020-11-30 17:40:52.770 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.os.patch.level=unknown
Jicofo 2020-11-30 17:40:52.770 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.service.media.DISABLE_AUDIO_SUPPORT=true
Jicofo 2020-11-30 17:40:52.770 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.specification.name=Java Virtual Machine Specification
Jicofo 2020-11-30 17:40:52.770 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() user.dir=/usr/share/jicofo
Jicofo 2020-11-30 17:40:52.771 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.FOCUS_USER_PASSWORD=hwbDHI4O
Jicofo 2020-11-30 17:40:52.771 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.runtime.version=1.8.0_275-8u275-b01-0ubuntu1~18.04-b01
Jicofo 2020-11-30 17:40:52.771 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment
Jicofo 2020-11-30 17:40:52.772 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.endorsed.dirs=/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/endorsed
Jicofo 2020-11-30 17:40:52.772 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() os.arch=amd64
Jicofo 2020-11-30 17:40:52.772 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.io.tmpdir=/tmp
Jicofo 2020-11-30 17:40:52.773 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() line.separator=

Jicofo 2020-11-30 17:40:52.773 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.specification.vendor=Oracle Corporation
Jicofo 2020-11-30 17:40:52.773 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() os.name=Linux
Jicofo 2020-11-30 17:40:52.773 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() config.file=/etc/jitsi/jicofo/jicofo.conf
Jicofo 2020-11-30 17:40:52.774 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.jnu.encoding=UTF-8
Jicofo 2020-11-30 17:40:52.774 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.CONFIGURATION_FILE_IS_READ_ONLY=true
Jicofo 2020-11-30 17:40:52.774 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.FOCUS_USER_DOMAIN=auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:52.775 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.library.path=/usr/java/packages/lib/amd64:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib
Jicofo 2020-11-30 17:40:52.775 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.specification.name=Java Platform API Specification
Jicofo 2020-11-30 17:40:52.776 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.class.version=52.0
Jicofo 2020-11-30 17:40:52.776 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.service.audionotifier.AudioNotifierService=org.jitsi.impl.neomedia.notify.AudioNotifierServiceImpl
Jicofo 2020-11-30 17:40:52.776 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.management.compiler=HotSpot 64-Bit Tiered Compilers
Jicofo 2020-11-30 17:40:52.776 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() os.version=5.4.0-1029-aws
Jicofo 2020-11-30 17:40:52.777 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() user.home=/usr/share/jicofo
Jicofo 2020-11-30 17:40:52.777 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() user.timezone=Etc/UTC
Jicofo 2020-11-30 17:40:52.777 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.awt.printerjob=sun.print.PSPrinterJob
Jicofo 2020-11-30 17:40:52.778 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.FOCUS_USER_NAME=focus
Jicofo 2020-11-30 17:40:52.778 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() file.encoding=UTF-8
Jicofo 2020-11-30 17:40:52.779 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.specification.version=1.8
Jicofo 2020-11-30 17:40:52.779 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.service.media.DISABLE_VIDEO_SUPPORT=true
Jicofo 2020-11-30 17:40:52.779 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.XMPP_DOMAIN=room-test4.mydomain.com
Jicofo 2020-11-30 17:40:52.779 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.class.path=/usr/share/jicofo/jicofo.jar:/usr/share/jicofo/lib/agafua-syslog-0.4.jar:/usr/share/jicofo/lib/annotations-15.0.jar:/usr/share/jicofo/lib/aopalliance-repackaged-2.6.1.jar:/usr/share/jicofo/lib/bccontrib-1.0.jar:/usr/share/jicofo/lib/bcpkix-jdk15on-1.54.jar:/usr/share/jicofo/lib/bcprov-jdk15on-1.54.jar:/usr/share/jicofo/lib/cglib-nodep-2.2.jar:/usr/share/jicofo/lib/checker-qual-2.11.1.jar:/usr/share/jicofo/lib/commons-codec-1.6.jar:/usr/share/jicofo/lib/commons-lang3-3.1.jar:/usr/share/jicofo/lib/commons-logging-1.2.jar:/usr/share/jicofo/lib/concurrentlinkedhashmap-lru-1.0_jdk5.jar:/usr/share/jicofo/lib/config-1.3.4.jar:/usr/share/jicofo/lib/core-2.0.1.jar:/usr/share/jicofo/lib/dnsjava-2.1.7.jar:/usr/share/jicofo/lib/dom4j-1.6.1.jar:/usr/share/jicofo/lib/error_prone_annotations-2.3.4.jar:/usr/share/jicofo/lib/failureaccess-1.0.1.jar:/usr/share/jicofo/lib/fmj-1.0-SNAPSHOT.jar:/usr/share/jicofo/lib/guava-29.0-jre.jar:/usr/share/jicofo/lib/hk2-api-2.6.1.jar:/usr/share/jicofo/lib/hk2-locator-2.6.1.jar:/usr/share/jicofo/lib/hk2-utils-2.6.1.jar:/usr/share/jicofo/lib/httpclient-4.4.1.jar:/usr/share/jicofo/lib/httpcore-4.4.1.jar:/usr/share/jicofo/lib/ice4j-3.0-21-g3a55627.jar:/usr/share/jicofo/lib/j2objc-annotations-1.3.jar:/usr/share/jicofo/lib/jackson-annotations-2.10.1.jar:/usr/share/jicofo/lib/jackson-core-2.10.0.jar:/usr/share/jicofo/lib/jackson-databind-2.10.1.jar:/usr/share/jicofo/lib/jackson-module-jaxb-annotations-2.10.1.jar:/usr/share/jicofo/lib/jain-sip-ri-ossonly-1.2.98c7f8c-jitsi-oss1.jar:/usr/share/jicofo/lib/jakarta.activation-api-1.2.1.jar:/usr/share/jicofo/lib/jakarta.annotation-api-1.3.5.jar:/usr/share/jicofo/lib/jakarta.inject-2.6.1.jar:/usr/share/jicofo/lib/jakarta.validation-api-2.0.2.jar:/usr/share/jicofo/lib/jakarta.ws.rs-api-2.1.6.jar:/usr/share/jicofo/lib/jakarta.xml.bind-api-2.3.2.jar:/usr/share/jicofo/lib/java-dogstatsd-client-2.5.jar:/usr/share/jicofo/lib/java-sdp-nist-bridge-1.1.jar:/usr/share/jicofo/lib/javassist-3.22.0-CR2.jar:/usr/share/jicofo/lib/javax.servlet-api-3.1.0.jar:/usr/share/jicofo/lib/jbosh-0.9.2.jar:/usr/share/jicofo/lib/jcip-annotations-1.0.jar:/usr/share/jicofo/lib/jcl-core-2.8.jar:/usr/share/jicofo/lib/jersey-client-2.30.1.jar:/usr/share/jicofo/lib/jersey-common-2.30.1.jar:/usr/share/jicofo/lib/jersey-container-jetty-http-2.30.1.jar:/usr/share/jicofo/lib/jersey-container-servlet-2.30.1.jar:/usr/share/jicofo/lib/jersey-container-servlet-core-2.30.1.jar:/usr/share/jicofo/lib/jersey-entity-filtering-2.30.1.jar:/usr/share/jicofo/lib/jersey-hk2-2.30.1.jar:/usr/share/jicofo/lib/jersey-media-jaxb-2.30.1.jar:/usr/share/jicofo/lib/jersey-media-json-jackson-2.30.1.jar:/usr/share/jicofo/lib/jersey-server-2.30.1.jar:/usr/share/jicofo/lib/jetty-client-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-continuation-9.4.17.v20190418.jar:/usr/share/jicofo/lib/jetty-http-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-io-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-proxy-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-security-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-server-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-servlet-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-util-9.4.15.v20190215.jar:/usr/share/jicofo/lib/jetty-webapp-7.0.1.v20091125.jar:/usr/share/jicofo/lib/jetty-xml-7.0.1.v20091125.jar:/usr/share/jicofo/lib/jicoco-1.1-58-g13ed16a.jar:/usr/share/jicofo/lib/jicoco-config-1.1-58-g13ed16a.jar:/usr/share/jicofo/lib/jitsi-android-osgi-1.0-SNAPSHOT.jar:/usr/share/jicofo/lib/jitsi-configuration-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-credentialsstorage-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-dnsservice-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-metaconfig-c3d8c51747.jar:/usr/share/jicofo/lib/jitsi-netaddr-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-protocol-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-protocol-jabber-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-protocol-media-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-util-2.13.cb5485e.jar:/usr/share/jicofo/lib/jitsi-utils-1.0-60-g07c4a0b.jar:/usr/share/jicofo/lib/jitsi-xmpp-extensions-1.0-15-g813e4c4.jar:/usr/share/jicofo/lib/jna-5.5.0.jar:/usr/share/jicofo/lib/jnsapi-0.0.3-jitsi-smack4.2-3.jar:/usr/share/jicofo/lib/json-simple-1.1.1.jar:/usr/share/jicofo/lib/jsr305-3.0.2.jar:/usr/share/jicofo/lib/jxmpp-core-0.6.2.jar:/usr/share/jicofo/lib/jxmpp-jid-0.6.2.jar:/usr/share/jicofo/lib/jxmpp-util-cache-0.6.2.jar:/usr/share/jicofo/lib/kotlin-osgi-bundle-1.3.72.jar:/usr/share/jicofo/lib/kotlin-reflect-1.3.72.jar:/usr/share/jicofo/lib/kotlin-stdlib-1.3.72.jar:/usr/share/jicofo/lib/kotlin-stdlib-common-1.3.71.jar:/usr/share/jicofo/lib/kotlin-stdlib-jdk7-1.3.72.jar:/usr/share/jicofo/lib/kotlin-stdlib-jdk8-1.3.72.jar:/usr/share/jicofo/lib/libidn-1.15.jar:/usr/share/jicofo/lib/libjitsi-1.0-0-gb3296cf.jar:/usr/share/jicofo/lib/listenablefuture-9999.0-empty-to-avoid-conflict-with-guava.jar:/usr/share/jicofo/lib/object-cloner-0.1.jar:/usr/share/jicofo/lib/objenesis-2.6.jar:/usr/share/jicofo/lib/orange-extensions-1.3.0.jar:/usr/share/jicofo/lib/org.apache.felix.framework-4.4.0.jar:/usr/share/jicofo/lib/org.apache.felix.main-4.4.0.jar:/usr/share/jicofo/lib/org.osgi.core-4.3.1.jar:/usr/share/jicofo/lib/osgi-resource-locator-1.0.3.jar:/usr/share/jicofo/lib/reflections-0.9.11.jar:/usr/share/jicofo/lib/sdes4j-1.1.3.jar:/usr/share/jicofo/lib/sdp-api-1.0.jar:/usr/share/jicofo/lib/sentry-1.7.30.jar:/usr/share/jicofo/lib/slf4j-api-1.7.26.jar:/usr/share/jicofo/lib/slf4j-jdk14-1.7.26.jar:/usr/share/jicofo/lib/smack-bosh-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-core-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-debug-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-experimental-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-extensions-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-im-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-java7-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-legacy-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-resolver-javax-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-sasl-javax-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/smack-tcp-4.2.4-47d17fc.jar:/usr/share/jicofo/lib/tinder-1.3.0.jar:/usr/share/jicofo/lib/weupnp-0.1.4.jar:/usr/share/jicofo/lib/xml-apis-1.0.b2.jar:/usr/share/jicofo/lib/xmlpull-1.1.3.4a.jar:/usr/share/jicofo/lib/xpp3-1.1.4c.jar:/usr/share/jicofo/lib/zrtp4j-light-4.1.0-jitsi-1-SNAPSHOT.jar
Jicofo 2020-11-30 17:40:52.780 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() user.name=jicofo
Jicofo 2020-11-30 17:40:52.780 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.specification.version=1.8
Jicofo 2020-11-30 17:40:52.780 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.java.command=org.jitsi.jicofo.Main --host=localhost --domain=room-test4.mydomain.com --port=5347 --user_name=focus --user_domain=auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:52.781 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.home=/usr/lib/jvm/java-8-openjdk-amd64/jre
Jicofo 2020-11-30 17:40:52.781 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.arch.data.model=64
Jicofo 2020-11-30 17:40:52.782 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() user.language=en
Jicofo 2020-11-30 17:40:52.782 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.specification.vendor=Oracle Corporation
Jicofo 2020-11-30 17:40:52.783 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() awt.toolkit=sun.awt.X11.XToolkit
Jicofo 2020-11-30 17:40:52.783 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vm.info=mixed mode
Jicofo 2020-11-30 17:40:52.783 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.version=1.8.0_275
Jicofo 2020-11-30 17:40:52.783 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.ext.dirs=/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/ext:/usr/java/packages/lib/ext
Jicofo 2020-11-30 17:40:52.783 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.boot.class.path=/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/resources.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/rt.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/sunrsasign.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/jsse.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/jce.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/charsets.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/lib/jfr.jar:/usr/lib/jvm/java-8-openjdk-amd64/jre/classes
Jicofo 2020-11-30 17:40:52.784 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.SC_HOME_DIR_LOCATION=/etc/jitsi
Jicofo 2020-11-30 17:40:52.784 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vendor=Private Build
Jicofo 2020-11-30 17:40:52.784 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() jdk.tls.ephemeralDHKeySize=2048
Jicofo 2020-11-30 17:40:52.785 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() file.separator=/
Jicofo 2020-11-30 17:40:52.785 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.impl.configuration.USE_PROPFILE_CONFIG=true
Jicofo 2020-11-30 17:40:52.786 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.SC_HOME_DIR_NAME=jicofo
Jicofo 2020-11-30 17:40:52.786 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() java.vendor.url.bug=http://bugreport.sun.com/bugreport/
Jicofo 2020-11-30 17:40:52.786 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.io.unicode.encoding=UnicodeLittle
Jicofo 2020-11-30 17:40:52.786 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.cpu.endian=little
Jicofo 2020-11-30 17:40:52.787 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() net.java.sip.communicator.SC_LOG_DIR_LOCATION=/var/log/jitsi
Jicofo 2020-11-30 17:40:52.787 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() sun.cpu.isalist=
Jicofo 2020-11-30 17:40:52.789 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() Normal classloader
Jicofo 2020-11-30 17:40:52.790 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() failed to find jitsi-defaults.properties with class loader, will continue without it.
Jicofo 2020-11-30 17:40:52.790 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() Normal classloader
Jicofo 2020-11-30 17:40:52.790 INFO: [10] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() failed to find jitsi-default-overrides.properties with class loader, will continue without it.
Jicofo 2020-11-30 17:40:52.828 INFO: [10] util.NetworkUtils.<clinit>().117 java.net.preferIPv6Addresses=null
Jicofo 2020-11-30 17:40:52.828 INFO: [10] util.NetworkUtils.<clinit>().118 java.net.preferIPv4Stack=null
Jicofo 2020-11-30 17:40:52.836 INFO: [10] impl.netaddr.NetworkAddressManagerServiceImpl.start().92 Network Address Manager         ...[  STARTED ]
Jicofo 2020-11-30 17:40:52.836 INFO: [10] impl.netaddr.NetworkAddressManagerServiceImpl.start().98 Network Address Manager Service ...[REGISTERED]
Jicofo 2020-11-30 17:40:52.842 INFO: [10] org.jitsi.version.AbstractVersionActivator.log() VersionService registered: JiCoFo 1.0.644
Jicofo 2020-11-30 17:40:53.176 INFO: [10] org.jitsi.jicofo.FocusBundleActivator.log() Max shared pool size: 1500
Jicofo 2020-11-30 17:40:53.180 INFO: [10] org.jitsi.jicofo.JitsiMeetGlobalConfig.log() Automatically grant 'owner' role: true
Jicofo 2020-11-30 17:40:53.190 INFO: [10] org.jitsi.jicofo.JitsiMeetGlobalConfig.log() Jibri requests in PENDING state will be timed out after: 90 seconds
Jicofo 2020-11-30 17:40:53.191 INFO: [10] org.jitsi.jicofo.JitsiMeetGlobalConfig.log() Will attempt a maximum of 5 Jibri retries after failure
Jicofo 2020-11-30 17:40:53.191 INFO: [10] org.jitsi.jicofo.JitsiMeetGlobalConfig.log() Lonely participants will be "terminated" after 20000 milliseconds
Jicofo 2020-11-30 17:40:53.209 WARNING: [10] org.jitsi.jicofo.FocusManager.log() Jicofo ID is not set. Configure a valid value [1-65535] by setting org.jitsi.jicofo.SHORT_ID. Future versions will require this for Octo.
Jicofo 2020-11-30 17:40:53.254 WARNING: [10] org.jitsi.jicofo.FocusManager.log() No dedicated JVB MUC XMPP connection configured - falling back to the default XMPP connection
Jicofo 2020-11-30 17:40:53.741 INFO: [10] JitsiConfig.log() Initialized newConfig: merge of system properties,/etc/jitsi/jicofo/jicofo.conf: 1,system properties,reference.conf @ jar:file:/usr/share/jicofo/jicofo.jar!/reference.conf: 1,reference.conf @ jar:file:/usr/share/jicofo/lib/ice4j-3.0-21-g3a55627.jar!/reference.conf: 1
Jicofo 2020-11-30 17:40:53.743 INFO: [10] org.jitsi.config.ReadOnlyConfigurationService.log() loading config file at path /etc/jitsi/jicofo/sip-communicator.properties
Jicofo 2020-11-30 17:40:53.745 INFO: [10] JitsiConfig.log() Initialized legacyConfig: sip communicator props (no description provided)
Jicofo 2020-11-30 17:40:53.871 INFO: [10] org.jitsi.jicofo.bridge.BridgeSelector.log() Using org.jitsi.jicofo.bridge.SingleBridgeSelectionStrategy
Jicofo 2020-11-30 17:40:53.875 INFO: [10] org.jitsi.jicofo.JitsiMeetServices.log() Using a Jibri detector with MUC: JibriBrewery@internal.auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:53.876 INFO: [10] org.jitsi.jicofo.JitsiMeetServices.log() Using a Bridge MUC detector with MUC: JvbBrewery@internal.auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:53.940 INFO: [10] org.eclipse.jetty.util.log.initialized() Logging initialized @1668ms to org.eclipse.jetty.util.log.Slf4jLog
Jicofo 2020-11-30 17:40:54.288 INFO: [10] org.eclipse.jetty.server.Server.doStart() jetty-9.4.15.v20190215; built: 2019-02-15T16:53:49.381Z; git: eb70b240169fcf1abbd86af36482d1c49826fa0b; jvm 1.8.0_275-8u275-b01-0ubuntu1~18.04-b01
Jicofo 2020-11-30 17:40:55.245 INFO: [16] org.jitsi.jicofo.ProtocolProviderHandler.log() XmppProtocolProvider(focus@auth.room-test4.mydomain.com/focus167377047707091 (Jabber)): RegistrationStateChangeEvent[ oldState=Unregistered; newState=RegistrationState=Registered; reasonCode=-1; reason=null]
Jicofo 2020-11-30 17:40:55.320 INFO: [16] org.jitsi.jicofo.xmpp.BaseBrewery.log() Joined brewery room: JibriBrewery@internal.auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:55.323 INFO: [28] org.jitsi.jicofo.xmpp.BaseBrewery.log() Added brewery instance: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname
Jicofo 2020-11-30 17:40:55.323 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname 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 2020-11-30 17:40:55.324 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname available: false
Jicofo 2020-11-30 17:40:55.327 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname 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 2020-11-30 17:40:55.327 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname available: false
Jicofo 2020-11-30 17:40:55.386 INFO: [16] org.jitsi.jicofo.xmpp.BaseBrewery.log() Joined brewery room: JvbBrewery@internal.auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:55.387 INFO: [28] org.jitsi.jicofo.xmpp.BaseBrewery.log() Added brewery instance: jvbbrewery@internal.auth.room-test4.mydomain.com/BahrainJVB
Jicofo 2020-11-30 17:40:55.400 INFO: [16] org.jitsi.jicofo.ComponentsDiscovery.log() New component discovered: recorder.room-test4.mydomain.com, Prosody(0.11.7,Linux)
Jicofo 2020-11-30 17:40:55.401 INFO: [16] org.jitsi.jicofo.ComponentsDiscovery.log() New component discovered: conference.room-test4.mydomain.com, null
Jicofo 2020-11-30 17:40:55.405 INFO: [16] org.jitsi.jicofo.ComponentsDiscovery.log() New component discovered: guest.room-test4.mydomain.com, Prosody(0.11.7,Linux)
Jicofo 2020-11-30 17:40:55.408 INFO: [16] org.jitsi.jicofo.ComponentsDiscovery.log() New component discovered: auth.room-test4.mydomain.com, Prosody(0.11.7,Linux)
Jicofo 2020-11-30 17:40:55.408 INFO: [16] org.jitsi.jicofo.JitsiMeetServices.log() Detected XMPP server version: Prosody(0.11.7,Linux)
Jicofo 2020-11-30 17:40:55.418 INFO: [28] org.jitsi.jicofo.bridge.BridgeSelector.log() Added new videobridge: Bridge[jid=jvbbrewery@internal.auth.room-test4.mydomain.com/BahrainJVB, relayId=15.185.225.129:4096, region=BahrainJVB, stress=0.00]
Jicofo 2020-11-30 17:40:55.420 WARNING: [16] org.jitsi.impl.protocol.xmpp.OpSetSimpleCapsImpl.log() Failed to discover features for focus.room-test4.mydomain.com: XMPP error reply received from focus.room-test4.mydomain.com: XMPPError: service-unavailable - wait
Jicofo 2020-11-30 17:40:55.422 WARNING: [16] org.jitsi.impl.protocol.xmpp.OpSetSimpleCapsImpl.log() Failed to discover features for speakerstats.room-test4.mydomain.com: XMPP error reply received from speakerstats.room-test4.mydomain.com: XMPPError: service-unavailable - cancel
Jicofo 2020-11-30 17:40:55.426 INFO: [16] org.jitsi.jicofo.ComponentsDiscovery.log() New component discovered: lobby.room-test4.mydomain.com, null
Jicofo 2020-11-30 17:40:55.427 INFO: [28] org.jitsi.jicofo.bridge.JvbDoctor.log() Scheduled health-check task for: jvbbrewery@internal.auth.room-test4.mydomain.com/BahrainJVB
Jicofo 2020-11-30 17:40:55.428 INFO: [28] org.jitsi.jicofo.xmpp.BaseBrewery.log() Added brewery instance: jvbbrewery@internal.auth.room-test4.mydomain.com/mydomain-jvb2
Jicofo 2020-11-30 17:40:55.430 INFO: [28] org.jitsi.jicofo.bridge.BridgeSelector.log() Added new videobridge: Bridge[jid=jvbbrewery@internal.auth.room-test4.mydomain.com/mydomain-jvb2, relayId=54.153.26.21:4096, region=USRegion, stress=0.00]
Jicofo 2020-11-30 17:40:55.431 WARNING: [16] org.jitsi.impl.protocol.xmpp.OpSetSimpleCapsImpl.log() Failed to discover features for conferenceduration.room-test4.mydomain.com: XMPP error reply received from conferenceduration.room-test4.mydomain.com: XMPPError: service-unavailable - cancel
Jicofo 2020-11-30 17:40:55.431 INFO: [16] org.jitsi.jicofo.ComponentsDiscovery.log() Service rediscovery disabled
Jicofo 2020-11-30 17:40:55.431 INFO: [16] org.jitsi.jicofo.FocusManager.log() XMPP provider reg state: RegistrationState=Registered
Jicofo 2020-11-30 17:40:55.439 INFO: [28] org.jitsi.jicofo.bridge.JvbDoctor.log() Scheduled health-check task for: jvbbrewery@internal.auth.room-test4.mydomain.com/mydomain-jvb2
Jicofo 2020-11-30 17:40:55.441 INFO: [16] org.jitsi.impl.protocol.xmpp.XmppProtocolProvider.log() XMPP provider Jabber:focus@auth.room-test4.mydomain.com/focus167377047707091@localhost connected (JID: focus@auth.room-test4.mydomain.com/focus167377047707091)
Jicofo 2020-11-30 17:40:55.830 INFO: [10] org.eclipse.jetty.server.handler.ContextHandler.doStart() Started o.e.j.s.ServletContextHandler@2170b578{/,null,AVAILABLE}
Jicofo 2020-11-30 17:40:55.844 INFO: [10] org.eclipse.jetty.server.AbstractConnector.doStart() Started ServerConnector@290536e{HTTP/1.1,[http/1.1]}{0.0.0.0:8888}
Jicofo 2020-11-30 17:40:55.845 INFO: [10] org.eclipse.jetty.server.Server.doStart() Started @3576ms
Jicofo 2020-11-30 17:40:55.849 INFO: [1] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.BRIDGE_MUC=JvbBrewery@internal.auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:55.849 INFO: [1] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.jibri.PENDING_TIMEOUT=90
Jicofo 2020-11-30 17:40:55.849 INFO: [1] org.jitsi.impl.configuration.ConfigurationServiceImpl.log() org.jitsi.jicofo.jibri.BREWERY=JibriBrewery@internal.auth.room-test4.mydomain.com
Jicofo 2020-11-30 17:40:55.855 INFO: [10] org.jitsi.jicofo.health.Health.log() Health checks are disabled.
Jicofo 2020-11-30 17:40:55.859 INFO: [1] org.jitsi.xmpp.component.ComponentBase.log() Component org.jitsi.jicofo. config: 
Jicofo 2020-11-30 17:40:55.859 INFO: [1] org.jitsi.xmpp.component.ComponentBase.log()   ping interval: 10000 ms
Jicofo 2020-11-30 17:40:55.859 INFO: [1] org.jitsi.xmpp.component.ComponentBase.log()   ping timeout: 5000 ms
Jicofo 2020-11-30 17:40:55.859 INFO: [1] org.jitsi.xmpp.component.ComponentBase.log()   ping threshold: 3
Jicofo 2020-11-30 17:41:06.349 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname 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 2020-11-30 17:41:06.350 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname available: true
Jicofo 2020-11-30 17:41:16.670 INFO: [54] org.jitsi.jicofo.xmpp.FocusComponent.log() Focus request for room: recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 17:41:16.680 INFO: [54] org.jitsi.jicofo.FocusManager.log() Created new focus for recording-test@conference.r.room-test4.mydomain.com@auth.room-test4.mydomain.com. Conference count 1,options: channelLastN=-1 enableLipSync=false openSctp=false disableRtx=false 
Jicofo 2020-11-30 17:41:16.682 INFO: [54] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Joining the room: recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 17:41:17.281 INFO: [28] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Chat room event ChatRoomMemberPresenceChangeEvent[type=MemberJoined sourceRoom=org.jitsi.impl.protocol.xmpp.ChatRoomImpl@38220a53 member=ChatMember[recording-test@conference.r.room-test4.mydomain.com/e0ae9b6f, jid: null]@1386801453]
Jicofo 2020-11-30 17:41:17.287 INFO: [28] org.jitsi.jicofo.ChatRoomRoleAndPresence.log() Granted owner to recording-test@conference.r.room-test4.mydomain.com/e0ae9b6f
Jicofo 2020-11-30 17:41:17.287 INFO: [28] org.jitsi.jicofo.JitsiMeetConferenceImpl.log() Member recording-test@conference.r.room-test4.mydomain.com/e0ae9b6f joined.
Jicofo 2020-11-30 17:41:38.690 INFO: [57] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname
Jicofo 2020-11-30 17:41:38.690 INFO: [57] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname for stream ID: null in room: recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 17:41:39.085 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname 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 2020-11-30 17:41:39.086 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname available: false
Jicofo 2020-11-30 17:41:39.088 INFO: [57] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.room-test4.mydomain.com/focus167377047707091' from='jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname' id='MTtv8-139' type='result'><jibri xmlns='http://jitsi.org/protocol/jibri' status='pending'/></iq> for recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 17:41:39.089 INFO: [57] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname has status pending and failure reason null, current Jibri jid is jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname
Jicofo 2020-11-30 17:41:39.089 INFO: [57] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Started Jibri session
Jicofo 2020-11-30 17:42:10.414 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.room-test4.mydomain.com/focus167377047707091' from='jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname' id='Zm9jdXNAYXV0aC5yb29tLXRlc3Q0LjExc2lnaHQuY29tL2ZvY3VzMTY3Mzc3MDQ3NzA3MDkxADB5ekFxLTc2AHyaPK6iFW3zZLJJbWS0HSo=' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq> for recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 17:42:10.414 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname has status off and failure reason error, current Jibri jid is jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname
Jicofo 2020-11-30 17:42:10.414 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2020-11-30 17:42:10.415 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed, trying to fall back to another Jibri
Jicofo 2020-11-30 17:42:10.415 SEVERE: [55] org.jitsi.jicofo.recording.jibri.JibriSession.log() Unable to find an available Jibri, can't start
Jicofo 2020-11-30 17:42:10.416 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriSession.log() Failed to fall back to another Jibri, this session has now failed: org.jitsi.jicofo.recording.jibri.JibriSession$StartException: All Jibri instances are busy
org.jitsi.jicofo.recording.jibri.JibriSession$StartException: All Jibri instances are busy
	at org.jitsi.jicofo.recording.jibri.JibriSession.startInternal(JibriSession.java:359)
	at org.jitsi.jicofo.recording.jibri.JibriSession.start(JibriSession.java:335)
	at org.jitsi.jicofo.recording.jibri.JibriSession.retryRequestWithAnotherJibri(JibriSession.java:649)
	at org.jitsi.jicofo.recording.jibri.JibriSession.handleJibriStatusUpdate(JibriSession.java:719)
	at org.jitsi.jicofo.recording.jibri.JibriSession.processJibriIqFromJibri(JibriSession.java:501)
	at org.jitsi.jicofo.recording.jibri.JibriSession.processJibriIqRequestFromJibri(JibriSession.java:482)
	at org.jitsi.jicofo.recording.jibri.CommonJibriStuff.handleIQRequest(CommonJibriStuff.java:247)
	at org.jitsi.jicofo.recording.jibri.OperationSetJibri.handleIQRequest(OperationSetJibri.java:98)
	at org.jivesoftware.smack.AbstractXMPPConnection$4.run(AbstractXMPPConnection.java:1188)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)
Jicofo 2020-11-30 17:42:10.417 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Got jibri status off and failure error
Jicofo 2020-11-30 17:42:10.418 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Publishing new jibri-recording-status: <jibri-recording-status xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' session_id='pwuwlaikfywazali' recording_mode='file'/> in: recording-test@conference.r.room-test4.mydomain.com
Jicofo 2020-11-30 17:42:10.418 INFO: [55] org.jitsi.jicofo.recording.jibri.JibriSession.log() Cleaning up current JibriSession
Jicofo 2020-11-30 17:42:10.782 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname 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 2020-11-30 17:42:10.782 INFO: [28] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.room-test4.mydomain.com/jibrinickname available: true[quote="Freddie, post:18, topic:86911, full:true"]
Ok... maybe I've just never paid attention to it.
Can you please share your current jicofo log?

Btw did you restart services after making the change in cfg.lua?
[/quote]


Well, you certainly should have restarted Jibri as well (probably especially). Maybe try that? I’ll take a look at your jicofo in the meantime.