Jibri recording does not start -no obvious error message

Hi,

I followed these guides here to install / troubleshoot Jibri:

but I cannot get jibri to start recording. It fails immediately and logs on jibri and on the jitsi servers do not show any obvious error message.

I tried both Java 8 and Java 11. Both systems run on latest Debian Buster.

Here are the log files, I have replaced the domain name with example.org.

log.0.txt:

2021-06-20 16:36:14.668 INFO: [1] org.jitsi.jibri.Main.log() Jibri run with args [--config, /etc/jitsi/jibri/config.json]
2021-06-20 16:36:14.738 INFO: [1] org.jitsi.jibri.Main.log() Checking legacy config file /etc/jitsi/jibri/config.json
2021-06-20 16:36:14.738 INFO: [1] org.jitsi.jibri.Main.log() Legacy config file /etc/jitsi/jibri/config.json doesn't exist
2021-06-20 16:36:14.978 INFO: [1] org.jitsi.jibri.Main.log() Jibri starting up with id jibri01-dev
2021-06-20 16:36:15.385 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() got jwtConfig: {}

2021-06-20 16:36:15.385 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() Unable to create JwtInfo: com.typesafe.config.ConfigException$Missing: /etc/jitsi/jibri/jibri.conf: 107: No configuration setting found for key 'signing-key-path'
2021-06-20 16:36:15.497 INFO: [1] org.jitsi.jibri.Main.log() Using port 3333 for internal HTTP API
2021-06-20 16:36:15.512 FINE: [19] org.jitsi.jibri.webhooks.v1.WebhookClient.log() Updating 0 subscribers of status
2021-06-20 16:36:15.773 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2021-06-20 16:36:15.780 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Connecting to xmpp environment on jitsi-dev.int.example.org with config XmppEnvironmentConfig(name=jibri env, xmppServerHosts=[jitsi-dev.int.example.org], xmppDomain=jitsi-dev.int.example.org, baseUrl=null, controlLogin=XmppCredentials(domain=auth.jitsi-dev.int.example.org, port=null, username=jibri, password=g8Z4R9kmEdKvpXBrY05E), controlMuc=XmppMuc(domain=internal.auth.jitsi-dev.int.example.org, roomName=JibriBrewery, nickname=jibri-nickname), sipControlMuc=null, callLogin=XmppCredentials(domain=recorder.jitsi-dev.int.example.org, port=null, username=recorder, password=AbNJapZbfExuEBMRLSYP), stripFromRoomDomain=conference., usageTimeoutMins=0, trustAllXmppCerts=true)
2021-06-20 16:36:15.781 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() The trustAllXmppCerts config is enabled for this domain, all XMPP server provided certificates will be accepted
2021-06-20 16:36:15.800 WARNING: [28] org.jitsi.xmpp.mucclient.MucClient.log() Disabling certificate verification!
2021-06-20 16:36:15.800 INFO: [1] org.jitsi.jibri.Main.log() Using port 2222 for HTTP API
2021-06-20 16:36:16.054 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Connected.
2021-06-20 16:36:16.054 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Logging in.
2021-06-20 16:36:16.116 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Joined MUC: jibribrewery@internal.auth.jitsi-dev.int.example.org

prosody.log:

