Timeout Waiting for Download Bitrate

[subsub]

After check audio succeed, yet another problem appears. This time it reaches timeout when waiting for download bitrate although I'm in a relatively fast internet connection. What do I do?

Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,438 INFO     Client options for host: {'roompass': '', 'nick': 'jibri-19307129379567112', 'password': 'mypassword', 'xmpp_domain': 'my.domain.com', 'selenium_xmpp_login': 'recorder@recorder.my.domain.com', 'url': 'https://my.domain.com/%SUBDOMAIN%%ROOM%', 'jid': 'jibri@auth.my.domain.com', 'mucserver_prefix': 'conference.', 'room': 'TheBrewery@conference.my.domain.com', 'boshdomain': 'recorder.my.domain.com', 'selenium_xmpp_prefix': 'recorder.', 'recording_directory': './recordings', 'usage_timeout': 0, 'google_account_password': 'mygoogleaccountpassword', 'jid_username': 'jibri', 'roomname': 'TheBrewery', 'jidserver_prefix': 'auth.', 'boshdomain_prefix': 'recorder.', 'brewery_prefix': 'conference.', 'selenium_xmpp_username': 'recorder', 'google_account': 'mygoogleaccount@gmail.com', 'selenium_xmpp_password': 'mypassword'}
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,439 INFO     Setting selenium google account from client options: mygoogleaccount@gmail.com
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,439 INFO     Setting selenium google account password from client options
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,440 INFO     Setting xmpp login from client options: recorder@recorder.my.domain.com
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,440 INFO     Setting xmpp password from client options
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,441 INFO     Setting boshdomain from client options recorder.my.domain.com
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,441 INFO     Setting xmpp_domain from client options my.domain.com
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,441 INFO     Setting mucserver_prefix from client options conference.
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,444 INFO     Setting recording_directory from client options ./recordings
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,446 INFO     Start recording callback
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,448 INFO     update_jibri_status
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,451 INFO     looping through queue for host my.domain.com
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,452 INFO     Starting jibri
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,452 INFO     Starting selenium attempt 1/3 pjsua_flag:False
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,453 INFO     overriding config.hosts.domain with boshdomain: recorder.my.domain.com
Nov 03 10:16:38 jibri app.py[10737]: 2017-11-03 10:16:38,453 INFO     starting jibri selenium, url=https://my.domain.com/test#config.iAmRecorder=true&config.externalConnectUrl=null&config.hosts.domain="recorder.my.domain.com", google_account=mygoogleaccount@gmail.com, xmpp_login=recorder@recorder.my.domain.com
Nov 03 10:16:44 jibri app.py[10737]: SIGNAL COUNT: 10986
Nov 03 10:16:44 jibri app.py[10737]: SUCCESS 0
Nov 03 10:16:44 jibri app.py[10737]: 2017-11-03 10:16:44,640 INFO     Audio levels confirmed OK.
Nov 03 10:16:44 jibri app.py[10737]: 2017-11-03 10:16:44,644 INFO     Launching URL: https://my.domain.com/test#config.iAmRecorder=true&config.externalConnectUrl=null&config.hosts.domain="recorder.my.domain.com"
Nov 03 10:16:44 jibri app.py[10737]: 2017-11-03 10:16:44,645 INFO     Logging in with google account
Nov 03 10:16:45 jibri app.py[10737]: 2017-11-03 10:16:45,889 INFO     Exception occurred logging into google: Message: no such element: Unable to locate element: {"method":"id","selector":"Email"}
Nov 03 10:16:45 jibri app.py[10737]:   (Session info: chrome=62.0.3202.75)
Nov 03 10:16:45 jibri app.py[10737]:   (Driver info: chromedriver=2.33.506092 (733a02544d189eeb751fe0d7ddca79a0ee28cce4),platform=Linux 4.4.0-98-generic x86_64)
Nov 03 10:16:45 jibri app.py[10737]: 2017-11-03 10:16:45,897 INFO     Google Login completed one way or another
Nov 03 10:16:45 jibri app.py[10737]: 2017-11-03 10:16:45,899 INFO     setting jibri identifiers: display Live Stream -  email recorder@jitsi.org
Nov 03 10:16:48 jibri app.py[10737]: 2017-11-03 10:16:48,788 INFO     setting jibri identifiers: xmpp_username_override recorder@recorder.my.domain.com
Nov 03 10:16:50 jibri app.py[10737]: 2017-11-03 10:16:50,170 INFO     starting to wait for XMPPConnected
Nov 03 10:16:50 jibri app.py[10737]: 2017-11-03 10:16:50,232 INFO     waiting +5 = 0 < 60 for XMPPConnected
Nov 03 10:16:55 jibri app.py[10737]: 2017-11-03 10:16:55,244 INFO     XMPP connected, done waiting
Nov 03 10:16:55 jibri app.py[10737]: 2017-11-03 10:16:55,248 INFO     starting to wait for DownloadBitrate
Nov 03 10:16:55 jibri app.py[10737]: 2017-11-03 10:16:55,259 INFO     waiting +5 = 0 < 60 for DownloadBitrate
Nov 03 10:17:00 jibri app.py[10737]: 2017-11-03 10:17:00,273 INFO     waiting +5 = 5 < 60 for DownloadBitrate
Nov 03 10:17:05 jibri app.py[10737]: 2017-11-03 10:17:05,282 INFO     waiting +5 = 10 < 60 for DownloadBitrate
Nov 03 10:17:10 jibri app.py[10737]: 2017-11-03 10:17:10,294 INFO     waiting +5 = 15 < 60 for DownloadBitrate
Nov 03 10:17:15 jibri app.py[10737]: 2017-11-03 10:17:15,304 INFO     waiting +5 = 20 < 60 for DownloadBitrate
Nov 03 10:17:20 jibri app.py[10737]: 2017-11-03 10:17:20,316 INFO     waiting +5 = 25 < 60 for DownloadBitrate
Nov 03 10:17:25 jibri app.py[10737]: 2017-11-03 10:17:25,326 INFO     waiting +5 = 30 < 60 for DownloadBitrate
Nov 03 10:17:30 jibri app.py[10737]: 2017-11-03 10:17:30,342 INFO     waiting +5 = 35 < 60 for DownloadBitrate
Nov 03 10:17:35 jibri app.py[10737]: 2017-11-03 10:17:35,355 INFO     waiting +5 = 40 < 60 for DownloadBitrate
Nov 03 10:17:40 jibri app.py[10737]: 2017-11-03 10:17:40,368 INFO     waiting +5 = 45 < 60 for DownloadBitrate
Nov 03 10:17:45 jibri app.py[10737]: 2017-11-03 10:17:45,379 INFO     waiting +5 = 50 < 60 for DownloadBitrate
Nov 03 10:17:50 jibri app.py[10737]: 2017-11-03 10:17:50,386 INFO     waiting +5 = 55 < 60 for DownloadBitrate
Nov 03 10:17:55 jibri app.py[10737]: 2017-11-03 10:17:55,394 INFO     Timed out waiting for download bitrate
Nov 03 10:17:55 jibri app.py[10737]: 2017-11-03 10:17:55,400 ERROR    Jibri Startup exception during attempt 1/3: unorderable types: NoneType() > int()
Nov 03 10:17:55 jibri app.py[10737]: 2017-11-03 10:17:55,401 INFO     start_jibri_selenium returned retcode=9999 during attempt 1/3
Nov 03 10:17:55 jibri app.py[10737]: 2017-11-03 10:17:55,402 INFO     Starting selenium attempt 2/3 pjsua_flag:False
Nov 03 10:17:55 jibri app.py[10737]: 2017-11-03 10:17:55,402 INFO     overriding config.hosts.domain with boshdomain: recorder.my.domain.com
Nov 03 10:17:55 jibri app.py[10737]: 2017-11-03 10:17:55,403 INFO     starting jibri selenium, url=https://my.domain.com/test#config.iAmRecorder=true&config.externalConnectUrl=null&config.hosts.domain="recorder.my.domain.com", google_account=mygoogleaccount@gmail.com, xmpp_login=recorder@recorder.my.domain.com
Nov 03 10:18:00 jibri app.py[10737]: 2017-11-03 10:18:00,560 WARNING  ERROR: failed audio check, no audio levels detected: 1
Nov 03 10:18:00 jibri app.py[10737]: 2017-11-03 10:18:00,568 WARNING  jibri detected audio issues during startup, bailing out.
Nov 03 10:18:00 jibri app.py[10737]: 2017-11-03 10:18:00,568 INFO     start_jibri_selenium returned retcode=3 during attempt 2/3
Nov 03 10:18:00 jibri app.py[10737]: 2017-11-03 10:18:00,569 INFO     Starting selenium attempt 3/3 pjsua_flag:False
Nov 03 10:18:00 jibri app.py[10737]: 2017-11-03 10:18:00,584 INFO     overriding config.hosts.domain with boshdomain: recorder.my.domain.com
Nov 03 10:18:00 jibri app.py[10737]: 2017-11-03 10:18:00,584 INFO     starting jibri selenium, url=https://my.domain.com/test#config.iAmRecorder=true&config.externalConnectUrl=null&config.hosts.domain="recorder.my.domain.com", google_account=mygoogleaccount@gmail.com, xmpp_login=recorder@recorder.my.domain.com
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,764 WARNING  ERROR: failed audio check, no audio levels detected: 1
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,769 WARNING  jibri detected audio issues during startup, bailing out.
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,770 INFO     start_jibri_selenium returned retcode=3 during attempt 3/3
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,770 INFO     start_jibri_selenium returned retcode=3
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,771 INFO     jibri_stop_callback run with status startup_selenium_error
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,771 INFO     queueing error error|startup_selenium_error for host my.domain.com
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,771 INFO     queueing job for jibri_watcher
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,772 INFO     jibri_watcher received job, now watching ffmpeg and selenium with a timeout of 0.
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,777 INFO     jibri_watcher finished loop...
Nov 03 10:18:05 jibri app.py[10737]: 2017-11-03 10:18:05,777 INFO     jibri_watcher starting up...
Nov 03 10:18:06 jibri app.py[10737]: 2017-11-03 10:18:06,289 INFO     got msg from main: error|startup_selenium_error
Nov 03 10:18:06 jibri app.py[10737]: 2017-11-03 10:18:06,295 INFO     sending status update: <iq id="feaf3f2a-55ca-493b-a138-3c89a6ffd190-D" to="thebrewery@conference.my.domain.com/focus" type="set"><jibri xmlns="http://jitsi.org/protocol/jibri" status="failed"><error xmlns="jabber:client" code="504" type="wait"><remote-server-timeout xmlns="urn:ietf:params:xml:ns:xmpp-stanzas" /><text xmlns="urn:ietf:params:xml:ns:xmpp-stanzas">Startup error: Selenium error</text><retry xmlns="http://jitsi.org/protocol/jibri" /></error></jibri></iq>
Nov 03 10:18:08 jibri app.py[10737]: 2017-11-03 10:18:08,935 INFO     update_jibri_status
Nov 03 10:18:08 jibri app.py[10737]: 2017-11-03 10:18:08,939 INFO     looping through queue for host my.domain.com
Nov 03 10:18:08 jibri app.py[10737]: 2017-11-03 10:18:08,941 INFO     queueing status stopped for host my.domain.com
Nov 03 10:18:08 jibri app.py[10737]: 2017-11-03 10:18:08,941 INFO     update_jibri_status
Nov 03 10:18:08 jibri app.py[10737]: 2017-11-03 10:18:08,944 INFO     looping through queue for host my.domain.com
Nov 03 10:18:08 jibri app.py[10737]: 2017-11-03 10:18:08,944 INFO     queueing status idle for host my.domain.com
Nov 03 10:18:08 jibri app.py[10737]: 2017-11-03 10:18:08,944 INFO     jibri_start_callback completed...
Nov 03 10:18:09 jibri app.py[10737]: 2017-11-03 10:18:09,291 INFO     got msg from main: stopped
Nov 03 10:18:09 jibri app.py[10737]: 2017-11-03 10:18:09,291 INFO     sending status update: <iq id="feaf3f2a-55ca-493b-a138-3c89a6ffd190-F" to="thebrewery@conference.my.domain.com/focus" type="set"><jibri xmlns="http://jitsi.org/protocol/jibri" status="off" /></iq>
Nov 03 10:18:39 jibri app.py[10737]: 2017-11-03 10:18:39,325 WARNING  Timed out waiting for IqWait_feaf3f2a-55ca-493b-a138-3c89a6ffd190-F
Nov 03 10:18:39 jibri app.py[10737]: 2017-11-03 10:18:39,326 ERROR    Failed to send status update: <iq id="feaf3f2a-55ca-493b-a138-3c89a6ffd190-F" to="thebrewery@conference.my.domain.com/focus" type="set"><jibri xmlns="http://jitsi.org/protocol/jibri" status="off" /></iq>
Nov 03 10:18:39 jibri app.py[10737]: 2017-11-03 10:18:39,326 INFO     got msg from main: idle
Nov 03 10:18:39 jibri app.py[10737]: 2017-11-03 10:18:39,326 INFO     sending presence: <presence to="TheBrewery@conference.my.domain.com" xml:lang="en"><jibri-status xmlns="http://jitsi.org/protocol/jibri" status="idle" /></presence>

