Greetings,
I have set up jitsi/jibri on my own machines per the respective instructions.
I am able to start recording from the meeting room, however when attempting to stop it, there is a delay of about 10 seconds before it reports recording is stopped. There is a .mp4 file produced in the recordings directory, however it seems corrupt (does not play). This is the jibri log (domain is redacted to example.com):
2020-03-26 15:18:04.734 INFO: [297] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@606c2f6d
2020-03-26 15:18:06.699 FINE: [301] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Visiting url https://conference.example.com/test_dvk#config.iAmRecorder=true&config.externalConnectUrl=null&config.startWithAudioMuted=true&config.startWithVideoMuted=true&interfaceConfig.APP_NAME="Jibri"&config.analytics.disabled=true&config.p2p.enabled=false
2020-03-26 15:18:09.646 INFO: [301] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Waited 1947 milliseconds for call page to load
2020-03-26 15:18:09.665 INFO: [301] org.jitsi.jibri.selenium.JibriSelenium.onSeleniumStateChange() Transitioning from state Starting up to Running
2020-03-26 15:18:09.665 INFO: [301] org.jitsi.jibri.service.impl.FileRecordingJibriService.invoke() Selenium joined the call, starting the capturer
2020-03-26 15:18:09.749 INFO: [301] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.launch() Starting ffmpeg with command ffmpeg -y -v info -f x11grab -draw_mouse 0 -r 30 -s 1280x720 -thread_queue_size 4096 -i :0.0+0,0 -f alsa -thread_queue_size 4096 -i plug:cloop -acodec aac -strict -2 -ar 44100 -c:v libx264 -preset veryfast -profile:v main -level 3.1 -pix_fmt yuv420p -r 30 -crf 25 -g 60 -tune zerolatency -f mp4 /tmp/recordings/jbcwrtulrhmqulrn/test_dvk_2020-03-26-15-18-04.mp4 ([ffmpeg, -y, -v, info, -f, x11grab, -draw_mouse, 0, -r, 30, -s, 1280x720, -thread_queue_size, 4096, -i, :0.0+0,0, -f, alsa, -thread_queue_size, 4096, -i, plug:cloop, -acodec, aac, -strict, -2, -ar, 44100, -c:v, libx264, -preset, veryfast, -profile:v, main, -level, 3.1, -pix_fmt, yuv420p, -r, 30, -crf, 25, -g, 60, -tune, zerolatency, -f, mp4, /tmp/recordings/jbcwrtulrhmqulrn/test_dvk_2020-03-26-15-18-04.mp4])
2020-03-26 15:18:11.020 INFO: [311] org.jitsi.jibri.capture.ffmpeg.FfmpegCapturer.onFfmpegStateMachineStateChange() Ffmpeg capturer transitioning from state Starting up to Running
2020-03-26 15:18:11.022 INFO: [311] org.jitsi.jibri.service.impl.FileRecordingJibriService.onServiceStateChange() File recording service transitioning from state Starting up to Running
2020-03-26 15:18:11.022 INFO: [311] org.jitsi.jibri.api.xmpp.XmppApi.invoke() Current service started up successfully, sending on iq <iq to='jibribrewery@internal.auth.conference.example.com/focus' id='jEypK-486' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='on'/></iq>
2020-03-26 15:18:24.792 INFO: [270] org.jitsi.jibri.selenium.JibriSelenium.run() Jibri client receive bitrates: {audio={download=36, upload=0}, download=1039, upload=0, video={download=1003, upload=0}}, all clients muted? false
2020-03-26 15:18:29.863 INFO: [287] org.jitsi.jibri.api.xmpp.XmppApi.handleJibriIq() Received JibriIq <iq to='jibri@auth.conference.example.com/qbY-Byfz' from='jibribrewery@internal.auth.conference.example.com/focus' id='amlicmlAYXV0aC5jb25mZXJlbmNlLnBveXBlLmlvL3FiWS1CeWZ6AFBSOEUxLTU3NjkASkfjiCfdAN0KxCdety5x6Q==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='stop'/></iq> from environment [MucClient id=conference.example.com hostname=conference.example.com]
2020-03-26 15:18:29.864 FINE: [287] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: stop:recording
2020-03-26 15:18:29.864 INFO: [287] org.jitsi.jibri.JibriManager.stopService() Stopping the current service
2020-03-26 15:18:29.864 INFO: [287] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Stopping capturer
2020-03-26 15:18:29.864 INFO: [287] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() Stopping ffmpeg process
2020-03-26 15:18:39.732 INFO: [270] org.jitsi.jibri.selenium.JibriSelenium.run() Jibri client receive bitrates: {audio={download=37, upload=0}, download=972, upload=0, video={download=935, upload=0}}, all clients muted? false
2020-03-26 15:18:39.867 SEVERE: [287] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() Error trying to gracefully stop ffmpeg, destroying forcibly
2020-03-26 15:18:39.877 INFO: [287] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() ffmpeg exited with value 137
2020-03-26 15:18:39.877 INFO: [287] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Quitting selenium
2020-03-26 15:18:39.897 INFO: [287] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Participants in this recording: []
2020-03-26 15:18:39.920 INFO: [287] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 17 log entries for type browser
2020-03-26 15:18:39.984 INFO: [287] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 729 log entries for type driver
2020-03-26 15:18:40.148 INFO: [287] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 0 log entries for type client
2020-03-26 15:18:40.149 INFO: [287] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Leaving web call
2020-03-26 15:18:40.454 INFO: [287] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Quitting chrome driver
2020-03-26 15:18:40.593 INFO: [287] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Chrome driver quit
2020-03-26 15:18:40.593 INFO: [287] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Finalizing the recording
2020-03-26 15:18:50.596 SEVERE: [287] org.jitsi.jibri.service.impl.FileRecordingJibriService.finalize() Timed out waiting for process logger task to complete
2020-03-26 15:18:50.597 INFO: [287] org.jitsi.jibri.service.impl.FileRecordingJibriService.finalize() Recording finalize script finished with exit value 0
2020-03-26 15:18:50.597 INFO: [287] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: BUSY -> IDLE
2020-03-26 15:18:50.597 INFO: [287] 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-03-26 15:18:50.598 INFO: [287] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@644f5283
It appears Jibri has trouble stopping the ffmpeg process gracefully.
System running Jibri is Ubuntu 18.04.4, ffmpeg is current from repositories: version 3.4.6-0ubuntu0.18.04.1
Any ideas?