Jun 20 15:52:06 c2s55d2d80e9e60 info    Client connected
Jun 20 15:52:06 c2s55d2d80e9e60 info    Stream encrypted (TLSv1.2 with ECDHE-RSA-AES128-GCM-SHA256)
Jun 20 15:52:06 c2s55d2d80e9e60 info    Authenticated as jibri@auth.jitsi-dev.int.example.org
Jun 20 15:52:08 mod_bosh        info    New BOSH session, assigned it sid '182fb8a8-7f79-4607-9454-a08b25409ca8'
Jun 20 15:52:09 bosh182fb8a8-7f79-4607-9454-a08b25409ca8        info    Authenticated as lkia_v_-t94kx-pe@jitsi-dev.int.example.org
Jun 20 15:55:30 c2s55d2d80e9e60 info    Client disconnected: closed
Jun 20 15:55:32 c2s55d2d7f9f930 info    Client connected
Jun 20 15:55:32 c2s55d2d7f9f930 info    Stream encrypted (TLSv1.3 with TLS_AES_256_GCM_SHA384)
Jun 20 15:55:32 c2s55d2d7f9f930 info    Authenticated as jibri@auth.jitsi-dev.int.example.org
Jun 20 15:57:39 c2s55d2d7f9f930 info    Client disconnected: closed
Jun 20 15:58:52 mod_posix       warn    Received SIGTERM
Jun 20 15:58:52 startup info    Shutting down: Received SIGTERM
Jun 20 15:58:52 c2s55d2d800da20 info    Client disconnected: connection closed
Jun 20 15:58:52 c2s55d2d7eb5e80 info    Client disconnected: connection closed
Jun 20 15:58:52 general info    Shutting down...
Jun 20 15:58:52 general info    Shutdown status: Cleaning up
Jun 20 15:58:52 general info    Shutdown complete
Jun 20 15:58:52 startup info    Hello and welcome to Prosody version 0.11.2
Jun 20 15:58:52 startup info    Prosody is using the select backend for connection handling
Jun 20 15:58:52 portmanager     info    Activated service 's2s' on [*]:5269, [::]:5269
Jun 20 15:58:52 portmanager     info    Activated service 'c2s' on [*]:5222, [::]:5222
Jun 20 15:58:52 portmanager     info    Activated service 'legacy_ssl' on no ports
Jun 20 15:58:52 portmanager     info    Activated service 'http' on [*]:5280, [::]:5280
Jun 20 15:58:52 portmanager     info    Activated service 'https' on no ports
Jun 20 15:58:52 general info    Starting conference duration timer for conference.jitsi-dev.int.example.org
Jun 20 15:58:52 conferenceduration.jitsi-dev.int.example.org:conference_duration_component      info    No muc component found, will listen for it: conference.jitsi-dev.int.example.org
Jun 20 15:58:52 general info    Starting speakerstats for conference.jitsi-dev.int.example.org
Jun 20 15:58:52 speakerstats.jitsi-dev.int.example.org:speakerstats_component   info    No muc component found, will listen for it: conference.jitsi-dev.int.example.org
Jun 20 15:58:52 conferenceduration.jitsi-dev.int.example.org:conference_duration_component      info    Hook to muc events on conference.jitsi-dev.int.example.org
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host conference.jitsi-dev.int.example.org!
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host conferenceduration.jitsi-dev.int.example.org!
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host internal.auth.jitsi-dev.int.example.org!
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host speakerstats.jitsi-dev.int.example.org!
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host localhost!
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host auth.jitsi-dev.int.example.org!
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host jitsi-dev.int.example.org!
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host recorder.jitsi-dev.int.example.org!
Jun 20 15:58:52 speakerstats.jitsi-dev.int.example.org:speakerstats_component   info    Hook to muc events on conference.jitsi-dev.int.example.org
Jun 20 15:58:52 conference.jitsi-dev.int.example.org:muc_domain_mapper  info    Loading mod_muc_domain_mapper for host focus.jitsi-dev.int.example.org!
Jun 20 15:58:52 mod_bosh        info    Client tried to use sid '182fb8a8-7f79-4607-9454-a08b25409ca8' which we don't know about
Jun 20 15:58:52 mod_bosh        info    Client tried to use sid '182fb8a8-7f79-4607-9454-a08b25409ca8' which we don't know about
Jun 20 15:58:53 c2s555ddcca1d70 info    Client connected
Jun 20 15:58:53 c2s555ddcca1d70 info    Stream encrypted (TLSv1.3 with TLS_AES_256_GCM_SHA384)
Jun 20 15:58:53 c2s555ddcca1d70 info    Authenticated as jvb@auth.jitsi-dev.int.example.org
Jun 20 15:58:56 c2s555ddce6e210 info    Client connected
Jun 20 15:58:57 c2s555ddce6e210 info    Stream encrypted (TLSv1.3 with TLS_AES_256_GCM_SHA384)
Jun 20 15:58:57 c2s555ddce6e210 info    Authenticated as focus@auth.jitsi-dev.int.example.org
Jun 20 15:58:58 c2s555ddcca1d70 info    Client disconnected: closed
Jun 20 15:59:00 c2s555ddcee62c0 info    Client connected
Jun 20 15:59:01 c2s555ddcee62c0 info    Stream encrypted (TLSv1.3 with TLS_AES_256_GCM_SHA384)
Jun 20 15:59:01 c2s555ddcee62c0 info    Authenticated as jvb@auth.jitsi-dev.int.example.org
Jun 20 15:59:04 mod_bosh        info    New BOSH session, assigned it sid '2a8b65db-1227-4a5e-abc9-c1cb9141a3ee'
Jun 20 15:59:04 bosh2a8b65db-1227-4a5e-abc9-c1cb9141a3ee        info    Authenticated as kapuumi7bs8izqms@jitsi-dev.int.example.org
Jun 20 16:00:25 speakerstats.jitsi-dev.int.example.org:speakerstats_component   warn    A module has been configured that triggers external events.
Jun 20 16:00:25 speakerstats.jitsi-dev.int.example.org:speakerstats_component   warn    Implement this lib to trigger external events.
Jun 20 16:21:53 mod_bosh        info    New BOSH session, assigned it sid '30c1defe-575e-4a59-b963-ba14a6cb2c6f'
Jun 20 16:21:54 bosh30c1defe-575e-4a59-b963-ba14a6cb2c6f        info    Authenticated as u6dgyqckomwwzxu8@jitsi-dev.int.example.org

jicofo.log