Thank you

[Ark74]

Hi!
Could you give me a light on this.
Seems like selenium will wait 60 seconds before it times out after that it will stop killing chrome/chromedriver.
By your log, I see your chrome and chromedriver start correctly;

(Session info: chrome=62.0.3202.75)
(Driver info: chromedriver=2.33.506092 (733a02544d189eeb751fe0d7ddca79a0ee28cce4),platform=Linux 4.4.0-98-generic x86_64)

After the 60 sec timeout, i get,

chrome: no process found
chromedriver: no process found

meaning that they weren't running (or they stopped before selenium tried to kill them)

I'm using

• Google Chrome 63.0.3239.132
• ChromeDriver 2.35.528139 (47ead77cb35ad2a9a83248b292151462a66cd881)

Did you use some procedure different than the readme one to install them?

[gricuk]

Ark74, I had the same problem. After your post, I try to use old chrome and chromeDriver versions, and it was resolved my problem.

[Ark74]

That's interesting, could you share the versions that are working with you?
I was thinking on upgrade all the python dependancies xD

Maybe just need to step back the browser and driver.

Thanks for the heads up ;)

[gricuk]

(Session info: chrome=62.0.3202.75)
(Driver info: chromedriver=2.33.506092

[Ark74]

Sorry, I don't wanna hijack this thread, I promise i'll open a new one.
Just one more question, is your jibri/jitsi-meet setup working as expected, recording and streaming from the TheBrewery room?

Thanks.

[gricuk]

Yes.
Also, I've changed jibri config:
replace bosh prefix to "boshdomain_prefix":"",

[noorshazmil]

hai @gricuk

what do you mean replace bosh prefix to "bosdomain_prefix":"" in jibri config?

default config.json are

{
"jidserver_prefix":"auth.",
"mucserver_prefix":"conference.",
"boshdomain_prefix":"recorder.",
"password":"jibri",
"recording_directory":"./recordings",
"jid_username":"jibri",
"roomname":"TheBrewery",
"xmpp_domain":"xmpp.example.com",
"selenium_xmpp_prefix":"recorder.",
"selenium_xmpp_username":"recorder",
"selenium_xmpp_password":"record",
"servers":["10.0.0.10"],
"environments":{
}
}

[aaronkvanmeerten]

I would very much recommend using the latest java-based jibri from our debian repository. It's easier to use and is much more reliable about getting connected. I'm going to close this issue, but please open a new one if you run into any problems with the new java-based jibri!