Recording stop work after setup jwt token

Hello people!

My jibri stopped working after I installed the jwt token on the prosody, follow the jibri log. Does anyone know what can it be?

Thank you.

2020-10-22 10:50:07.953 INFO: [83] org.jitsi.xmpp.mucclient.MucClient.log() [MucClient id=videocorp2.conferenciacorp.com.br hostname=videocorp2.conferenciacorp.com.br] connected
2020-10-22 10:50:07.975 INFO: [83] org.jitsi.xmpp.mucclient.MucClient.log() Leaving a MUC we already occupy.
2020-10-22 10:50:08.024 INFO: [83] org.jitsi.xmpp.mucclient.MucClient.log() Joined MUC: jibribrewery@internal.auth.videocorp2.conferenciacorp.com.br
2020-10-22 10:50:08.845 INFO: [91] org.jitsi.jibri.api.xmpp.XmppApi.handleJibriIq() Received JibriIq <iq to='jibri@auth.videocorp3.conferenciacorp.com.br/nUWOKzIy' from='jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/focus' id='amlicmlAYXV0aC52aWRlb2NvcnAzLmNvbmZlcmVuY2lhY29ycC5jb20uYnIvblVXT0t6SXkAR0ZNQk4tMTAxNTA2MDcwAPcuiz1yH9Nq3R2CDYtxxFA=' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='start' recording_mode='file' room='60b4f76d-8c06-417c-ba6d-270755d55ab2-6883@conference.videocorp3.conferenciacorp.com.br' session_id='zdnkhsgvrjjfygjk' app_data='{"file_recording_metadata":{"share":true}}'/></iq> from environment [MucClient id=videocorp3.conferenciacorp.com.br hostname=videocorp3.conferenciacorp.com.br]
2020-10-22 10:50:08.846 INFO: [91] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Received start request
2020-10-22 10:50:08.846 INFO: [91] org.jitsi.jibri.api.xmpp.XmppApi.handleStartJibriIq() Sending 'pending' response to start IQ
2020-10-22 10:50:08.846 INFO: [92] org.jitsi.jibri.api.xmpp.XmppApi.run() Starting service
2020-10-22 10:50:08.856 INFO: [92] org.jitsi.jibri.api.xmpp.XmppApi.handleStartService() Parsed call url info: CallUrlInfo(baseUrl=https://videocorp3.conferenciacorp.com.br, callName=60b4f76d-8c06-417c-ba6d-270755d55ab2-6883, urlParams=[])
2020-10-22 10:50:08.857 INFO: [92] org.jitsi.jibri.JibriManager.startFileRecording() Starting a file recording with params: FileRecordingRequestParams(callParams=CallParams(callUrlInfo=CallUrlInfo(baseUrl=https://videocorp3.conferenciacorp.com.br, callName=60b4f76d-8c06-417c-ba6d-270755d55ab2-6883, urlParams=[])), sessionId=zdnkhsgvrjjfygjk, callLoginParams=XmppCredentials(domain=recorder.videocorp3.conferenciacorp.com.br, username=recorder, password=strdig13)) finalize script path: /path/to/finalize_recording.sh and recordings directory: /srv/recordings
2020-10-22 10:50:09.263 INFO: [92] org.openqa.selenium.remote.ProtocolHandshake.createSession() Detected dialect: OSS
2020-10-22 10:50:09.269 INFO: [92] org.jitsi.jibri.selenium.JibriSelenium.<init>() Starting empty call check with a timeout of PT30S
2020-10-22 10:50:09.270 FINE: [92] org.jitsi.jibri.capture.ffmpeg.FfmpegCapturer.<init>() Detected os as OS: LINUX
2020-10-22 10:50:09.270 INFO: [92] org.jitsi.jibri.service.impl.FileRecordingJibriService.<init>() Writing recording to /srv/recordings/zdnkhsgvrjjfygjk
2020-10-22 10:50:09.270 FINE: [92] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: start:recording
2020-10-22 10:50:09.271 INFO: [92] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: IDLE -> BUSY
2020-10-22 10:50:09.271 INFO: [92] 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-10-22 10:50:09.271 INFO: [92] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@56883b4c
2020-10-22 10:50:10.838 FINE: [101] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Visiting url https://videocorp3.conferenciacorp.com.br/60b4f76d-8c06-417c-ba6d-270755d55ab2-6883#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-10-22 10:50:35.749 INFO: [39] org.jitsi.xmpp.mucclient.MucClient.log() [MucClient id=videocorp.conferenciacorp.com.br hostname=videocorp.conferenciacorp.com.br] reconnection failed
2020-10-22 10:50:35.750 WARNING: [103] org.jivesoftware.smack.AbstractXMPPConnection.callConnectionClosedOnErrorListener() Connection XMPPTCPConnection[not-authenticated] (0) closed with error
org.jivesoftware.smack.XMPPException$StreamErrorException: host-unknown You can read more about the meaning of this stream error at http://xmpp.org/rfcs/rfc6120.html#streams-error-conditions
<stream:error><host-unknown xmlns='urn:ietf:params:xml:ns:xmpp-streams'/><text>This server does not serve auth.videocorp.conferenciacorp.com.br</text></stream:error>
        at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.parsePackets(XMPPTCPConnection.java:1064)
        at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.access$300(XMPPTCPConnection.java:1000)
        at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader$1.run(XMPPTCPConnection.java:1016)
        at java.lang.Thread.run(Thread.java:748)
2020-10-22 10:50:35.750 INFO: [103] org.jitsi.xmpp.mucclient.MucClient.log() [MucClient id=videocorp.conferenciacorp.com.br hostname=videocorp.conferenciacorp.com.br] closed on error:
org.jivesoftware.smack.XMPPException$StreamErrorException: host-unknown You can read more about the meaning of this stream error at http://xmpp.org/rfcs/rfc6120.html#streams-error-conditions
<stream:error><host-unknown xmlns='urn:ietf:params:xml:ns:xmpp-streams'/><text>This server does not serve auth.videocorp.conferenciacorp.com.br</text></stream:error>
        at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.parsePackets(XMPPTCPConnection.java:1064)
        at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader.access$300(XMPPTCPConnection.java:1000)
        at org.jivesoftware.smack.tcp.XMPPTCPConnection$PacketReader$1.run(XMPPTCPConnection.java:1016)
        at java.lang.Thread.run(Thread.java:748)
2020-10-22 10:50:41.564 SEVERE: [101] org.jitsi.jibri.selenium.pageobjects.CallPage.visit() Timed out waiting for call page to load
2020-10-22 10:50:41.564 INFO: [101] org.jitsi.jibri.selenium.JibriSelenium.onSeleniumStateChange() Transitioning from state Starting up to Error: SESSION Failed to join call
2020-10-22 10:50:41.565 INFO: [101] org.jitsi.jibri.service.impl.FileRecordingJibriService.onServiceStateChange() File recording service transitioning from state Starting up to Error: SESSION Failed to join call
2020-10-22 10:50:41.565 INFO: [101] org.jitsi.jibri.api.xmpp.XmppApi.invoke() Current service had an error, sending error iq <iq to='jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/focus' id='buZZd-93' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq>
2020-10-22 10:50:41.565 FINE: [101] org.jitsi.jibri.statsd.JibriStatsDClient.incrementCounter() Incrementing statsd counter: stop:recording
2020-10-22 10:50:41.566 INFO: [101] org.jitsi.jibri.JibriManager.stopService() Stopping the current service
2020-10-22 10:50:41.566 INFO: [101] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Stopping capturer
2020-10-22 10:50:41.566 INFO: [101] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() Stopping ffmpeg process
2020-10-22 10:50:41.566 INFO: [101] org.jitsi.jibri.util.JibriSubprocess.ffmpeg.stop() ffmpeg exited with value null
2020-10-22 10:50:41.567 INFO: [101] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Quitting selenium
2020-10-22 10:50:41.575 INFO: [101] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Participants in this recording: []
2020-10-22 10:50:41.589 INFO: [101] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 28 log entries for type browser
2020-10-22 10:50:41.675 INFO: [101] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 1017 log entries for type driver
2020-10-22 10:50:41.880 INFO: [101] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Got 0 log entries for type client
2020-10-22 10:50:41.881 INFO: [101] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Leaving web call
2020-10-22 10:50:41.923 INFO: [101] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Quitting chrome driver
2020-10-22 10:50:41.995 INFO: [101] org.jitsi.jibri.selenium.JibriSelenium.leaveCallAndQuitBrowser() Chrome driver quit
2020-10-22 10:50:41.995 INFO: [101] org.jitsi.jibri.service.impl.FileRecordingJibriService.stop() Finalizing the recording
2020-10-22 10:50:41.996 SEVERE: [101] org.jitsi.jibri.service.impl.FileRecordingJibriService.finalize() Failed to run finalize script: java.io.IOException: Cannot run program "/path/to/finalize_recording.sh": error=2, No such file or directory with stack:
java.lang.ProcessBuilder.start(ProcessBuilder.java:1048)
org.jitsi.jibri.util.ProcessWrapper.start(ProcessWrapper.kt:87)
org.jitsi.jibri.service.impl.FileRecordingJibriService.finalize(FileRecordingJibriService.kt:205)
org.jitsi.jibri.service.impl.FileRecordingJibriService.stop(FileRecordingJibriService.kt:190)
org.jitsi.jibri.JibriManager.stopService(JibriManager.kt:254)
org.jitsi.jibri.JibriManager$startService$1.invoke(JibriManager.kt:205)
org.jitsi.jibri.JibriManager$startService$1.invoke(JibriManager.kt:84)
org.jitsi.jibri.util.StatusPublisher$addStatusHandler$1.invoke(StatusPublisher.kt:37)
org.jitsi.jibri.util.StatusPublisher$addStatusHandler$1.invoke(StatusPublisher.kt:29)
org.jitsi.jibri.util.StatusPublisher$publishStatus$1.invoke(StatusPublisher.kt:53)
org.jitsi.jibri.util.StatusPublisher$publishStatus$1.invoke(StatusPublisher.kt:29)
kotlin.collections.CollectionsKt__MutableCollectionsKt.filterInPlace$CollectionsKt__MutableCollectionsKt(MutableCollections.kt:191)
kotlin.collections.CollectionsKt__MutableCollectionsKt.retainAll(MutableCollections.kt:182)
org.jitsi.jibri.util.StatusPublisher.publishStatus(StatusPublisher.kt:53)
org.jitsi.jibri.service.impl.StatefulJibriService.onServiceStateChange(StatefulJibriService.kt:40)
org.jitsi.jibri.service.impl.StatefulJibriService.access$onServiceStateChange(StatefulJibriService.kt:26)
org.jitsi.jibri.service.impl.StatefulJibriService$1.invoke(StatefulJibriService.kt:35)
org.jitsi.jibri.service.impl.StatefulJibriService$1.invoke(StatefulJibriService.kt:26)
org.jitsi.jibri.util.NotifyingStateMachine.notify(NotifyingStateMachine.kt:26)
org.jitsi.jibri.service.JibriServiceStateMachine.access$notify(JibriServiceStateMachine.kt:46)
org.jitsi.jibri.service.JibriServiceStateMachine$stateMachine$1$5.invoke(JibriServiceStateMachine.kt:100)
org.jitsi.jibri.service.JibriServiceStateMachine$stateMachine$1$5.invoke(JibriServiceStateMachine.kt:46)
com.tinder.StateMachine.notifyOnTransition(StateMachine.kt:65)
com.tinder.StateMachine.transition(StateMachine.kt:23)
org.jitsi.jibri.service.JibriServiceStateMachine.transition(JibriServiceStateMachine.kt:112)
org.jitsi.jibri.service.impl.StatefulJibriService$registerSubComponent$1.invoke(StatefulJibriService.kt:46)
org.jitsi.jibri.service.impl.StatefulJibriService$registerSubComponent$1.invoke(StatefulJibriService.kt:26)
org.jitsi.jibri.util.StatusPublisher$addStatusHandler$1.invoke(StatusPublisher.kt:37)
org.jitsi.jibri.util.StatusPublisher$addStatusHandler$1.invoke(StatusPublisher.kt:29)
org.jitsi.jibri.util.StatusPublisher$publishStatus$1.invoke(StatusPublisher.kt:53)
org.jitsi.jibri.util.StatusPublisher$publishStatus$1.invoke(StatusPublisher.kt:29)
kotlin.collections.CollectionsKt__MutableCollectionsKt.filterInPlace$CollectionsKt__MutableCollectionsKt(MutableCollections.kt:191)
kotlin.collections.CollectionsKt__MutableCollectionsKt.retainAll(MutableCollections.kt:182)
org.jitsi.jibri.util.StatusPublisher.publishStatus(StatusPublisher.kt:53)
org.jitsi.jibri.selenium.JibriSelenium.onSeleniumStateChange(JibriSelenium.kt:181)
org.jitsi.jibri.selenium.JibriSelenium.access$onSeleniumStateChange(JibriSelenium.kt:112)
org.jitsi.jibri.selenium.JibriSelenium$1.invoke(JibriSelenium.kt:166)
org.jitsi.jibri.selenium.JibriSelenium$1.invoke(JibriSelenium.kt:112)
org.jitsi.jibri.util.NotifyingStateMachine.notify(NotifyingStateMachine.kt:26)
org.jitsi.jibri.selenium.SeleniumStateMachine.access$notify(SeleniumStateMachine.kt:34)
org.jitsi.jibri.selenium.SeleniumStateMachine$stateMachine$1$5.invoke(SeleniumStateMachine.kt:79)
org.jitsi.jibri.selenium.SeleniumStateMachine$stateMachine$1$5.invoke(SeleniumStateMachine.kt:34)
com.tinder.StateMachine.notifyOnTransition(StateMachine.kt:65)
com.tinder.StateMachine.transition(StateMachine.kt:23)
org.jitsi.jibri.selenium.SeleniumStateMachine.transition(SeleniumStateMachine.kt:84)
org.jitsi.jibri.selenium.JibriSelenium$joinCall$1.run(JibriSelenium.kt:257)
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
java.util.concurrent.FutureTask.run(FutureTask.java:266)
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
java.lang.Thread.run(Thread.java:748)

2020-10-22 10:50:41.997 INFO: [101] org.jitsi.jibri.status.JibriStatusManager.log() Busy status has changed: BUSY -> IDLE
2020-10-22 10:50:41.997 INFO: [101] 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-10-22 10:50:41.997 INFO: [101] org.jitsi.xmpp.mucclient.MucClientManager.log() Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@246597ac

Looks like Jibri can’t join the call. Probably try going over the steps again to make sure your configuration is correct? Not sure if Jicofo logs would be helpful in this instance, but it might be worth checking.

Hello!

It is the second environment that I install from scratch and exactly the same thing happened as the first.

Here is the log:

Jicofo 2020-10-22 13:11:30.550 INFO: [37] org.jitsi.jicofo.health.Health.log() Performed a successful health check in PT0.00973S. Sticky failure: false
Jicofo 2020-10-22 13:11:33.621 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting session with Jibri jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce
Jicofo 2020-10-22 13:11:33.621 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Starting Jibri jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce for stream ID: null in room: 60b4f76d-8c06-417c-ba6d-270755d55ab2-6883@conference.videocorp3.conferenciacorp.com.br
Jicofo 2020-10-22 13:11:33.626 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.videocorp3.conferenciacorp.com.br/focus94993102234770' from='jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce' id='jVDNx-1398902' type='result'><jibri xmlns='http://jitsi.org/protocol/jibri' status='pending'/></iq> for 60b4f76d-8c06-417c-ba6d-270755d55ab2-6883@conference.videocorp3.conferenciacorp.com.br
Jicofo 2020-10-22 13:11:33.627 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce has status pending and failure reason null, current Jibri jid is jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce
Jicofo 2020-10-22 13:11:33.627 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Started Jibri session
Jicofo 2020-10-22 13:11:34.060 INFO: [33] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce 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-10-22 13:11:34.061 INFO: [33] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce available: false
Jicofo 2020-10-22 13:11:40.549 INFO: [37] org.jitsi.jicofo.health.Health.log() Performed a successful health check in PT0.009068S. Sticky failure: false
Jicofo 2020-10-22 13:11:50.549 INFO: [37] org.jitsi.jicofo.health.Health.log() Performed a successful health check in PT0.009417S. Sticky failure: false
Jicofo 2020-10-22 13:12:00.549 INFO: [37] org.jitsi.jicofo.health.Health.log() Performed a successful health check in PT0.008903S. Sticky failure: false
Jicofo 2020-10-22 13:12:06.449 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Updating status from JIBRI: <iq to='focus@auth.videocorp3.conferenciacorp.com.br/focus94993102234770' from='jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce' id='Zm9jdXNAYXV0aC52aWRlb2NvcnAzLmNvbmZlcmVuY2lhY29ycC5jb20uYnIvZm9jdXM5NDk5MzEwMjIzNDc3MABqa2dZZy0zMjcAtwTLBTKDIsBgZEUkPM3ZMQ==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error'/></iq> for 60b4f76d-8c06-417c-ba6d-270755d55ab2-6883@conference.videocorp3.conferenciacorp.com.br
Jicofo 2020-10-22 13:12:06.449 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Got Jibri status update: Jibri jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce has status off and failure reason error, current Jibri jid is jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce
Jicofo 2020-10-22 13:12:06.449 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri is no longer pending, cancelling pending timeout task
Jicofo 2020-10-22 13:12:06.449 WARNING: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() failureReason was non-null but shouldRetry wasn't set, will NOT retry
Jicofo 2020-10-22 13:12:06.449 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Jibri failed and signaled that we should not retry the same request
Jicofo 2020-10-22 13:12:06.449 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriRecorder.log() Got jibri status off and failure error
Jicofo 2020-10-22 13:12:06.450 INFO: [301] 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='kollhvhorqrwkrfv' recording_mode='file'/> in: 60b4f76d-8c06-417c-ba6d-270755d55ab2-6883@conference.videocorp3.conferenciacorp.com.br
Jicofo 2020-10-22 13:12:06.450 INFO: [301] org.jitsi.jicofo.recording.jibri.JibriSession.log() Cleaning up current JibriSession
Jicofo 2020-10-22 13:12:06.781 INFO: [33] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Received Jibri jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce 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-10-22 13:12:06.781 INFO: [33] org.jitsi.jicofo.recording.jibri.JibriDetector.log() Jibri: jibribrewery@internal.auth.videocorp3.conferenciacorp.com.br/61f28b9fe436ca92dd224ac02d5933f62ce available: true