Jicofo 2021-06-20 15:59:05.850 INFO: [31] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org] JitsiMeetConferenceImpl.joinTheRoom#433: Joining localpunishmentssumanyway@conference.jitsi-dev.int.example.org
Jicofo 2021-06-20 15:59:06.203 INFO: [26] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event Joined member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/01fd2b8a, jid: null]@2074878300
Jicofo 2021-06-20 15:59:06.204 WARNING: [26] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.electNewOwner#177: Focus role unknown
Jicofo 2021-06-20 15:59:06.204 INFO: [26] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.electNewOwner#181: Obtained focus role: OWNER
Jicofo 2021-06-20 15:59:06.208 INFO: [26] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.electNewOwner#224: Granted owner to 01fd2b8a
Jicofo 2021-06-20 15:59:06.208 INFO: [26] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] JitsiMeetConferenceImpl.onMemberJoined#547: Member joined:01fd2b8a
Jicofo 2021-06-20 15:59:06.213 INFO: [26] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event PresenceUpdated member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/01fd2b8a, jid: kapuumi7bs8izqms@jitsi-dev.int.example.org/yN52rJey]@2074878300
Jicofo 2021-06-20 15:59:06.214 INFO: [26] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event PresenceUpdated member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/01fd2b8a, jid: kapuumi7bs8izqms@jitsi-dev.int.example.org/yN52rJey]@2074878300
Jicofo 2021-06-20 16:00:25.512 INFO: [33] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event Left member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/01fd2b8a, jid: kapuumi7bs8izqms@jitsi-dev.int.example.org/yN52rJey]@2074878300
Jicofo 2021-06-20 16:00:25.512 INFO: [33] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] ChatRoomRoleAndPresence.memberPresenceChanged#150: Owner has left the room !
Jicofo 2021-06-20 16:00:25.513 INFO: [33] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] JitsiMeetConferenceImpl.onMemberLeft#1081: Member left:01fd2b8a
Jicofo 2021-06-20 16:00:25.513 WARNING: [33] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] JitsiMeetConferenceImpl.onMemberLeft#1096: Participant not found for 01fd2b8a. Terminated already or never started?
Jicofo 2021-06-20 16:00:25.529 INFO: [33] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=70cb3778-1ac8-4c0d-8d27-fcde2811addc] JitsiMeetConferenceImpl.stop#410: Stopped.
Jicofo 2021-06-20 16:21:57.307 INFO: [38] ConferenceIqHandler.handleConferenceIq#56: Focus request for room: localpunishmentssumanyway@conference.jitsi-dev.int.example.org
Jicofo 2021-06-20 16:21:57.312 INFO: [38] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org] JitsiMeetConferenceImpl.<init>#251: Created new conference, roomJid=localpunishmentssumanyway@conference.jitsi-dev.int.example.org
Jicofo 2021-06-20 16:21:57.312 INFO: [38] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org] JitsiMeetConferenceImpl.joinTheRoom#433: Joining localpunishmentssumanyway@conference.jitsi-dev.int.example.org
Jicofo 2021-06-20 16:21:58.273 INFO: [37] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event Joined member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/a65b9556, jid: null]@321353564
Jicofo 2021-06-20 16:21:58.273 WARNING: [37] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.electNewOwner#177: Focus role unknown
Jicofo 2021-06-20 16:21:58.273 INFO: [37] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.electNewOwner#181: Obtained focus role: OWNER
Jicofo 2021-06-20 16:21:58.278 INFO: [37] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.electNewOwner#224: Granted owner to a65b9556
Jicofo 2021-06-20 16:21:58.278 INFO: [37] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] JitsiMeetConferenceImpl.onMemberJoined#547: Member joined:a65b9556
Jicofo 2021-06-20 16:21:58.279 INFO: [37] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event PresenceUpdated member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/a65b9556, jid: u6dgyqckomwwzxu8@jitsi-dev.int.example.org/mEaxAMXO]@321353564
Jicofo 2021-06-20 16:21:58.279 INFO: [37] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event PresenceUpdated member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/a65b9556, jid: u6dgyqckomwwzxu8@jitsi-dev.int.example.org/mEaxAMXO]@321353564
Jicofo 2021-06-20 16:35:45.246 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event PresenceUpdated member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/a65b9556, jid: u6dgyqckomwwzxu8@jitsi-dev.int.example.org/mEaxAMXO]@321353564
Jicofo 2021-06-20 16:35:47.388 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event Left member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/a65b9556, jid: u6dgyqckomwwzxu8@jitsi-dev.int.example.org/mEaxAMXO]@321353564
Jicofo 2021-06-20 16:35:47.388 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] ChatRoomRoleAndPresence.memberPresenceChanged#150: Owner has left the room !
Jicofo 2021-06-20 16:35:47.388 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] JitsiMeetConferenceImpl.onMemberLeft#1081: Member left:a65b9556
Jicofo 2021-06-20 16:35:47.388 WARNING: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] JitsiMeetConferenceImpl.onMemberLeft#1096: Participant not found for a65b9556. Terminated already or never started?
Jicofo 2021-06-20 16:35:47.392 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=23782202-eed4-46ea-848d-ded95b25fbd6] JitsiMeetConferenceImpl.stop#410: Stopped.
Jicofo 2021-06-20 16:35:48.995 INFO: [47] ConferenceIqHandler.handleConferenceIq#56: Focus request for room: localpunishmentssumanyway@conference.jitsi-dev.int.example.org
Jicofo 2021-06-20 16:35:48.996 INFO: [47] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org] JitsiMeetConferenceImpl.<init>#251: Created new conference, roomJid=localpunishmentssumanyway@conference.jitsi-dev.int.example.org
Jicofo 2021-06-20 16:35:48.996 INFO: [47] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org] JitsiMeetConferenceImpl.joinTheRoom#433: Joining localpunishmentssumanyway@conference.jitsi-dev.int.example.org
Jicofo 2021-06-20 16:35:49.194 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=9f33d90d-0b36-49cb-b970-69a6b7db4d9f] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event Joined member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/849ef185, jid: null]@1306237137
Jicofo 2021-06-20 16:35:49.194 WARNING: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=9f33d90d-0b36-49cb-b970-69a6b7db4d9f] ChatRoomRoleAndPresence.electNewOwner#177: Focus role unknown
Jicofo 2021-06-20 16:35:49.194 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=9f33d90d-0b36-49cb-b970-69a6b7db4d9f] ChatRoomRoleAndPresence.electNewOwner#181: Obtained focus role: OWNER
Jicofo 2021-06-20 16:35:49.196 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=9f33d90d-0b36-49cb-b970-69a6b7db4d9f] ChatRoomRoleAndPresence.electNewOwner#224: Granted owner to 849ef185
Jicofo 2021-06-20 16:35:49.196 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=9f33d90d-0b36-49cb-b970-69a6b7db4d9f] JitsiMeetConferenceImpl.onMemberJoined#547: Member joined:849ef185
Jicofo 2021-06-20 16:35:49.196 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=9f33d90d-0b36-49cb-b970-69a6b7db4d9f] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event PresenceUpdated member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/849ef185, jid: kpqzgenjcw4l_cwk@jitsi-dev.int.example.org/htVnRMKa]@1306237137
Jicofo 2021-06-20 16:35:49.197 INFO: [46] [room=localpunishmentssumanyway@conference.jitsi-dev.int.example.org meeting_id=9f33d90d-0b36-49cb-b970-69a6b7db4d9f] ChatRoomRoleAndPresence.memberPresenceChanged#130: Chat room event PresenceUpdated member=ChatMember[localpunishmentssumanyway@conference.jitsi-dev.int.example.org/849ef185, jid: kpqzgenjcw4l_cwk@jitsi-dev.int.example.org/htVnRMKa]@1306237137

