Jibri CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined

pawelkuzma · January 20, 2023, 11:48am

Hi,

I’m using docker version.
I have problem with recording from the beginning of working with jitsi. Right now I have fresh / default settings for recording:

PUBLIC_URL=https://test.dev
ENABLE_XMPP_WEBSOCKET=0
XMPP_DOMAIN=meet.jitsi
XMPP_SERVER=xmpp.meet.jitsi
ENABLE_RECORDING=1
ENABLE_LIVESTREAMING=true
ENABLE_SERVICE_RECORDING=true
XMPP_RECORDER_DOMAIN=recorder.meet.jitsi
JIBRI_RECORDER_USER=recorder
JIBRI_RECORDING_DIR=/config/recordings
JIBRI_FINALIZE_RECORDING_SCRIPT_PATH=/config/finalize.sh
JIBRI_XMPP_USER=jibri
JIBRI_BREWERY_MUC=jibribrewery
JIBRI_PENDING_TIMEOUT=90

Jibri logs:

 Jibri 2023-01-20 11:23:57.699 INFO: [47] XmppApi.handleStartJibriIq#281: Sending 'pending' response to start IQ
 Jibri 2023-01-20 11:23:57.708 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
 Jibri 2023-01-20 11:23:57.708 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
 Jibri 2023-01-20 11:23:57.713 INFO: [60] AbstractPageObject.visit#32: Visiting url https://webagent-dev11.alfavox.dev/video
 Jibri 2023-01-20 11:23:58.603 INFO: [60] AbstractPageObject.visit#38: Waited 883.316646ms for driver to load page
 Jibri 2023-01-20 11:23:59.308 INFO: [60] AbstractPageObject.visit#32: Visiting url https://webagent-dev11.alfavox.dev/video/8498551f-d936-4777-a2cd-0fda1e7ade45#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.prejoinConfig.enabled=false&config.requireDisplayName=false
 Jibri 2023-01-20 11:23:59.863 INFO: [60] AbstractPageObject.visit#38: Waited 554.787663ms for driver to load page
 Jibri 2023-01-20 11:23:59.968 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:00.502 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:01.096 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:01.613 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:02.155 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:02.708 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:03.296 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:03.848 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:04.376 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:04.975 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:05.489 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:06.015 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:06.557 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:07.094 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:07.609 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:08.126 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:08.642 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:09.152 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:09.673 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:10.186 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:10.699 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:11.220 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:11.733 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:12.269 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:12.790 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:13.311 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:13.836 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:14.350 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:14.879 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:15.416 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:15.926 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:16.449 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:16.995 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:17.531 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:18.053 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:18.598 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:19.125 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:19.638 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:20.155 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:20.681 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:21.213 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:21.732 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:22.264 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:22.796 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:23.311 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:23.838 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:24.350 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:24.865 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:25.385 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:25.905 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:26.420 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:26.933 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:27.455 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:27.973 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:28.497 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:29.016 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:29.548 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:30.068 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
 Jibri 2023-01-20 11:24:30.101 SEVERE: [60] CallPage.visit#65: Timed out waiting for call page to load
 Jibri 2023-01-20 11:24:30.160 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.onSeleniumStateChange#216: Transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
 Jibri 2023-01-20 11:24:30.162 INFO: [60] [session_id=waowmhcrfzdbqdyg] StatefulJibriService.onServiceStateChange#39: File recording service transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
 Jibri 2023-01-20 11:24:30.168 INFO: [60] XmppApi$createServiceStatusHandler$1.invoke#317: Current service had an error Error: FailedToJoinCall SESSION Failed to join the call, sending error iq <iq xmlns='jabber:client' to='jibribrewery@internal-muc.meet.jitsi/focus' id='83FLL-15' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq>
 Jibri 2023-01-20 11:24:30.185 FINE: [60] JibriStatsDClient.incrementCounter#38: Incrementing statsd counter: stop:recording
 Jibri 2023-01-20 11:24:30.185 INFO: [60] JibriManager.stopService#260: Stopping the current service
 Jibri 2023-01-20 11:24:30.186 INFO: [60] [session_id=waowmhcrfzdbqdyg] FileRecordingJibriService.stop#182: Stopping capturer
 Jibri 2023-01-20 11:24:30.197 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSubprocess.stop#75: Stopping ffmpeg process
 Jibri 2023-01-20 11:24:30.199 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSubprocess.stop#89: ffmpeg exited with value null
 Jibri 2023-01-20 11:24:30.199 INFO: [60] [session_id=waowmhcrfzdbqdyg] FileRecordingJibriService.stop#184: Quitting selenium
 Jibri 2023-01-20 11:24:30.200 INFO: [60] [session_id=waowmhcrfzdbqdyg] FileRecordingJibriService.stop#191: No media was recorded, deleting directory and skipping metadata file & finalize
 Jibri 2023-01-20 11:24:30.205 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#338: Leaving call and quitting browser
 Jibri 2023-01-20 11:24:30.207 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#341: Recurring call status checks cancelled
 Jibri 2023-01-20 11:24:30.267 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#347: Got 2 log entries for type browser
 Jibri 2023-01-20 11:24:30.570 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#347: Got 871 log entries for type driver
 Jibri 2023-01-20 11:24:30.970 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#347: Got 0 log entries for type client
 Jibri 2023-01-20 11:24:30.971 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#356: Leaving web call
 Jibri 2023-01-20 11:24:31.052 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#363: Quitting chrome driver
 Jibri 2023-01-20 11:24:31.209 INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.leaveCallAndQuitBrowser#365: Chrome driver quit
 Jibri 2023-01-20 11:24:31.212 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: checking for value via suppliers:
   LambdaSupplier: 'JibriConfig::singleUseMode'
   ConfigSourceSupplier: key: 'jibri.single-use-mode', type: 'kotlin.Boolean', source: 'config'
 Jibri 2023-01-20 11:24:31.213 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: LambdaSupplier: Trying to retrieve value via JibriConfig::singleUseMode
 Jibri 2023-01-20 11:24:31.214 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: failed to find value via LambdaSupplier: 'JibriConfig::singleUseMode': org.jitsi.metaconfig.ConfigException$UnableToRetrieve$Error: class java.lang.NullPointerException
 Jibri 2023-01-20 11:24:31.215 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.single-use-mode' from source 'config' as type kotlin.Boolean
 Jibri 2023-01-20 11:24:31.216 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.single-use-mode' from source 'config' as type kotlin.Boolean
 Jibri 2023-01-20 11:24:31.219 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: value found via ConfigSourceSupplier: key: 'jibri.single-use-mode', type: 'kotlin.Boolean', source: 'config'
 Jibri 2023-01-20 11:24:31.219 INFO: [60] JibriStatusManager$special$$inlined$observable$1.afterChange#72: Busy status has changed: BUSY -> IDLE
 Jibri 2023-01-20 11:24:31.221 FINE: [60] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
 Jibri 2023-01-20 11:24:31.221 INFO: [60] XmppApi.updatePresence#209: Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
 Jibri 2023-01-20 11:24:31.221 FINE: [60] MucClientManager.setPresenceExtension#160: Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@1fba4f8e
 Jibri 2023-01-20 11:24:31.222 FINE: [60] MucClientManager.saveExtension#185: Replacing presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@7055ac36
 Jibri 2023-01-20 11:24:31.236 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
 Jibri 2023-01-20 11:24:31.237 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
 Jibri 2023-01-20 11:24:32.161 FINE: [20] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status

