Jitsi: failure of recoreder

[kevkevinpal]

not really related to this repo but jibri recorder is failing on our jitsi service I'm seeing this error in the logs

vi /var/log/jitsi/jibri/log.0.txt

sis: 0, all clients muted? false
2023-08-01 16:38:04.900 INFO: [19] [session_id=jhljbgcvasvatygw] MediaReceivedStatusCheck.run#43: Jibri client receive bitrates: {audio={download=31, upload=0}, download=142, upload=0, video={download=111, upload=0}}, num participants: 22, numMutedParticipants: 21, numJigasis: 0, all clients muted? false
2023-08-01 16:38:07.613 FINE: [19] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
2023-08-01 16:38:21.082 INFO: [19] [session_id=jhljbgcvasvatygw] MediaReceivedStatusCheck.run#43: Jibri client receive bitrates: {audio={download=30, upload=0}, download=371, upload=0, video={download=341, upload=0}}, num participants: 22, numMutedParticipants: 21, numJigasis: 0, all clients muted? false
2023-08-01 16:38:34.375 INFO: [19] [session_id=jhljbgcvasvatygw] MediaReceivedStatusCheck.run#43: Jibri client receive bitrates: {audio={download=32, upload=0}, download=458, upload=0, video={download=426, upload=0}}, num participants: 22, numMutedParticipants: 21, numJigasis: 0, all clients muted? false
2023-08-01 16:38:49.712 INFO: [19] [session_id=jhljbgcvasvatygw] MediaReceivedStatusCheck.run#43: Jibri client receive bitrates: {audio={download=49, upload=0}, download=71, upload=0, video={download=22, upload=0}}, num participants: 22, numMutedParticipants: 20, numJigasis: 0, all clients muted? false
2023-08-01 16:39:04.794 INFO: [19] [session_id=jhljbgcvasvatygw] MediaReceivedStatusCheck.run#43: Jibri client receive bitrates: {audio={download=40, upload=0}, download=88, upload=0, video={download=48, upload=0}}, num participants: 22, numMutedParticipants: 20, numJigasis: 0, all clients muted? false
2023-08-01 16:39:07.614 FINE: [19] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
2023-08-01 16:39:12.539 FINE: [19] [session_id=jhljbgcvasvatygw] ProcessStatePublisher$startProcessAliveChecks$1.invoke#86: Process ffmpeg hasn't written in 2 seconds, publishing periodic update
2023-08-01 16:39:12.553 INFO: [277] [session_id=jhljbgcvasvatygw] FfmpegCapturer.onFfmpegProcessUpdate#125: Ffmpeg quit abruptly.  Last output line: frame=14110 fps= 27 q=19.0 size=   24576kB time=00:07:50.32 bitrate= 428.1kbits/s speed=0.888x
2023-08-01 16:39:12.555 INFO: [277] [session_id=jhljbgcvasvatygw] FfmpegCapturer.onFfmpegStateMachineStateChange#134: Ffmpeg capturer transitioning from state Running to Error: QuitUnexpectedly SESSION frame=14110 fps= 27 q=19.0 size=   24576kB time=00:07:50.32 bitrate= 428.1kbits/s speed=0.888x
2023-08-01 16:39:12.558 INFO: [277] [session_id=jhljbgcvasvatygw] StatefulJibriService.onServiceStateChange#39: File recording service transitioning from state Running to Error: QuitUnexpectedly SESSION frame=14110 fps= 27 q=19.0 size=   24576kB time=00:07:50.32 bitrate= 428.1kbits/s speed=0.888x
2023-08-01 16:39:12.559 INFO: [277] XmppApi$createServiceStatusHandler$1.invoke#317: Current service had an error Error: QuitUnexpectedly SESSION frame=14110 fps= 27 q=19.0 size=   24576kB time=00:07:50.32 bitrate= 428.1kbits/s speed=0.888x    , sending error iq <iq xmlns='jabber:client' to='[email protected]/focus' id='FUV3T-2890' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq>
2023-08-01 16:39:12.559 FINE: [277] JibriStatsDClient.incrementCounter#38: Incrementing statsd counter: stop:recording
2023-08-01 16:39:12.562 INFO: [277] JibriManager.stopService#260: Stopping the current service
2023-08-01 16:39:12.562 INFO: [277] [session_id=jhljbgcvasvatygw] FileRecordingJibriService.stop#182: Stopping capturer
2023-08-01 16:39:12.562 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSubprocess.stop#75: Stopping ffmpeg process
2023-08-01 16:39:12.578 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSubprocess.stop#89: ffmpeg exited with value 137
2023-08-01 16:39:12.579 INFO: [277] [session_id=jhljbgcvasvatygw] FileRecordingJibriService.stop#184: Quitting selenium
2023-08-01 16:39:12.619 INFO: [277] [session_id=jhljbgcvasvatygw] FileRecordingJibriService.stop#211: Participants in this recording: []
2023-08-01 16:39:12.730 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#338: Leaving call and quitting browser
2023-08-01 16:39:12.730 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#341: Recurring call status checks cancelled
2023-08-01 16:39:12.739 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#347: Got 25 log entries for type browser
2023-08-01 16:39:13.079 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#347: Got 3937 log entries for type driver
2023-08-01 16:39:13.420 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#347: Got 0 log entries for type client
2023-08-01 16:39:13.421 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#356: Leaving web call
2023-08-01 16:39:14.090 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#363: Quitting chrome driver
2023-08-01 16:39:14.305 INFO: [277] [session_id=jhljbgcvasvatygw] JibriSelenium.leaveCallAndQuitBrowser#365: Chrome driver quit
2023-08-01 16:39:14.305 INFO: [277] [session_id=jhljbgcvasvatygw] FileRecordingJibriService.stop#232: Finalizing the recording
2023-08-01 16:39:14.305 INFO: [277] JibriServiceFinalizeCommandRunner.doFinalize#44: Finalizing the jibri service operation using command [/srv/finalize_recording.sh, /srv/recordings/jhljbgcvasvatygw]
2023-08-01 16:39:15.099 INFO: [278] LoggingUtils$Companion$OutputLogger$1.invoke$lambda-0#42: Uploading: /srv/recordings/jhljbgcvasvatygw/sphinx.call.698174930.840225_2023-08-01-16-30-13.mp4 (video/mp4) to stakwork-uploads:sphinx-private-graph/sphinx.call.698174930.840225.mp4
2023-08-01 16:39:15.099 INFO: [278] LoggingUtils$Companion$OutputLogger$1.invoke$lambda-0#42:
2023-08-01 16:39:15.099 INFO: [278] LoggingUtils$Companion$OutputLogger$1.invoke$lambda-0#42: ################################                                          44.9%
2023-08-01 16:39:15.099 INFO: [278] LoggingUtils$Companion$OutputLogger$1.invoke$lambda-0#42: ######################################################################## 100.0%
2023-08-01 16:39:15.099 INFO: [277] JibriServiceFinalizeCommandRunner.doFinalize#60: Finalize script finished with exit value 0
2023-08-01 16:39:15.100 INFO: [277] JibriStatusManager$special$$inlined$observable$1.afterChange#72: Busy status has changed: BUSY -> IDLE
2023-08-01 16:39:15.102 FINE: [277] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
2023-08-01 16:39:15.103 INFO: [277] XmppApi.updatePresence#209: Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
2023-08-01 16:40:07.614 FINE: [19] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status