I cannot find any error. Please let me know if I can provide additional information.

Thanks!

Links to guides:

That Jibri log seems incomplete. But share your jibri.conf.

jibri.conf:

// Ansible managed, don't make manual changes here!

jibri {
  // A unique identifier for this Jibri
  // TODO: eventually this will be required with no default
  id = "jibri01-dev"
  // Whether or not Jibri should return to idle state after handling
  // (successfully or unsuccessfully) a request.  A value of 'true'
  // here means that a Jibri will NOT return back to the IDLE state
  // and will need to be restarted in order to be used again.
  single-use-mode = false
  api {
    http {
      external-api-port = 2222
      internal-api-port = 3333
    }
    xmpp {
      // See example_xmpp_envs.conf for an example of what is expected here
      environments = [
        {

                name = "jibri env"

                xmpp-server-hosts = [ "jitsi-dev.int.example.org" ]

                xmpp-domain = "jitsi-dev.int.example.org"

                control-muc {
                        domain = "internal.auth.jitsi-dev.int.example.org"
                        room-name = "JibriBrewery"
                        nickname = "jibri-nickname"
                }

                control-login {
                        domain = "auth.jitsi-dev.int.example.org"
                        username = "jibri"
                        password = "g8Z4R9kmEdKvpXBrY05E"
                }

                call-login {
                        domain = "recorder.jitsi-dev.int.example.org"
                        username = "recorder"
                        password = "AbNJapZbfExuEBMRLSYP"
                }

                // The value we'll strip from the room JID domain to derive
                // the call URL
                strip-from-room-domain = "conference."

                // How long Jibri sessions will be allowed to last before
                // they are stopped.  A value of 0 allows them to go on
                // indefinitely
                usage-timeout = 0 hour

                // Whether or not we'll automatically trust any cert on
                // this XMPP domain
                trust-all-xmpp-certs = true

        }
      ]
    }
  }
  recording {
    recordings-directory = "/srv/recordings"
    # TODO: make this an optional param and remove the default
    finalize-script = "/usr/local/bin/finalize_recording.sh"
  }
  streaming {
    // A list of regex patterns for allowed RTMP URLs.  The RTMP URL used
    // when starting a stream must match at least one of the patterns in
    // this list.
    rtmp-allow-list = [
      // By default, all services are allowed
      ".*"
    ]
  }
  sip {
    // The routing rule for the outbound scenario in VoxImplant is based on this prefix
    outbound-prefix = "out_"
  }
  ffmpeg {
    resolution = "1920x1080"
    // The audio source that will be used to capture audio on Linux
    audio-source = "alsa"
    // The audio device that will be used to capture audio on Linux
    audio-device = "plug:bsnoop"
  }
  chrome {
    // The flags which will be passed to chromium when launching
    flags = [
      "--use-fake-ui-for-media-stream",
      "--start-maximized",
      "--kiosk",
      "--enabled",
      "--disable-infobars",
      "--autoplay-policy=no-user-gesture-required"
      "--ignore-certificate-errors"
    ]
  }
  stats {
    enable-stats-d = true
  }
  webhook {
    // A list of subscribers interested in receiving webhook events
    subscribers = []
  }
  jwt-info {
    // The path to a .pem file which will be used to sign JWT tokens used in webhook
    // requests.  If not set, no JWT will be added to webhook requests.
    # signing-key-path = "/path/to/key.pem"

    // The kid to use as part of the JWT
    # kid = "key-id"

    // The issuer of the JWT
    # issuer = "issuer"

    // The audience of the JWT
    # audience = "audience"

    // The TTL of each generated JWT.  Can't be less than 10 minutes.
    # ttl = 1 hour
  }
  call-status-checks {
    // If all clients have their audio and video muted and if Jibri does not
    // detect any data stream (audio or video) comming in, it will stop
    // recording after NO_MEDIA_TIMEOUT expires.
    no-media-timeout = 30 seconds

    // If all clients have their audio and video muted, Jibri consideres this
    // as an empty call and stops the recording after ALL_MUTED_TIMEOUT expires.
    all-muted-timeout = 10 minutes

    // When detecting if a call is empty, Jibri takes into consideration for how
    // long the call has been empty already. If it has been empty for more than
    // DEFAULT_CALL_EMPTY_TIMEOUT, it will consider it empty and stop the recording.
    default-call-empty-timeout = 30 seconds
  }
}