from the user perspective its looks like:

recording
we are receving msg like: preparing for recording
waiting about 30s
recording fail / its busy

thanks for help in advance

saghul · January 20, 2023, 12:16pm

There is a certificate error on your site. Try to open it with a browser and you’ll see.

pawelkuzma · January 20, 2023, 12:29pm

Screenshot_34
This site is behind a VPN, being connected I can see the correct certificate

video - is a jitsi subdomain

when I want to request from docker container to https://webagent-dev11.alfavox.dev via curl I receiving an html page with some js script - this js looks like jitsi config file

pawelkuzma · January 20, 2023, 12:57pm

all my ffmpeg.number.logs are empty

pawelkuzma · January 20, 2023, 1:17pm

local recording is working,
recording via service dosent work

emrah · January 20, 2023, 1:20pm

pawelkuzma:

SEVERE: [60] CallPage.visit#65: Timed out waiting for call page to load
INFO: [60] [session_id=waowmhcrfzdbqdyg] JibriSelenium.onSeleniumStateChange#216: Transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
INFO: [60] [session_id=waowmhcrfzdbqdyg] StatefulJibriService.onServiceStateChange#39: File recording service transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
 Jibri 2023-01-20 11:24:30.168 INFO: [60] XmppApi$createServiceStatusHandler$1.invoke#317: Current service had an error Error: FailedToJoinCall SESSION Failed to join the call, sending error iq <iq xmlns='jabber:client' to='jibribrewery@internal-muc.meet.jitsi/focus' id='83FLL-15' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq>

jibri cannot connect to the meeting. Probably you have a problem in your network or internal DNS etc.

pawelkuzma · January 23, 2023, 11:34am

What I did:

I created new cert for new dev adress for testing purpose
I change only the public url
now we have double sure that current nginx for adres https://webagent-dev11.alfavox.dev/video doesn’t affect us

PUBLIC_URL=webagent-video-dev11.alfavox.alfavox.dev:8443

now the step