Nothing jumps out to me in your conf.

Restart services and share your complete Jibri log.

Jitsi

root@jitsi-dev:~# systemctl stop jitsi-videobridge2.service
root@jitsi-dev:~# systemctl stop jicofo.service
root@jitsi-dev:~# systemctl stop prosody.service
root@jitsi-dev:~# systemctl start prosody.service
root@jitsi-dev:~# systemctl start jicofo.service
root@jitsi-dev:~# systemctl start jitsi-videobridge2.service
root@jitsi-dev:~# systemctl status prosody.service
● prosody.service - Prosody XMPP Server
   Loaded: loaded (/lib/systemd/system/prosody.service; enabled; vendor preset: enabled)
   Active: active (running) since Sun 2021-06-20 18:41:55 CEST; 16s ago
     Docs: https://prosody.im/doc
 Main PID: 7242 (lua5.2)
    Tasks: 1 (limit: 65000)
   Memory: 8.4M
   CGroup: /system.slice/prosody.service
           └─7242 lua5.2 /usr/bin/prosody

Jun 20 18:41:55 jitsi-dev systemd[1]: Started Prosody XMPP Server.
root@jitsi-dev:~# systemctl status jicofo.service
● jicofo.service - LSB: Jitsi conference Focus
   Loaded: loaded (/etc/init.d/jicofo; generated)
   Active: active (running) since Sun 2021-06-20 18:41:59 CEST; 16s ago
     Docs: man:systemd-sysv-generator(8)
  Process: 7261 ExecStart=/etc/init.d/jicofo start (code=exited, status=0/SUCCESS)
    Tasks: 30 (limit: 65000)
   Memory: 149.8M
   CGroup: /system.slice/jicofo.service
           └─7266 java -Xmx3072m -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/tmp -Djdk.tls.ephemeralDHKeySize=2048 -Dconfig.file=/etc/jitsi/jicofo/jicofo.conf -Dnet.java.sip.communicator.SC_HOME_DIR_LOCATION=/etc/jitsi -Dnet.java.s

Jun 20 18:41:59 jitsi-dev systemd[1]: Starting LSB: Jitsi conference Focus...
Jun 20 18:41:59 jitsi-dev jicofo[7261]: Starting jicofo: jicofo started.
Jun 20 18:41:59 jitsi-dev systemd[1]: Started LSB: Jitsi conference Focus.
root@jitsi-dev:~# systemctl status jitsi-videobridge2.service
● jitsi-videobridge2.service - Jitsi Videobridge
   Loaded: loaded (/lib/systemd/system/jitsi-videobridge2.service; enabled; vendor preset: enabled)
   Active: active (running) since Sun 2021-06-20 18:42:05 CEST; 15s ago
  Process: 7316 ExecStartPost=/bin/bash -c echo $MAINPID > /var/run/jitsi-videobridge/jitsi-videobridge.pid (code=exited, status=0/SUCCESS)
 Main PID: 7315 (java)
    Tasks: 49 (limit: 65000)
   Memory: 159.1M
   CGroup: /system.slice/jitsi-videobridge2.service
           └─7315 java -Xmx3072m -XX:+UseG1GC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/tmp -Djdk.tls.ephemeralDHKeySize=2048 -Dconfig.file=/etc/jitsi/videobridge/jvb.conf -Dnet.java.sip.communicator.SC_HOME_DIR_LOCATION=/etc/jit

Jun 20 18:42:05 jitsi-dev systemd[1]: Starting Jitsi Videobridge...
Jun 20 18:42:05 jitsi-dev systemd[1]: Started Jitsi Videobridge.

Jibri

root@jibri01-dev:/var/log/jitsi/jibri# systemctl stop jibri
root@jibri01-dev:/var/log/jitsi/jibri# systemctl start jibri
root@jibri01-dev:/var/log/jitsi/jibri# systemctl status jibri
● jibri.service - Jibri Process
   Loaded: loaded (/etc/systemd/system/jibri.service; disabled; vendor preset: enabled)
   Active: active (running) since Sun 2021-06-20 18:43:38 CEST; 5s ago
 Main PID: 1592 (java)
    Tasks: 50 (limit: 4915)
   Memory: 130.8M
   CGroup: /system.slice/jibri.service
           └─1592 /usr/lib/jvm/java-11-openjdk-amd64/bin/java -Djava.util.logging.config.file=/etc/jitsi/jibri/logging.properties -Dconfig.file=/etc/jitsi/jibri/jibri.conf -jar /opt/jitsi/jibri/jibri.jar --config /etc/jitsi/jibri/config.

Jun 20 18:43:38 jibri01-dev systemd[1]: Started Jibri Process.
Jun 20 18:43:39 jibri01-dev launch.sh[1592]: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
Jun 20 18:43:39 jibri01-dev launch.sh[1592]: SLF4J: Defaulting to no-operation (NOP) logger implementation
Jun 20 18:43:39 jibri01-dev launch.sh[1592]: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
root@jibri01-dev:/var/log/jitsi/jibri# cat log.0.txt
2021-06-20 18:43:39.125 INFO: [1] org.jitsi.jibri.Main.log() Jibri run with args [--config, /etc/jitsi/jibri/config.json]
2021-06-20 18:43:39.185 INFO: [1] org.jitsi.jibri.Main.log() Checking legacy config file /etc/jitsi/jibri/config.json
2021-06-20 18:43:39.186 INFO: [1] org.jitsi.jibri.Main.log() Legacy config file /etc/jitsi/jibri/config.json doesn't exist
2021-06-20 18:43:39.362 INFO: [1] org.jitsi.jibri.Main.log() Jibri starting up with id jibri01-dev
2021-06-20 18:43:39.607 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() got jwtConfig: {}

2021-06-20 18:43:39.608 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() Unable to create JwtInfo: com.typesafe.config.ConfigException$Missing: /etc/jitsi/jibri/jibri.conf: 107: No configuration setting found for key 'signing-key-path'
2021-06-20 18:43:39.686 INFO: [1] org.jitsi.jibri.Main.log() Using port 3333 for internal HTTP API
2021-06-20 18:43:39.692 FINE: [19] org.jitsi.jibri.webhooks.v1.WebhookClient.log() Updating 0 subscribers of status
2021-06-20 18:43:39.876 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2021-06-20 18:43:39.885 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Connecting to xmpp environment on jitsi-dev.int.example.org with config XmppEnvironmentConfig(name=jibri env, xmppServerHosts=[jitsi-dev.int.example.org], xmppDomain=jitsi-dev.int.example.org, baseUrl=null, controlLogin=XmppCredentials(domain=auth.jitsi-dev.int.example.org, port=null, username=jibri, password=g8Z4R9kmEdKvpXBrY05E), controlMuc=XmppMuc(domain=internal.auth.jitsi-dev.int.example.org, roomName=JibriBrewery, nickname=jibri-nickname), sipControlMuc=null, callLogin=XmppCredentials(domain=recorder.jitsi-dev.int.example.org, port=null, username=recorder, password=AbNJapZbfExuEBMRLSYP), stripFromRoomDomain=conference., usageTimeoutMins=0, trustAllXmppCerts=true)
2021-06-20 18:43:39.886 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() The trustAllXmppCerts config is enabled for this domain, all XMPP server provided certificates will be accepted
2021-06-20 18:43:39.899 INFO: [1] org.jitsi.jibri.Main.log() Using port 2222 for HTTP API
2021-06-20 18:43:39.899 WARNING: [28] org.jitsi.xmpp.mucclient.MucClient.log() Disabling certificate verification!
2021-06-20 18:43:40.090 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Connected.
2021-06-20 18:43:40.090 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Logging in.
2021-06-20 18:43:40.142 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Joined MUC: jibribrewery@internal.auth.jitsi-dev.int.example.org
root@jibri01-dev:/var/log/jitsi/jibri#