Jibri 2023-01-23 11:11:08.536 INFO: [50] XmppApi.handleStartJibriIq#281: Sending 'pending' response to start IQ
Jibri 2023-01-23 11:11:08.549 INFO: [63] AbstractPageObject.visit#32: Visiting url webagent-video-dev11.alfavox.alfavox.dev:8443/video
Jibri 2023-01-23 11:11:08.597 FINE: [47] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-01-23 11:11:08.598 FINE: [47] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-01-23 11:11:08.769 INFO: [63] AbstractPageObject.visit#38: Waited 206.873176ms for driver to load page
Jibri 2023-01-23 11:11:08.894 SEVERE: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.joinCall$lambda-3#327: An error occurred while joining the call
org.openqa.selenium.WebDriverException: <unknown>: Failed to read the 'localStorage' property from 'Window': Storage is disabled inside 'data:' URLs.
  (Session info: chrome=106.0.5249.61)
  (Driver info: chromedriver=106.0.5249.61 (511755355844955cd3e264779baf0dd38212a4d0-refs/branch-heads/5249@{#569}),platform=Linux 5.4.17-2102.203.6.el8uek.x86_64 x86_64) (WARNING: The server did not provide any stacktrace information)
Command duration or timeout: 0 milliseconds
Build info: version: 'unknown', revision: 'unknown', time: 'unknown'
System info: host: '67e5aba28296', ip: '15.12.1.6', os.name: 'Linux', os.arch: 'amd64', os.version: '5.4.17-2102.203.6.el8uek.x86_64', java.version: '11.0.16'
Driver info: org.openqa.selenium.chrome.ChromeDriver
Capabilities {acceptInsecureCerts: false, acceptSslCerts: false, browserConnectionEnabled: false, browserName: chrome, chrome: {chromedriverVersion: 106.0.5249.61 (511755355844..., userDataDir: /tmp/.com.google.Chrome.B3GAAj}, cssSelectorsEnabled: true, databaseEnabled: false, goog:chromeOptions: {debuggerAddress: localhost:37693}, handlesAlerts: true, hasTouchScreen: false, javascriptEnabled: true, locationContextEnabled: true, mobileEmulationEnabled: false, nativeEvents: true, networkConnectionEnabled: false, pageLoadStrategy: normal, platform: LINUX, platformName: LINUX, proxy: Proxy(), rotatable: false, setWindowRect: true, strictFileInteractability: false, takesHeapSnapshot: true, takesScreenshot: true, timeouts: {implicit: 0, pageLoad: 300000, script: 30000}, unexpectedAlertBehaviour: ignore, unhandledPromptBehavior: ignore, version: 106.0.5249.61, webStorageEnabled: true, webauthn:extension:credBlob: true, webauthn:extension:largeBlob: true, webauthn:virtualAuthenticators: true}
Session ID: 8813b48df8830d24c9c2de10081c5d19
        at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
        at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
        at java.base/jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
        at java.base/java.lang.reflect.Constructor.newInstance(Constructor.java:490)
        at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:214)
        at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:166)
        at org.openqa.selenium.remote.http.JsonHttpResponseCodec.reconstructValue(JsonHttpResponseCodec.java:40)
        at org.openqa.selenium.remote.http.AbstractHttpResponseCodec.decode(AbstractHttpResponseCodec.java:80)
        at org.openqa.selenium.remote.http.AbstractHttpResponseCodec.decode(AbstractHttpResponseCodec.java:44)
        at org.openqa.selenium.remote.HttpCommandExecutor.execute(HttpCommandExecutor.java:158)
        at org.openqa.selenium.remote.service.DriverCommandExecutor.execute(DriverCommandExecutor.java:83)
        at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:543)
        at org.openqa.selenium.remote.RemoteWebDriver.executeScript(RemoteWebDriver.java:480)
        at org.jitsi.jibri.selenium.JibriSelenium.setLocalStorageValues(JibriSelenium.kt:211)
        at org.jitsi.jibri.selenium.JibriSelenium.joinCall$lambda-3(JibriSelenium.kt:317)
        at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
        at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
        at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
        at java.base/java.lang.Thread.run(Thread.java:829)
Jibri 2023-01-23 11:11:09.064 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.onSeleniumStateChange#216: Transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
Jibri 2023-01-23 11:11:09.091 INFO: [63] [session_id=usfvwdizlnfsbqqw] StatefulJibriService.onServiceStateChange#39: File recording service transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
Jibri 2023-01-23 11:11:09.096 INFO: [63] XmppApi$createServiceStatusHandler$1.invoke#317: Current service had an error Error: FailedToJoinCall SESSION Failed to join the call, sending error iq <iq xmlns='jabber:client' to='jibribrewery@internal-muc.meet.jitsi/focus' id='ZQ8MC-13' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq>
Jibri 2023-01-23 11:11:09.100 FINE: [63] JibriStatsDClient.incrementCounter#38: Incrementing statsd counter: stop:recording
Jibri 2023-01-23 11:11:09.107 INFO: [63] JibriManager.stopService#260: Stopping the current service
Jibri 2023-01-23 11:11:09.108 INFO: [63] [session_id=usfvwdizlnfsbqqw] FileRecordingJibriService.stop#182: Stopping capturer
Jibri 2023-01-23 11:11:09.117 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSubprocess.stop#75: Stopping ffmpeg process
Jibri 2023-01-23 11:11:09.118 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSubprocess.stop#89: ffmpeg exited with value null
Jibri 2023-01-23 11:11:09.118 INFO: [63] [session_id=usfvwdizlnfsbqqw] FileRecordingJibriService.stop#184: Quitting selenium
Jibri 2023-01-23 11:11:09.119 INFO: [63] [session_id=usfvwdizlnfsbqqw] FileRecordingJibriService.stop#191: No media was recorded, deleting directory and skipping metadata file & finalize
Jibri 2023-01-23 11:11:09.119 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#338: Leaving call and quitting browser
Jibri 2023-01-23 11:11:09.120 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#341: Recurring call status checks cancelled
Jibri 2023-01-23 11:11:09.193 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#347: Got 0 log entries for type browser
Jibri 2023-01-23 11:11:09.250 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#347: Got 82 log entries for type driver
Jibri 2023-01-23 11:11:09.330 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#347: Got 0 log entries for type client
Jibri 2023-01-23 11:11:09.330 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#356: Leaving web call
Jibri 2023-01-23 11:11:09.500 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#363: Quitting chrome driver
Jibri 2023-01-23 11:11:09.731 INFO: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.leaveCallAndQuitBrowser#365: Chrome driver quit
Jibri 2023-01-23 11:11:09.733 FINE: [63] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: checking for value via suppliers:
  LambdaSupplier: 'JibriConfig::singleUseMode'
  ConfigSourceSupplier: key: 'jibri.single-use-mode', type: 'kotlin.Boolean', source: 'config'
Jibri 2023-01-23 11:11:09.733 FINE: [63] MainKt$setupMetaconfigLogger$1.debug#232: LambdaSupplier: Trying to retrieve value via JibriConfig::singleUseMode
Jibri 2023-01-23 11:11:09.734 FINE: [63] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: failed to find value via LambdaSupplier: 'JibriConfig::singleUseMode': org.jitsi.metaconfig.ConfigException$UnableToRetrieve$Error: class java.lang.NullPointerException
Jibri 2023-01-23 11:11:09.734 FINE: [63] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.single-use-mode' from source 'config' as type kotlin.Boolean
Jibri 2023-01-23 11:11:09.735 FINE: [63] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.single-use-mode' from source 'config' as type kotlin.Boolean
Jibri 2023-01-23 11:11:09.738 FINE: [63] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: value found via ConfigSourceSupplier: key: 'jibri.single-use-mode', type: 'kotlin.Boolean', source: 'config'
Jibri 2023-01-23 11:11:09.739 INFO: [63] JibriStatusManager$special$$inlined$observable$1.afterChange#72: Busy status has changed: BUSY -> IDLE
Jibri 2023-01-23 11:11:09.739 FINE: [63] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
Jibri 2023-01-23 11:11:09.739 INFO: [63] XmppApi.updatePresence#209: Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
Jibri 2023-01-23 11:11:09.740 FINE: [63] MucClientManager.setPresenceExtension#160: Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@68b3393
Jibri 2023-01-23 11:11:09.740 FINE: [63] MucClientManager.saveExtension#185: Replacing presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@7c3f208a
Jibri 2023-01-23 11:11:09.754 FINE: [47] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-01-23 11:11:09.755 FINE: [47] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-01-23 11:12:00.341 FINE: [20] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
Jibri 2023-01-23 11:13:00.342 FINE: [20] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status

right now looks like we are go throught step:
ibri 2023-01-23 11:11:08.549 INFO: [63] AbstractPageObject.visit#32: Visiting url webagent-video-dev11.alfavox.alfavox.dev:8443/video

emrah · January 23, 2023, 12:49pm

Changing the port affects many things. You should fix your setup to work on TCP/443

saghul · January 23, 2023, 1:21pm

That’s not a valid URL, please also specify the scheme: https://

pawelkuzma · January 23, 2023, 1:46pm

i have some thoughts:

it seems that a mistake such as:

Jibri 2023-01-23 11:11:08.549 INFO: [63] AbstractPageObject.visit#32: Visiting url webagent-video-dev11.alfavox.alfavox.dev:8443/video
Jibri 2023-01-23 11:11:08.597 FINE: [47] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-01-23 11:11:08.598 FINE: [47] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-01-23 11:11:08.769 INFO: [63] AbstractPageObject.visit#38: Waited 206.873176ms for driver to load page
Jibri 2023-01-23 11:11:08.894 SEVERE: [63] [session_id=usfvwdizlnfsbqqw] JibriSelenium.joinCall$lambda-3#327: An error occurred while joining the call
org.openqa.selenium.WebDriverException: <unknown>: Failed to read the 'localStorage' property from 'Window': Storage is disabled inside 'data:' URLs.
  (Session info: chrome=106.0.5249.61)
  (Driver info: chromedriver=106.0.5249.61 (511755355844955cd3e264779baf0dd38212a4d0-refs/branch-heads/5249@{#569}),platform=Linux 5.4.17-2102.203.6.el8uek.x86_64 x86_64) (WARNING: The server did not provide any stacktrace information)
Command duration or timeout: 0 milliseconds
Build info: version: 'unknown', revision: 'unknown', time: 'unknown'
System info: host: '67e5aba28296', ip: '15.12.1.6', os.name: 'Linux', os.arch: 'amd64', os.version: '5.4.17-2102.203.6.el8uek.x86_64', java.version: '11.0.16'
Driver info: org.openqa.selenium.chrome.ChromeDriver
Capabilities {acceptInsecureCerts: false, acceptSslCerts: false, browserConnectionEnabled: false, browserName: chrome, chrome: {chromedriverVersion: 106.0.5249.61 (511755355844..., userDataDir: /tmp/.com.google.Chrome.B3GAAj}, cssSelectorsEnabled: true, databaseEnabled: false, goog:chromeOptions: {debuggerAddress: localhost:37693}, handlesAlerts: true, hasTouchScreen: false, javascriptEnabled: true, locationContextEnabled: true, mobileEmulationEnabled: false, nativeEvents: true, networkConnectionEnabled: false, pageLoadStrategy: normal, platform: LINUX, platformName: LINUX, proxy: Proxy(), rotatable: false, setWindowRect: true, strictFileInteractability: false, takesHeapSnapshot: true, takesScreenshot: true, timeouts: {implicit: 0, pageLoad: 300000, script: 30000}, unexpectedAlertBehaviour: ignore, unhandledPromptBehavior: ignore, version: 106.0.5249.61, webStorageEnabled: true, webauthn:extension:credBlob: true, webauthn:extension:largeBlob: true, webauthn:virtualAuthenticators: true}
Session ID: 8813b48df8830d24c9c2de10081c5d19

Im dobule sure that this error msg is misleading I noticed that at above situation I was unable to connect via a curl to webagent-video-dev11.alfavox.alfavox.dev:8443/video

I change url as saghul suggested, right now is:

PUBLIC_URL=https://webagent-video-dev11.alfavox.alfavox.dev:8443

and now I have the same error as initial:

[root@dev_11-awa docker-jitsi-meet-stable-8138-1]# docker logs docker-jitsi-meet-stable-8138-1_jibri_1 --tail 150 --since 2023-01-22T12:00:00Z
Jibri 2023-01-23 13:32:45.261 INFO: [33] [hostname=xmpp.meet.jitsi id=xmpp.meet.jitsi] MucClient.lambda$getConnectAndLoginCallable$9#637: Logging in.
Jibri 2023-01-23 13:32:45.505 INFO: [33] [hostname=xmpp.meet.jitsi id=xmpp.meet.jitsi] MucClient$2.authenticated#318: Authenticated, b=false
Jibri 2023-01-23 13:32:45.511 FINE: [33] [hostname=xmpp.meet.jitsi id=xmpp.meet.jitsi] MucClient$MucWrapper.resetLastPresenceSent#836: Resetting lastPresenceSent
Jibri 2023-01-23 13:32:45.565 INFO: [33] [hostname=xmpp.meet.jitsi id=xmpp.meet.jitsi] MucClient$MucWrapper.join#761: Joined MUC: jibribrewery@internal-muc.meet.jitsi
Jibri 2023-01-23 13:32:45.599 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-01-23 13:32:45.600 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-01-23 13:33:31.413 FINE: [47] [hostname=xmpp.meet.jitsi id=xmpp.meet.jitsi] MucClient$3.handleIQRequest#504: Received an IQ with type set: IQ Stanza (jibri http://jitsi.org/protocol/jibri) [to=jibri@auth.meet.jitsi/1VAGwRsASh06,from=jibribrewery@internal-muc.meet.jitsi/focus,id=amlicmlAYXV0aC5tZWV0LmppdHNpLzFWQUd3UnNBU2gwNgBZQ1Q2SS0zMACacRZPOXNkIg==,type=set,]
Jibri 2023-01-23 13:33:31.417 INFO: [47] XmppApi.handleJibriIq#236: Received JibriIq <iq xmlns='jabber:client' to='jibri@auth.meet.jitsi/1VAGwRsASh06' from='jibribrewery@internal-muc.meet.jitsi/focus' id='amlicmlAYXV0aC5tZWV0LmppdHNpLzFWQUd3UnNBU2gwNgBZQ1Q2SS0zMACacRZPOXNkIg==' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' action='start' recording_mode='file' room='85791056-c384-4473-a63e-9a62a3a43f2d@muc.video.meet.jitsi' session_id='agngobpabsvdteay' app_data='{"file_recording_metadata":{"share":true}}'/></iq> from environment [MucClient id=xmpp.meet.jitsi hostname=xmpp.meet.jitsi]
Jibri 2023-01-23 13:33:31.419 INFO: [47] XmppApi.handleStartJibriIq#268: Received start request, starting service
Jibri 2023-01-23 13:33:31.706 INFO: [47] XmppApi.handleStartService#379: Parsed call url info: CallUrlInfo(baseUrl=https://webagent-video-dev11.alfavox.alfavox.dev:8443/video, callName=85791056-c384-4473-a63e-9a62a3a43f2d, urlParams=[])
Jibri 2023-01-23 13:33:31.769 INFO: [47] JibriManager.startFileRecording#138: Starting a file recording with params: FileRecordingRequestParams(callParams=CallParams(callUrlInfo=CallUrlInfo(baseUrl=https://webagent-video-dev11.alfavox.alfavox.dev:8443/video, callName=85791056-c384-4473-a63e-9a62a3a43f2d, urlParams=[]), email='', passcode=null, callStatsUsernameOverride=, displayName=), sessionId=agngobpabsvdteay, callLoginParams=XmppCredentials(domain=recorder.meet.jitsi, port=null, username=recorder, password=*****))
Jibri 2023-01-23 13:33:31.834 FINE: [47] [session_id=agngobpabsvdteay] FfmpegCapturer.<init>#92: Detected os as OS: LINUX
Jibri 2023-01-23 13:33:31.849 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.call-status-checks.default-call-empty-timeout' from source 'config' as type java.time.Duration
Jibri 2023-01-23 13:33:31.855 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.call-status-checks.default-call-empty-timeout' from source 'config' as type java.time.Duration
Jibri 2023-01-23 13:33:31.876 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.chrome.flags' from source 'config' as type kotlin.collections.List<kotlin.String>
Jibri 2023-01-23 13:33:31.877 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.chrome.flags' from source 'config' as type kotlin.collections.List<kotlin.String>
Starting ChromeDriver 106.0.5249.61 (511755355844955cd3e264779baf0dd38212a4d0-refs/branch-heads/5249@{#569}) on port 16007
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully.
Jibri 2023-01-23 13:33:34.902 INFO: [47] org.openqa.selenium.remote.ProtocolHandshake.createSession: Detected dialect: OSS
Jibri 2023-01-23 13:33:34.945 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: checking for value via suppliers:
  LambdaSupplier: 'JibriConfig::recordingDirectory'
  ConfigSourceSupplier: key: 'jibri.recording.recordings-directory', type: 'kotlin.String', source: 'config'
Jibri 2023-01-23 13:33:34.945 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: LambdaSupplier: Trying to retrieve value via JibriConfig::recordingDirectory
Jibri 2023-01-23 13:33:34.945 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: failed to find value via LambdaSupplier: 'JibriConfig::recordingDirectory': org.jitsi.metaconfig.ConfigException$UnableToRetrieve$Error: class java.lang.NullPointerException
Jibri 2023-01-23 13:33:34.946 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.recording.recordings-directory' from source 'config' as type kotlin.String
Jibri 2023-01-23 13:33:34.949 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.recording.recordings-directory' from source 'config' as type kotlin.String
Jibri 2023-01-23 13:33:34.954 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: value found via ConfigSourceSupplier: key: 'jibri.recording.recordings-directory', type: 'kotlin.String', source: 'config'
Jibri 2023-01-23 13:33:34.955 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: checking for value via suppliers:
  LambdaSupplier: 'JibriConfig::finalizeRecordingScriptPath'
  ConfigSourceSupplier: key: 'jibri.recording.finalize-script', type: 'kotlin.String', source: 'config'
Jibri 2023-01-23 13:33:34.956 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: LambdaSupplier: Trying to retrieve value via JibriConfig::finalizeRecordingScriptPath
Jibri 2023-01-23 13:33:34.957 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: failed to find value via LambdaSupplier: 'JibriConfig::finalizeRecordingScriptPath': org.jitsi.metaconfig.ConfigException$UnableToRetrieve$Error: class java.lang.NullPointerException
Jibri 2023-01-23 13:33:34.958 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.recording.finalize-script' from source 'config' as type kotlin.String
Jibri 2023-01-23 13:33:34.959 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.recording.finalize-script' from source 'config' as type kotlin.String
Jibri 2023-01-23 13:33:34.959 FINE: [47] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: value found via ConfigSourceSupplier: key: 'jibri.recording.finalize-script', type: 'kotlin.String', source: 'config'
Jibri 2023-01-23 13:33:34.960 INFO: [47] [session_id=agngobpabsvdteay] FileRecordingJibriService.<init>#134: Writing recording to /config/recordings/agngobpabsvdteay, finalize script path /config/finalize.sh
Jibri 2023-01-23 13:33:35.058 FINE: [47] JibriStatsDClient.incrementCounter#38: Incrementing statsd counter: start:recording
Jibri 2023-01-23 13:33:35.062 INFO: [47] JibriStatusManager$special$$inlined$observable$1.afterChange#72: Busy status has changed: IDLE -> BUSY
Jibri 2023-01-23 13:33:35.064 FINE: [47] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
Jibri 2023-01-23 13:33:35.065 INFO: [47] XmppApi.updatePresence#209: Jibri reports its status is now JibriStatus(busyStatus=BUSY, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
Jibri 2023-01-23 13:33:35.066 FINE: [47] MucClientManager.setPresenceExtension#160: Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@187d51d5
Jibri 2023-01-23 13:33:35.067 FINE: [47] MucClientManager.saveExtension#185: Replacing presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@2b03d52f
Jibri 2023-01-23 13:33:35.073 INFO: [47] XmppApi.handleStartJibriIq#281: Sending 'pending' response to start IQ
Jibri 2023-01-23 13:33:35.081 INFO: [60] AbstractPageObject.visit#32: Visiting url https://webagent-video-dev11.alfavox.alfavox.dev:8443/video
Jibri 2023-01-23 13:33:35.087 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-01-23 13:33:35.087 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-01-23 13:33:38.757 INFO: [60] AbstractPageObject.visit#38: Waited 3.652477987s for driver to load page
Jibri 2023-01-23 13:33:38.879 INFO: [60] AbstractPageObject.visit#32: Visiting url https://webagent-video-dev11.alfavox.alfavox.dev:8443/video/85791056-c384-4473-a63e-9a62a3a43f2d#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.prejoinConfig.enabled=false&config.requireDisplayName=false
Jibri 2023-01-23 13:33:40.202 INFO: [60] AbstractPageObject.visit#38: Waited 1.320832862s for driver to load page
Jibri 2023-01-23 13:33:40.554 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: Cannot read properties of undefined (reading 'isJoined')
Jibri 2023-01-23 13:33:41.663 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:42.203 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:42.715 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:43.244 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:43.330 FINE: [20] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
Jibri 2023-01-23 13:33:43.765 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:44.279 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:44.793 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:45.315 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:45.849 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:46.361 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:46.874 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:47.384 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:47.896 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:48.410 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:48.922 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:49.439 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:49.964 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:50.476 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:50.997 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:51.514 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:52.031 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:52.546 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:53.056 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:53.568 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:54.080 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:54.600 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:55.119 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:55.632 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:56.146 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:56.667 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:57.189 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:57.706 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:58.223 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:58.737 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:59.268 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:33:59.786 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:00.309 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:00.845 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:01.369 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:01.906 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:02.455 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:03.016 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:03.582 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:04.284 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:04.829 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:05.364 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:05.916 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:06.451 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:06.979 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:07.498 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:08.015 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:08.527 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:09.047 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:09.563 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:10.074 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:10.593 FINE: [60] CallPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined
Jibri 2023-01-23 13:34:10.611 SEVERE: [60] CallPage.visit#65: Timed out waiting for call page to load
Jibri 2023-01-23 13:34:10.642 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.onSeleniumStateChange#216: Transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
Jibri 2023-01-23 13:34:10.644 INFO: [60] [session_id=agngobpabsvdteay] StatefulJibriService.onServiceStateChange#39: File recording service transitioning from state Starting up to Error: FailedToJoinCall SESSION Failed to join the call
Jibri 2023-01-23 13:34:10.646 INFO: [60] XmppApi$createServiceStatusHandler$1.invoke#317: Current service had an error Error: FailedToJoinCall SESSION Failed to join the call, sending error iq <iq xmlns='jabber:client' to='jibribrewery@internal-muc.meet.jitsi/focus' id='6RNY9-10' type='set'><jibri xmlns='http://jitsi.org/protocol/jibri' status='off' failure_reason='error' should_retry='true'/></iq>
Jibri 2023-01-23 13:34:10.648 FINE: [60] JibriStatsDClient.incrementCounter#38: Incrementing statsd counter: stop:recording
Jibri 2023-01-23 13:34:10.649 INFO: [60] JibriManager.stopService#260: Stopping the current service
Jibri 2023-01-23 13:34:10.649 INFO: [60] [session_id=agngobpabsvdteay] FileRecordingJibriService.stop#182: Stopping capturer
Jibri 2023-01-23 13:34:10.650 INFO: [60] [session_id=agngobpabsvdteay] JibriSubprocess.stop#75: Stopping ffmpeg process
Jibri 2023-01-23 13:34:10.651 INFO: [60] [session_id=agngobpabsvdteay] JibriSubprocess.stop#89: ffmpeg exited with value null
Jibri 2023-01-23 13:34:10.651 INFO: [60] [session_id=agngobpabsvdteay] FileRecordingJibriService.stop#184: Quitting selenium
Jibri 2023-01-23 13:34:10.652 INFO: [60] [session_id=agngobpabsvdteay] FileRecordingJibriService.stop#191: No media was recorded, deleting directory and skipping metadata file & finalize
Jibri 2023-01-23 13:34:10.652 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#338: Leaving call and quitting browser
Jibri 2023-01-23 13:34:10.653 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#341: Recurring call status checks cancelled
Jibri 2023-01-23 13:34:10.702 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#347: Got 24 log entries for type browser
Jibri 2023-01-23 13:34:10.877 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#347: Got 1005 log entries for type driver
Jibri 2023-01-23 13:34:11.082 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#347: Got 0 log entries for type client
Jibri 2023-01-23 13:34:11.082 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#356: Leaving web call
Jibri 2023-01-23 13:34:11.142 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#363: Quitting chrome driver
Jibri 2023-01-23 13:34:11.346 INFO: [60] [session_id=agngobpabsvdteay] JibriSelenium.leaveCallAndQuitBrowser#365: Chrome driver quit
Jibri 2023-01-23 13:34:11.348 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: checking for value via suppliers:
  LambdaSupplier: 'JibriConfig::singleUseMode'
  ConfigSourceSupplier: key: 'jibri.single-use-mode', type: 'kotlin.Boolean', source: 'config'
Jibri 2023-01-23 13:34:11.349 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: LambdaSupplier: Trying to retrieve value via JibriConfig::singleUseMode
Jibri 2023-01-23 13:34:11.349 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: failed to find value via LambdaSupplier: 'JibriConfig::singleUseMode': org.jitsi.metaconfig.ConfigException$UnableToRetrieve$Error: class java.lang.NullPointerException
Jibri 2023-01-23 13:34:11.350 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Trying to retrieve key 'jibri.single-use-mode' from source 'config' as type kotlin.Boolean
Jibri 2023-01-23 13:34:11.351 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: ConfigSourceSupplier: Successfully retrieved key 'jibri.single-use-mode' from source 'config' as type kotlin.Boolean
Jibri 2023-01-23 13:34:11.352 FINE: [60] MainKt$setupMetaconfigLogger$1.debug#232: FallbackSupplier: value found via ConfigSourceSupplier: key: 'jibri.single-use-mode', type: 'kotlin.Boolean', source: 'config'
Jibri 2023-01-23 13:34:11.352 INFO: [60] JibriStatusManager$special$$inlined$observable$1.afterChange#72: Busy status has changed: BUSY -> IDLE
Jibri 2023-01-23 13:34:11.353 FINE: [60] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status
Jibri 2023-01-23 13:34:11.353 INFO: [60] XmppApi.updatePresence#209: Jibri reports its status is now JibriStatus(busyStatus=IDLE, health=OverallHealth(healthStatus=HEALTHY, details={})), publishing presence to connections
Jibri 2023-01-23 13:34:11.354 FINE: [60] MucClientManager.setPresenceExtension#160: Setting a presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@626bce41
Jibri 2023-01-23 13:34:11.354 FINE: [60] MucClientManager.saveExtension#185: Replacing presence extension: org.jitsi.xmpp.extensions.jibri.JibriStatusPacketExt@187d51d5
Jibri 2023-01-23 13:34:11.360 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element busy-status from namespace http://jitsi.org/protocol/jibri
Jibri 2023-01-23 13:34:11.360 FINE: [46] org.jitsi.xmpp.extensions.DefaultPacketExtensionProvider.parse: Could not add a provider for element health-status from namespace http://jitsi.org/protocol/health
Jibri 2023-01-23 13:34:43.330 FINE: [20] WebhookClient$updateStatus$1.invokeSuspend#109: Updating 0 subscribers of status

so its look like the chrome selenium error is a step before than allPage.visit$lambda-2$lambda-1#58: Not joined yet: APP is not defined

Changing the port affects many things. You should fix your setup to work on TCP/443

I’m using a docker version and when I type docker ps -a I can see:

eceff76ab221   jitsi/jibri:stable-8138-1                                    "/init"                  12 minutes ago   Up 12 minutes                                                                                                                                                                                          docker-jitsi-meet-stable-8138-1_jibri_1
d2e0f376b4d2   jitsi/jvb:stable-8138-1                                      "/init"                  12 minutes ago   Up 12 minutes   127.0.0.1:8080->8080/tcp, 0.0.0.0:10000->10000/udp, :::10000->10000/udp                                                                                                                docker-jitsi-meet-stable-8138-1_jvb_1
78af33c746f7   jitsi/jicofo:stable-8138-1                                   "/init"                  12 minutes ago   Up 12 minutes                                                                                                                                                                                          docker-jitsi-meet-stable-8138-1_jicofo_1
a87e4edd3f4d   jitsi/web:stable-8138-1                                      "/init"                  12 minutes ago   Up 12 minutes   0.0.0.0:8000->80/tcp, :::8000->80/tcp, 0.0.0.0:8443->443/tcp, :::8443->443/tcp                                                                                                         docker-jitsi-meet-stable-8138-1_web_1
06280a64b492   jitsi/prosody:stable-8138-1                                  "/init"                  12 minutes ago   Up 12 minutes   5222/tcp, 5280/tcp, 5347/tcp                                                                                                                                                           docker-jitsi-meet-stable-8138-1_prosody_1

as I understand the 8443 is for outside purpose and the 443 inside, in such default setting of ports while using jibri are actually wrong?

EDIT:
I forgot to mention that after fixing

PUBLIC_URL=https://webagent-video-dev11.alfavox.alfavox.dev:8443

from docker container via curl I am able to send request to above url

saghul · January 23, 2023, 3:36pm

What is the actual URL where Jitsi Meet is: https://webagent-video-dev11.alfavox.alfavox.dev:8443 or https://webagent-video-dev11.alfavox.alfavox.dev:8443/video ?

Also, while we try to, support for non-standard ports is not great, i’d strongly suggest you use port 443.

pawelkuzma · January 23, 2023, 3:40pm

PUBLIC_URL=https://webagent-video-dev11.alfavox.alfavox.dev:8443

video is a subdomain (in the meaning of jitsi - aka group of rooms)
ENABLE_SUBDOMAINS=1

Im using default docker meet jitsi ports I dont change anything

I will try to use 443 as external also

pawelkuzma · January 24, 2023, 11:38am

So in general, yes - port 443 is a solution
at docker right now I have:

[root@dev14-awa docker-jitsi-meet-stable-8138-1]# docker ps -a
CONTAINER ID   IMAGE                         COMMAND   CREATED             STATUS             PORTS                                                                      NAMES
8b27e7210640   jitsi/web:stable-8138-1       "/init"   About an hour ago   Up About an hour   0.0.0.0:80->80/tcp, :::80->80/tcp, 0.0.0.0:443->443/tcp, :::443->443/tcp   docker-jitsi-meet-stable-8138-1_web_1
2887e5b87456   jitsi/jibri:stable-8138-1     "/init"   About an hour ago   Up About an hour                                                                              docker-jitsi-meet-stable-8138-1_jibri_1
2af3906a65c3   jitsi/jvb:stable-8138-1       "/init"   About an hour ago   Up About an hour   127.0.0.1:8080->8080/tcp, 0.0.0.0:10000->10000/udp, :::10000->10000/udp    docker-jitsi-meet-stable-8138-1_jvb_1
22c0700f74ad   jitsi/jicofo:stable-8138-1    "/init"   About an hour ago   Up About an hour                                                                              docker-jitsi-meet-stable-8138-1_jicofo_1
a9395c8cc25e   jitsi/prosody:stable-8138-1   "/init"   About an hour ago   Up About an hour   5222/tcp, 5280/tcp, 5347/tcp                                               docker-jitsi-meet-stable-8138-1_prosody_1

instead of:

0.0.0.0:8000->80/tcp, :::8000->80/tcp, 0.0.0.0:8443->443/tcp, :::8443->443/tcp

.env

HTTP_PORT=80
HTTPS_PORT=443
PUBLIC_URL=https://{URL}
XMPP_DOMAIN={URL}
XMPP_SERVER=xmpp.{URL}
XMPP_BOSH_URL_BASE=http://xmpp.{URL}:5280
XMPP_AUTH_DOMAIN=auth.{URL}
XMPP_MUC_DOMAIN=muc.{URL}
XMPP_INTERNAL_MUC_DOMAIN=internal-muc.{URL}
ENABLE_RECORDING=1
ENABLE_SERVICE_RECORDING=true
XMPP_RECORDER_DOMAIN=recorder.{URL}
JIBRI_RECORDER_USER=recorder
JIBRI_RECORDING_DIR=/config/recordings
JIBRI_FINALIZE_RECORDING_SCRIPT_PATH=/config/finalize.sh
JIBRI_XMPP_USER=jibri
JIBRI_BREWERY_MUC=jibribrewery
JIBRI_PENDING_TIMEOUT=90
# default "1280x720"
# Modes "32768x32768" "32768x16384" "16384x8192" "8192x4096" "5120x3200" "3840x2880" "3840x2560" "3840x2048" "2048x2048" "2560x1600" "1920x1440" "1920x1200" "1920x1080" "1600x1200" "1680x1050" "1600x900" "1400x1050" "1440x900" "1280x1024" "1366x768" "1280x800" "1024x768" "1024x600" "800x600" "320x200"
JIBRI_RECORDING_RESOLUTION=320x200

docker-compose.yml

        ports:
            - '${HTTP_PORT}:${HTTP_PORT}'
            - '${HTTPS_PORT}:${HTTPS_PORT}'

jibri.yml

        volumes:
            - ${CONFIG}/jibri/hosts:/etc/hosts:Z

with {IP} {URL} /not docker env var, your real data, where {URL} aka PUBLIC_URL

you need valid cert

This is not my case right now but, jitsi will be at separated vm in production, but for the future reasons, is there any tutorial - what and where to find all places in code where should I change default 443 to different one?

emrah · January 24, 2023, 11:49am

For non-Dockerized setup