Missing comma in Chrome’s flags

Thanks, I added the comma:

  chrome {
    // The flags which will be passed to chromium when launching
    flags = [
      "--use-fake-ui-for-media-stream",
      "--start-maximized",
      "--kiosk",
      "--enabled",
      "--disable-infobars",
      "--autoplay-policy=no-user-gesture-required",
      "--ignore-certificate-errors"
    ]
  }

Unfortunately no change, logs are still the same.

When you say logs are still the same, does that mean that no new things are being added when you click on recording?

It looks identical to me compared to the previos jibri.conf with the missing comma. And it does not add any error message after joining a room and trying to start recording.

Log after service restart:

root@jibri01-dev:/var/log/jitsi/jibri# cat log.0.txt
2021-06-21 15:18:03.634 INFO: [1] org.jitsi.jibri.Main.log() Jibri run with args [--config, /etc/jitsi/jibri/config.json]
2021-06-21 15:18:03.689 INFO: [1] org.jitsi.jibri.Main.log() Checking legacy config file /etc/jitsi/jibri/config.json
2021-06-21 15:18:03.690 INFO: [1] org.jitsi.jibri.Main.log() Legacy config file /etc/jitsi/jibri/config.json doesn't exist
2021-06-21 15:18:03.881 INFO: [1] org.jitsi.jibri.Main.log() Jibri starting up with id jibri01-dev
2021-06-21 15:18:04.117 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() got jwtConfig: {}

2021-06-21 15:18:04.117 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() Unable to create JwtInfo: com.typesafe.config.ConfigException$Missing: /etc/jitsi/jibri/jibri.conf: 107: No configuration setting found for key 'signing-key-path'
2021-06-21 15:18:04.194 INFO: [1] org.jitsi.jibri.Main.log() Using port 3333 for internal HTTP API
2021-06-21 15:18:04.200 FINE: [19] org.jitsi.jibri.webhooks.v1.WebhookClient.log() Updating 0 subscribers of status
2021-06-21 15:18:04.384 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2021-06-21 15:18:04.389 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Connecting to xmpp environment on jitsi-dev.int.example.org with config XmppEnvironmentConfig(name=jibri env, xmppServerHosts=[jitsi-dev.int.example.org], xmppDomain=jitsi-dev.int.example.org, baseUrl=null, controlLogin=XmppCredentials(domain=auth.jitsi-dev.int.example.org, port=null, username=jibri, password=g8Z4R9kmEdKvpXBrY05E), controlMuc=XmppMuc(domain=internal.auth.jitsi-dev.int.example.org, roomName=JibriBrewery, nickname=jibri-nickname), sipControlMuc=null, callLogin=XmppCredentials(domain=recorder.jitsi-dev.int.example.org, port=null, username=recorder, password=AbNJapZbfExuEBMRLSYP), stripFromRoomDomain=conference., usageTimeoutMins=0, trustAllXmppCerts=true)
2021-06-21 15:18:04.390 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() The trustAllXmppCerts config is enabled for this domain, all XMPP server provided certificates will be accepted
2021-06-21 15:18:04.403 INFO: [1] org.jitsi.jibri.Main.log() Using port 2222 for HTTP API
2021-06-21 15:18:04.404 WARNING: [28] org.jitsi.xmpp.mucclient.MucClient.log() Disabling certificate verification!
2021-06-21 15:18:04.609 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Connected.
2021-06-21 15:18:04.610 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Logging in.
2021-06-21 15:18:04.660 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Joined MUC: jibribrewery@internal.auth.jitsi-dev.int.example.org
root@jibri01-dev:/var/log/jitsi/jibri#

Log after joining room and click on recording:

root@jibri01-dev:/var/log/jitsi/jibri# cat log.0.txt
2021-06-21 15:18:03.634 INFO: [1] org.jitsi.jibri.Main.log() Jibri run with args [--config, /etc/jitsi/jibri/config.json]
2021-06-21 15:18:03.689 INFO: [1] org.jitsi.jibri.Main.log() Checking legacy config file /etc/jitsi/jibri/config.json
2021-06-21 15:18:03.690 INFO: [1] org.jitsi.jibri.Main.log() Legacy config file /etc/jitsi/jibri/config.json doesn't exist
2021-06-21 15:18:03.881 INFO: [1] org.jitsi.jibri.Main.log() Jibri starting up with id jibri01-dev
2021-06-21 15:18:04.117 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() got jwtConfig: {}

2021-06-21 15:18:04.117 INFO: [1] org.jitsi.jibri.webhooks.v1.JwtInfo.log() Unable to create JwtInfo: com.typesafe.config.ConfigException$Missing: /etc/jitsi/jibri/jibri.conf: 107: No configuration setting found for key 'signing-key-path'
2021-06-21 15:18:04.194 INFO: [1] org.jitsi.jibri.Main.log() Using port 3333 for internal HTTP API
2021-06-21 15:18:04.200 FINE: [19] org.jitsi.jibri.webhooks.v1.WebhookClient.log() Updating 0 subscribers of status
2021-06-21 15:18:04.384 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2021-06-21 15:18:04.389 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() Connecting to xmpp environment on jitsi-dev.int.example.org with config XmppEnvironmentConfig(name=jibri env, xmppServerHosts=[jitsi-dev.int.example.org], xmppDomain=jitsi-dev.int.example.org, baseUrl=null, controlLogin=XmppCredentials(domain=auth.jitsi-dev.int.example.org, port=null, username=jibri, password=g8Z4R9kmEdKvpXBrY05E), controlMuc=XmppMuc(domain=internal.auth.jitsi-dev.int.example.org, roomName=JibriBrewery, nickname=jibri-nickname), sipControlMuc=null, callLogin=XmppCredentials(domain=recorder.jitsi-dev.int.example.org, port=null, username=recorder, password=AbNJapZbfExuEBMRLSYP), stripFromRoomDomain=conference., usageTimeoutMins=0, trustAllXmppCerts=true)
2021-06-21 15:18:04.390 INFO: [1] org.jitsi.jibri.api.xmpp.XmppApi.log() The trustAllXmppCerts config is enabled for this domain, all XMPP server provided certificates will be accepted
2021-06-21 15:18:04.403 INFO: [1] org.jitsi.jibri.Main.log() Using port 2222 for HTTP API
2021-06-21 15:18:04.404 WARNING: [28] org.jitsi.xmpp.mucclient.MucClient.log() Disabling certificate verification!
2021-06-21 15:18:04.609 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Connected.
2021-06-21 15:18:04.610 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Logging in.
2021-06-21 15:18:04.660 INFO: [28] org.jitsi.xmpp.mucclient.MucClient.log() Joined MUC: jibribrewery@internal.auth.jitsi-dev.int.example.org
2021-06-21 15:19:04.189 FINE: [19] org.jitsi.jibri.webhooks.v1.WebhookClient.log() Updating 0 subscribers of status
root@jibri01-dev:/var/log/jitsi/jibri#

From the logs above, it appears you have not restarted after changing jibri.conf…Is it?

In case you didn’t, restart and check.

EDIT: My bad, you did restart…ok.

Are there any other logs or settings which I can share?

No. That’s the log file. Strange that no logs are being written to that file, when you click on recording.

Which guides?

Sorry, I was not able to add both links to the initial post.

This is the comment where I mentioned the link to the guides I have followed:

Do you have jibri and JMS on the same server?

What are the server configs, CPU, RAM?

Separate server, both Debian Buster 10.10:

JMS:
2 CPUs
4 GB RAM

root@jitsi-dev:~# uname -a
Linux jitsi-dev 4.19.0-17-amd64 #1 SMP Debian 4.19.194-1 (2021-06-10) x86_64 GNU/Linux
root@jitsi-dev:~# apt list --installed | grep jitsi

WARNING: apt does not have a stable CLI interface. Use with caution in scripts.

jitsi-meet-prosody/stable,now 1.0.5056-1 all [installed,automatic]
jitsi-meet-turnserver/stable,now 1.0.5056-1 all [installed,automatic]
jitsi-meet-web-config/stable,now 1.0.5056-1 all [installed,automatic]
jitsi-meet-web/stable,now 1.0.5056-1 all [installed,automatic]
jitsi-meet/stable,now 2.0.5963-1 all [installed]
jitsi-videobridge2/stable,now 2.1-508-gb24f756c-1 all [installed,automatic]
root@jitsi-dev:~#

Jibri:
4 CPUs
8 GB RAM

root@jibri01-dev:~# uname -a
Linux jibri01-dev 4.19.0-17-amd64 #1 SMP Debian 4.19.194-1 (2021-06-10) x86_64 GNU/Linux
root@jibri01-dev:~# apt list --installed | grep jibri

WARNING: apt does not have a stable CLI interface. Use with caution in scripts.

jibri/stable,now 8.0-93-g51fe7a2-1 all [installed]
root@jibri01-dev:~# apt list --installed | grep chrome

WARNING: apt does not have a stable CLI interface. Use with caution in scripts.

google-chrome-stable/stable,now 91.0.4472.114-1 amd64 [installed]
root@jibri01-dev:~# /usr/local/bin/chromedriver --version
ChromeDriver 91.0.4472.101 (af52a90bf87030dd1523486a1cd3ae25c5d76c9b-refs/branch-heads/4472@{#1462})
root@jibri01-dev:~# apt list --installed | grep ffmpeg

WARNING: apt does not have a stable CLI interface. Use with caution in scripts.

ffmpeg/stable,stable,now 7:4.1.6-1~deb10u1 amd64 [installed]

I tried both adoptopenjdk-8-hotspot and OpenJDK 11.

This issue is resolved. I set up the jitsi instance from scratch and it worked… I was not able to determine the root cause and cannot reproduce it anymore. Thanks for your support!