I am trying to deploy my algo to live trading using latest Quantconnect docker image, Lean CLI and IB Brokerage. When I start live trading with:

lean live “ProjectName” 

I get InitializationTimeout error.

Here are full logs:

PS C:\Users\Mislav\Documents\GitHub\lean_test> lean live "ExuberLocal"
Select a brokerage:
1) Paper Trading
2) Interactive Brokers
3) Tradier
4) OANDA
5) Bitfinex
6) Coinbase Pro
7) Binance
8) Zerodha
Enter an option: 2
To use IB with LEAN you must disable two-factor authentication or only use IBKR Mobile.
This is done from your IB Account Manage Account -> Settings -> User Settings -> Security -> Secure Login System.
In the Secure Login System, deselect all options or only select "IB Key Security via IBKR Mobile".
Interactive Brokers Lite accounts do not support API trading.
Username [optim1072]:
Account id [U2835450]: 
Account password [********]: 
Select a data feed:
1) Interactive Brokers
2) IQFeed
Enter an option: 1
Delayed market data is used when you subscribe to data for which you don't have a market data subscription on IB.
If delayed market data is disabled, live trading will stop and LEAN will shut down when this happens.
Enable delayed market data? [y/N]: n
20210615 12:58:06.619 TRACE:: Config.GetValue(): debug-mode - Using default value: False
20210615 12:58:06.628 TRACE:: Config.Get(): Configuration key not found. Key: plugin-directory - Using default value:
20210615 12:58:06.632 TRACE:: Composer(): Loading Assemblies from /Lean/Launcher/bin/Debug
20210615 12:58:06.709 TRACE:: Python for .NET Assembly: Python.Runtime, Version=2.0.4.0, Culture=neutral, PublicKeyToken=5000fea6cba702dd
20210615 12:58:06.762 TRACE:: Config.Get(): Configuration key not found. Key: data-directory - Using default value: ../../../Data/
20210615 12:58:06.767 TRACE:: Config.Get(): Configuration key not found. Key: version-id - Using default value:
20210615 12:58:06.768 TRACE:: Config.Get(): Configuration key not found. Key: cache-location - Using default value: /Lean/Data
20210615 12:58:06.769 TRACE:: Engine.Main(): LEAN ALGORITHMIC TRADING ENGINE v2.5.0.0 Mode: DEBUG (64bit) Host: DESKTOP-KBV1GE9
20210615 12:58:06.774 TRACE:: Engine.Main(): Started 12:58 PM
20210615 12:58:06.778 TRACE:: Config.Get(): Configuration key not found. Key: lean-manager-type - Using default value: LocalLeanManager
20210615 12:58:06.802 TRACE:: JobQueue.NextJob(): Selected /LeanCLI/main.py
20210615 12:58:06.880 TRACE:: Config.GetValue(): scheduled-event-leaky-bucket-capacity - Using default value: 120
20210615 12:58:06.880 TRACE:: Config.GetValue(): scheduled-event-leaky-bucket-time-interval-minutes - Using default value: 1440
20210615 12:58:06.881 TRACE:: Config.GetValue(): scheduled-event-leaky-bucket-refill-amount - Using default value: 18
20210615 12:58:06.883 TRACE:: Config.Get(): Configuration key not found. Key: algorithm-id - Using default value: main
20210615 12:58:06.888 TRACE:: Config.GetValue(): job-project-id - Using default value: 0
20210615 12:58:09.483 TRACE:: Config.Get(): Configuration key not found. Key: data-permission-manager - Using default value: DataPermissionManager
20210615 12:58:09.488 TRACE:: Config.GetValue(): streamed-chart-limit - Using default value: 12
20210615 12:58:09.489 TRACE:: Config.GetValue(): streamed-chart-group-size - Using default value: 3
20210615 12:58:09.499 TRACE:: AlgorithmManager.CreateTokenBucket(): Initializing LeakyBucket: Capacity: 120 RefillAmount: 18 TimeInterval: 1440
20210615 12:58:09.502 TRACE:: Config.GetValue(): algorithm-manager-time-loop-maximum - Using default value: 20
20210615 12:58:09.517 TRACE:: TextSubscriptionDataSourceReader.SetCacheSize(): Setting cache size to 71582788 items
20210615 12:58:09.520 TRACE:: Config.GetValue(): consumer-batching-timeout-ms - Using default value: 0
20210615 12:58:09.856 TRACE:: Config.GetValue(): algorithm-creation-timeout - Using default value: 90
20210615 12:58:09.862 TRACE:: PythonInitializer.Initialize(): start...
PythonEngine.Initialize(): Runtime.Initialize()...
Runtime.Initialize(): Py_Initialize...
Runtime.Initialize(): PyEval_InitThreads...
Runtime.Initialize(): Initialize types...
Runtime.Initialize(): Initialize types end.
Runtime.Initialize(): AssemblyManager.Initialize()...
Runtime.Initialize(): AssemblyManager.UpdatePath()...
PythonEngine.Initialize(): GetCLRModule()...
PythonEngine.Initialize(): clr GetManifestResourceStream...
20210615 12:58:10.193 TRACE:: PythonInitializer.Initialize(): ended
20210615 12:58:10.208 TRACE:: AlgorithmPythonWrapper(): Python version 3.6.8 |Anaconda, Inc.| (default, Dec 30 2018, 01:25:33)
[GCC 7.3.0]: Importing python module main
20210615 12:58:12.004 TRACE:: AlgorithmPythonWrapper(): main successfully imported.
20210615 12:58:12.011 TRACE:: AlgorithmPythonWrapper(): Creating IAlgorithm instance.
20210615 12:58:12.177 TRACE:: Config.GetValue(): mute-python-library-logging - Using default value: True
20210615 12:58:12.197 TRACE:: LocalObjectStore.Initialize(): Storage Root: /Storage/QCAlgorithm
20210615 12:58:12.201 TRACE:: LiveTradingDataFeed.GetDataChannelProvider(): will use DataChannelProvider
20210615 12:58:12.216 TRACE:: InteractiveBrokersBrokerage.InteractiveBrokersBrokerage(): Starting IB Automater...
20210615 12:58:12.227 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Setting execute permissions on IBAutomater.sh
20210615 12:58:12.269 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): chmod +x IBAutomater.sh: process exit code: 0
20210615 12:58:12.269 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Setting execute permissions on IBAutomaterScreenCapture.sh
20210615 12:58:12.274 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): chmod +x IBAutomaterScreenCapture.sh: process exit code: 0
20210615 12:58:12.275 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Loading IBGateway - Version: 978 - Path: /root/Jts/ibgateway/978 - User: optim1072
20210615 12:58:12.276 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Searching for TWS JRE path
20210615 12:58:12.276 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Install4J path: /root/Jts/ibgateway/978/.install4j
20210615 12:58:12.277 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): File found: /root/Jts/ibgateway/978/.install4j/pref_jre.cfg
20210615 12:58:12.277 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Directory found: /usr/local/i4j_jres/1.8.0_152-tzdata2019c
20210615 12:58:12.278 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Updating IBGateway configuration file: /root/Jts/ibgateway/978/ibgateway.vmoptions
20210615 12:58:12.289 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): IBAutomater process started - Id:54 - Name:IBAutomater.sh - InitializationTimeout:00:10:00
20210615 12:58:12.290 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): UID        PID  PPID  C    SZ   RSS PSR STIME TTY          TIME CMD
20210615 12:58:12.291 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): root         1     0 99 1505223 297416 20 12:58 pts/0  00:00:07 dotnet QuantConnect.Lean.Launcher.dll
20210615 12:58:12.291 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): root        54     1  0  5412  3508  19 12:58 pts/0    00:00:00   /bin/bash /Lean/Launcher/bin/Debug/IBAutomater.sh /root/Jts/ibgateway/978
20210615 12:58:12.292 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): root        55    54  0  9419  3476  29 12:58 pts/0    00:00:00     ps -AFH
20210615 12:58:12.533 ERROR:: LiveTradingResultHandler.Update(): Algorithm not yet initialized.
20210615 12:58:17.297 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): UID        PID  PPID  C    SZ   RSS PSR STIME TTY          TIME CMD
20210615 12:58:17.298 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): root         1     0 70 1505223 297728 20 12:58 pts/0  00:00:07 dotnet QuantConnect.Lean.Launcher.dll
20210615 12:58:17.298 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): root        54     1  0  5412  3596  19 12:58 pts/0    00:00:00   /bin/bash /Lean/Launcher/bin/Debug/IBAutomater.sh /root/Jts/ibgateway/978
20210615 12:58:17.299 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): root        60    54  0  9419  3360  29 12:58 pts/0    00:00:00     ps -AFH
20210615 12:58:18.278 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): IBGateway started
20210615 12:58:22.280 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_ACTIVATED] - Window title: [IB Gateway] - Window name: [frame0]
20210615 12:58:22.280 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_OPENED] - Window title: [IB Gateway] - Window name: [frame0]
20210615 12:58:22.280 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Main window - Window title: [IB Gateway] - Window name: [frame0]
20210615 12:58:22.281 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Trading mode: live
20210615 12:58:22.281 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Click button: [Log In]
20210615 12:58:23.279 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_DEACTIVATED] - Window title: [IB Gateway] - Window name: [frame0]
20210615 12:58:23.281 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_OPENED] - Window title: [Loading...] - Window name: [frame1]
20210615 12:58:23.281 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_ACTIVATED] - Window title: [Loading...] - Window name: [frame1]
20210615 12:58:24.278 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_DEACTIVATED] - Window title: [Loading...] - Window name: [frame1]
20210615 12:58:24.279 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_ACTIVATED] - Window title: [Authenticating...] - Window name: [frame2]
20210615 12:58:24.279 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_OPENED] - Window title: [Authenticating...] - Window name: [frame2]
20210615 12:58:28.021 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): LogModuleConfigurator-Init: Log4j Ver2.x found on classpath
20210615 12:58:28.022 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): LogModuleConfigurator-Init: LogModuleConfigurator initialized with Log4j Verd.x
20210615 12:58:29.279 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_DEACTIVATED] - Window title: [Starting application...] - Window name: [frame2]
20210615 12:58:29.280 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_CLOSED] - Window title: [Starting application...] - Window name: [frame2]
20210615 12:58:29.280 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Finding main window...
20210615 12:58:29.281 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Found main window (Window title: [OptimTrader Gateway] - Window name: [frame1])
20210615 12:58:30.279 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_OPENED] - Window title: [U7835450 OptimTrader Configuration] - Window name: [Configuration]
20210615 12:58:30.279 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Unselect checkbox: [Read-Only API]
20210615 12:58:30.279 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Set API port textbox value: [4002]
20210615 12:58:30.280 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Select checkbox: [Create API message log file]
20210615 12:58:30.280 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Error: java.lang.Exception: 'Use Account Groups with Allocation Methods' check box not found
20210615 12:58:30.281 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Window event: [WINDOW_ACTIVATED] - Window title: [U7835450 OptimTrader Configuration] - Window name: [Configuration]
20210615 13:08:12.291 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): IB launcher log file: /root/Jts/launcher.log
20210615 13:08:12.292 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.698 [DU] INFO  [JTS-Main] - OvernightManager.dayInit: new values: dayOfTheWeek: 3 (Tue), YYYYMMofToday: 202106, YYYYMMDDofToday: 20210615, m_todayDate: 20210615, 
dueToChangeInTimezone: false
20210615 13:08:12.292 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.699 [DU] INFO  [JTS-Main] - Created on: 20210615
20210615 13:08:12.293 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.699 [DU] INFO  [JTS-Main] - ------------------------------- IB GATEWAY RESTART --------------------------------
20210615 13:08:12.294 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.699 [DU] INFO  [JTS-Main] - Jts dir is /root/Jts/
20210615 13:08:12.295 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.723 [DU] INFO  [JTS-Main] - PROXY Installed custom selector
20210615 13:08:12.296 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.728 [DU] INFO  [JTS-Main] - LOCALE: Property locale_to_use=null
20210615 13:08:12.296 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.731 [DU] INFO  [JTS-Main] - LOCALE: JTS Locale=en_US  country=United States  variant=
20210615 13:08:12.297 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.734 [DU] INFO  [JTS-Main] - Region: America
20210615 13:08:12.297 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.737 [DU] INFO  [JTS-Main] - WebRegionURL Path:/java/classes/locales.jar URL:http://www.interactivebrokers.com/java/classes/locales.jar
20210615 13:08:12.298 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:18.739 [DU] INFO  [JTS-Main] - Starting to download 'https://download2.interactivebrokers.com/java/classes/locales.jar' using HotBackup
'https://download2.interactivebrokers.com/java/classes/locales.jar'
20210615 13:08:12.298 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.335 [DU] INFO  [JTS-Main] - LOCALE: needToUpdate  dest=/root/Jts/ibgateway/978/jars/locales.jar  sourceMod=2019-11-07 15:30:14  destMod=2019-11-07 15:30:14  sourceSize=114646 
destSize=114646  conditions=[NEWER_REMOTE, LOCAL_IN_FUTURE, SIZE_DIFFERS]  download=false
20210615 13:08:12.298 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.335 [DU] INFO  [JTS-Main] - LOCALE: /root/Jts/ibgateway/978/jars/locales.jar already up-to-date
20210615 13:08:12.299 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.383 [DU] INFO  [JTS-Main] - LOCALE: loaded 9 supported locales
20210615 13:08:12.299 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.412 [DU] INFO  [JTS-Main] - LOCALE: loaded 8 countries
20210615 13:08:12.299 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.422 [DU] INFO  [JTS-Main] - Locale from ini: en
20210615 13:08:12.300 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.422 [DU] INFO  [JTS-Main] - LOCALE: created classloader java.net.URLClassLoader@78ffe6dc with file:/root/Jts/ibgateway/978/jars/locales.jar, parent:
sun.misc.Launcher$AppClassLoader@18b4aac2
20210615 13:08:12.300 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.424 [DU] INFO  [JTS-Main] - Locale=en_US  country=United States  variant=
20210615 13:08:12.300 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.427 [DU] INFO  [JTS-Main] - prohibiting class lookup [twslaunch.ji18n.LauncherLanguage]
20210615 13:08:12.300 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.431 [DU] INFO  [JTS-Main] - prohibiting class lookup [twslaunch.ji18n.LauncherLanguage_en]
20210615 13:08:12.301 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.432 [DU] INFO  [JTS-Main] - prohibiting class lookup [twslaunch.ji18n.LauncherLanguage_en_US]
20210615 13:08:12.301 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.432 [DU] INFO  [JTS-Main] - LOCALE:  type: launcher intended: en_US actual:  size: 416
20210615 13:08:12.302 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.433 [DU] INFO  [JTS-Main] - settings dir: '/root/Jts/'
20210615 13:08:12.302 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.440 [DU] INFO  [JTS-Main] - initPreUI() - entry
20210615 13:08:12.303 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.471 [DU] INFO  [JTS-Main] - Build 978.2c, Jun 17, 2020 1:25:21 PM
20210615 13:08:12.303 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.557 [DU] INFO  [JTS-Main] - LookAndFeelChangedListener installed
20210615 13:08:12.303 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:19.563 [DU] INFO  [JTS-Main] - JtsLookAndFeel initialized
20210615 13:08:12.304 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.045 [DU] INFO  [JTS-Main] - Current LAF: TWS LAF
20210615 13:08:12.305 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.046 [DU] INFO  [JTS-Main] - initPreUI() - exit
20210615 13:08:12.305 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.060 [DU] INFO  [JTS-MiscUlrsRequester-5] - Connecting gdc1.ibllc.com:4000 timeout=0
20210615 13:08:12.305 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.066 [DU] INFO  [JTS-Main] - Hard linking is not enabled in standalone mode
20210615 13:08:12.306 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.204 [DU] INFO  [JTS-MiscUlrsRequester-5] - Connected to gdc1.ibllc.com:4000 on local port 0 socket local addrs:port /172.17.0.2:49264
20210615 13:08:12.306 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.207 [DU] INFO  [JTS-MiscUlrsRequester-5] - Resolving mac address
20210615 13:08:12.307 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.208 [DU] INFO  [JTS-MiscUlrsRequester-5] - MACNetworkInfo created <9b1b1947|02:42:AC:11:00:02|172.17.0.2>
20210615 13:08:12.307 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.214 [DU] INFO  [JTS-MISC URLsListenerS1-6] - Starting Listener thread
20210615 13:08:12.307 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.247 [DU] INFO  [JTS-MiscUlrsRequester-5] - Sending MiscUrls request
20210615 13:08:12.308 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.247 [DU] INFO  [JTS-AuthDispatcherS1-7] - Starting dispatcher
20210615 13:08:12.308 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.249 [DU] INFO  [JTS-MiscUlrsRequester-5] - LOGIN_TIME_MONITOR     false 12:58:20:249 since start = 0 thread delay = 0 global delay = 0 tag=null, cls=AuthDispatcher method=send20210615 13:08:12.308 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.250 [DU] INFO  [JTS-AuthResponseMonitor-8] - Starting auth response monitor thread
20210615 13:08:12.308 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.250 [DU] INFO  [JTS-MISC URLsListenerS1-6] - Initial listener buffer size is 65536
20210615 13:08:12.309 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.379 [DU] INFO  [JTS-AuthDispatcherS1-7] - Received NS_MISC_URLS
20210615 13:08:12.309 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.382 [DU] INFO  [JTS-AuthDispatcherS1-7] - Misc URL: scope is blank - setting userScope to EXTERNAL
20210615 13:08:12.309 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.383 [DU] INFO  [JTS-AuthDispatcherS1-7] - Misc URL: setting userScope to EXTERNAL
20210615 13:08:12.310 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.400 [DU] INFO  [JTS-AuthDispatcherS1-7] - Disconnecting gdc1.ibllc.com:4000 with status DISCONNECT_BY_DESIGN
20210615 13:08:12.310 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.401 [DU] INFO  [JTS-AuthDispatcherS1-7] - Terminating dispatcher
20210615 13:08:12.310 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.401 [DU] INFO  [JTS-MISC URLsListenerS1-6] - Socket or stream for connection gdc1.ibllc.com:4000 was closed by another thread
20210615 13:08:12.311 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.402 [DU] INFO  [JTS-MISC URLsListenerS1-6] - Terminating Listener thread
20210615 13:08:12.311 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:20.402 [DU] INFO  [JTS-AuthResponseMonitor-8] - Terminating auth response monitor thread
20210615 13:08:12.312 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.330 [DU] INFO  [AWT-EventQueue-0] - Install4jAutoUpdateService 
props=[updateDownloadUrl=null,skipUpdateCheck=false,nightlyEnabled=true,channelNameArg=null,channelName=latest,jtsConfigDir=null,apiPort=null,sessionId=null,installDir=/root/Jts/ibgateway/978/,exeName=null]
20210615 13:08:12.312 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.606 [DU] INFO  [AWT-EventQueue-0] - Default timezone:GMT
20210615 13:08:12.312 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - Started on 20210615-12:58:21
20210615 13:08:12.313 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - Default timezone:GMT name:Greenwich Mean Time dst:0
20210615 13:08:12.313 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - java version = 1.8.0_152
20210615 13:08:12.314 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - sun.arch.data.model = 64
20210615 13:08:12.314 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - os = Linux
20210615 13:08:12.314 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - os architecture = 32
20210615 13:08:12.315 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - runtime = Java(TM) SE Runtime Environment
20210615 13:08:12.315 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - vendor = Oracle Corporation
20210615 13:08:12.315 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - vm name = Java HotSpot(TM) 64-Bit Server VM
20210615 13:08:12.316 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:21.675 [DU] INFO  [AWT-EventQueue-0] - username = 'Unknown'
20210615 13:08:12.316 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.232 [DU] INFO  [AWT-EventQueue-0] - Setting dir is /root/Jts/
20210615 13:08:12.316 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.233 [DU] INFO  [AWT-EventQueue-0] - PROXY Cleared HTTP proxy: no valid proxy is present (passing on :0)
20210615 13:08:12.317 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.235 [DU] INFO  [AWT-EventQueue-0] - SPLASH Creating screen with 5 steps
20210615 13:08:12.317 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.849 [DU] INFO  [AWT-EventQueue-0] - initAutoEncryptionMenu(): autoEncryption set to true
20210615 13:08:12.317 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.859 [DU] INFO  [AWT-EventQueue-0] - Contacting gdc1.ibllc.com:4000 as user NOT PRINTED
20210615 13:08:12.318 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.877 [DU] INFO  [AWT-EventQueue-0] - CONN CALC: Last connection attempt time is cleared!
20210615 13:08:12.318 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.877 [DU] INFO  [AWT-EventQueue-0] - CONN CALC: Last connection set as now:12:58:22:877
20210615 13:08:12.318 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.877 [DU] INFO  [AWT-EventQueue-0] - Prelogin delay:0
20210615 13:08:12.319 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.880 [DU] INFO  [JTS-Login-12] - Starting launcher login thread
20210615 13:08:12.319 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.881 [DU] INFO  [JTS-Login-12] - SPLASH Connecting to server
20210615 13:08:12.319 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.882 [DU] INFO  [JTS-Login-12] - Connecting gdc1.ibllc.com:4000 timeout=20000
20210615 13:08:12.320 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.885 [DU] INFO  [JTS-Order-Audit-14] - Starting async queue thread
20210615 13:08:12.320 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:22.886 [DU] INFO  [AWT-EventQueue-0] - Network monitor is activated
20210615 13:08:12.321 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.006 [DU] INFO  [JTS-Login-12] - Connected to gdc1.ibllc.com:4000 on local port 0 socket local addrs:port /172.17.0.2:49266
20210615 13:08:12.321 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.012 [DU] INFO  [JTS-Login-12] - JTSAuthConnection.onConnected(): lastKnownCipherStatus=SECURE
20210615 13:08:12.321 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.012 [DU] INFO  [JTS-Login-12] - Changing state for connection from PRELOGON to PRELOGON in A 440325285: SessionSocket:2
20210615 13:08:12.322 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.013 [DU] INFO  [JTS-Login-12] - Authenticating
20210615 13:08:12.322 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.014 [DU] INFO  [JTS-CCPListenerS2-15] - Starting Listener thread
20210615 13:08:12.323 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.014 [DU] INFO  [JTS-Login-12] - AuthTimeoutMonitor-CCP: deactivate
20210615 13:08:12.323 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.015 [DU] INFO  [JTS-Login-12] - AuthTimeoutMonitor-CCP: timeout=20000
20210615 13:08:12.323 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.025 [DU] INFO  [JTS-Login-12] - AuthTimeoutMonitor-CCP: activate
20210615 13:08:12.324 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.025 [DU] INFO  [JTS-AuthTimeoutMonitorThread-CCP-17] - Starting auth timeout monitor thread for CCP
20210615 13:08:12.324 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.026 [DU] INFO  [JTS-Login-12] - SPLASH -0001 step 1 AuthDispatcher.connect
20210615 13:08:12.325 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.028 [DU] INFO  [JTS-Login-12] - Sending connect request
20210615 13:08:12.325 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.029 [DU] INFO  [JTS-AuthDispatcherS2-16] - Starting dispatcher
20210615 13:08:12.325 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.030 [DU] INFO  [JTS-Login-12] - AuthDispatcher.initNSMsgVersionLimits: minVersion=35, maxVersion=43
20210615 13:08:12.326 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.031 [DU] INFO  [JTS-Login-12] - SPLASH Authenticating
20210615 13:08:12.326 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.033 [DU] INFO  [JTS-CCPListenerS2-15] - Initial listener buffer size is 65536
20210615 13:08:12.326 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.034 [DU] INFO  [JTS-Login-12] - Terminating launcher login thread
20210615 13:08:12.327 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.175 [DU] INFO  [JTS-AuthDispatcherS2-16] - set nsMsgVersion: context=SecureConnectionStart; version=43
20210615 13:08:12.327 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.218 [DU] INFO  [JTS-AuthDispatcherS2-16] - cert #0 subject:CN=tws.ibllc.com,OU=tws.ibllc.com,O=IBLLC,C=US        issuer:CN=prod.ckg.ibllc.com,O=Interactive Brokers
LLC,L=Greenwich,ST=Connecticut,C=US  notBefore:20210614-13:36:14 Europe/London       notAfter:20210616-21:36:14 Europe/London
20210615 13:08:12.327 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.219 [DU] INFO  [JTS-AuthDispatcherS2-16] - cert #1 subject:CN=prod.ckg.ibllc.com,O=Interactive Brokers LLC,L=Greenwich,ST=Connecticut,C=US       issuer:CN=DigiCert SHA2 High 
Assurance Server CA,OU=www.digicert.com,O=DigiCert Inc,C=US        notBefore:20200812-01:00:00 Europe/London       notAfter:20220817-13:00:00 Europe/London
20210615 13:08:12.328 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.219 [DU] INFO  [JTS-AuthDispatcherS2-16] - cert #2 subject:CN=DigiCert SHA2 High Assurance Server CA,OU=www.digicert.com,O=DigiCert Inc,C=US     issuer:CN=DigiCert High       
Assurance EV Root CA,OU=www.digicert.com,O=DigiCert Inc,C=US    notBefore:20131022-13:00:00 Europe/London       notAfter:20281022-13:00:00 Europe/London
20210615 13:08:12.328 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.221 [DU] INFO  [JTS-AuthDispatcherS2-16] - Current time is: 20210615-13:58:23 Europe/London
20210615 13:08:12.329 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.250 [DU] INFO  [JTS-AuthDispatcherS2-16] - CipherContext initialization successed.
20210615 13:08:12.329 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.318 [DU] INFO  [JTS-AuthDispatcherS2-16] - AuthDispatcher.NSConnectRequest: userSessionID=60c8a3ee.0366, beeDemoType=NULL
20210615 13:08:12.329 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.319 [DU] INFO  [JTS-AuthDispatcherS2-16] - SPLASH 0294 step 2 AuthDispatcher.send
20210615 13:08:12.329 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.470 [DU] INFO  [JTS-AuthDispatcherS2-16] - set nsMsgVersion: context=AuthStart; version=42
20210615 13:08:12.330 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.471 [DU] INFO  [JTS-AuthDispatcherS2-16] - Received NS_AUTH_START: 1
20210615 13:08:12.330 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.472 [DU] INFO  [JTS-AuthDispatcherS2-16] - pwd time to expiry ms: 2478614494000 days: 28688
20210615 13:08:12.331 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.473 [DU] INFO  [JTS-AuthDispatcherS2-16] - Long pwd allowed: true
20210615 13:08:12.331 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.473 [DU] INFO  [JTS-AuthDispatcherS2-16] -  NS_AUTH_START version=42 pwd=1 soft=0 token(s)=[token=0] initialTokenType=PWD authenticationType=MARKET_DATA_CONNECTION
identityHashcode=1163632927
20210615 13:08:12.331 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.475 [DU] INFO  [JTS-AuthDispatcherS2-16] - Starting password Authentication
20210615 13:08:12.332 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.475 [DU] INFO  [JTS-AuthDispatcherS2-16] - sendXYZMessage: 777
20210615 13:08:12.332 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.627 [DU] INFO  [JTS-AuthDispatcherS2-16] - SPLASH 0307 step 3 AuthDispatcher.processDataItr
20210615 13:08:12.332 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.630 [DU] INFO  [JTS-AuthDispatcherS2-16] - Receiver: 777
20210615 13:08:12.333 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.631 [DU] INFO  [JTS-AuthDispatcherS2-16] - processXYZMessage: 777, protocol version: 42
20210615 13:08:12.333 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.631 [DU] INFO  [JTS-AuthDispatcherS2-16] - Received AUTH_PARAMS
20210615 13:08:12.333 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.631 [DU] INFO  [JTS-AuthDispatcherS2-16] - sendXYZMessage: 777
20210615 13:08:12.334 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.721 [DU] INFO  [JTS-DeadlockMonitor-2] - Memory:total=786,432KB free=703,728KB HeapUsage: Max=786,432KB used=81,165KB committed=786,432KB NonHeapUsage: Max=0KB used=61,137KB  
committed=62,976KB
20210615 13:08:12.334 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.744 [DU] INFO  [JTS-AuthDispatcherS2-16] - Receiver: 777
20210615 13:08:12.334 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.744 [DU] INFO  [JTS-AuthDispatcherS2-16] - processXYZMessage: 777, protocol version: 42
20210615 13:08:12.335 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.745 [DU] INFO  [JTS-AuthDispatcherS2-16] - Received SERVER_PARAMS
20210615 13:08:12.335 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.747 [DU] INFO  [JTS-AuthDispatcherS2-16] - Session Token received in AuthenticationMessageXYZ=c5bc8XXXXX.., messageId=SERVER_KEY (4)
20210615 13:08:12.335 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.748 [DU] INFO  [JTS-AuthDispatcherS2-16] - sendXYZMessage: 777
20210615 13:08:12.336 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.856 [DU] INFO  [JTS-AuthDispatcherS2-16] - SPLASH 0230 step 3 AuthDispatcher.processDataItr
20210615 13:08:12.336 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.857 [DU] INFO  [JTS-AuthDispatcherS2-16] - Receiver: 777
20210615 13:08:12.336 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.857 [DU] INFO  [JTS-AuthDispatcherS2-16] - processXYZMessage: 777, protocol version: 42
20210615 13:08:12.337 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.857 [DU] INFO  [JTS-AuthDispatcherS2-16] - Received AUTH_RESULT
20210615 13:08:12.337 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.857 [DU] INFO  [JTS-AuthDispatcherS2-16] - Passed pwd authentication.
20210615 13:08:12.337 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.857 [DU] INFO  [JTS-AuthDispatcherS2-16] - Authentication completed.
20210615 13:08:12.337 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:23.858 [DU] INFO  [JTS-AuthDispatcherS2-16] - setting session token in AuthenticationHandler: authenticationType=MARKET_DATA_CONNECTION hashCode=1163632927
new=XYZSessionToken[type=SOFT,token=c5bc8XXXXX..] old=null ctciOld=null
20210615 13:08:12.338 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.009 [DU] INFO  [JTS-AuthDispatcherS2-16] - Received NS_CONNECT_RESPONSE: version=42
20210615 13:08:12.338 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.011 [DU] INFO  [JTS-AuthDispatcherS2-16] - Log encryption support is enabled [logKeyId: aaa].
20210615 13:08:12.338 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.011 [DU] INFO  [JTS-AuthDispatcherS2-16] - CCP sent paperUser NOT PRINTED for prodUser NOT PRINTED
20210615 13:08:12.339 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.013 [DU] INFO  [JTS-AuthDispatcherS2-16] - Sending NSNewcommPort [publishTokens=0]...
20210615 13:08:12.339 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.122 [DU] INFO  [JTS-AuthDispatcherS2-16] - SPLASH 0266 step 3 AuthDispatcher.processDataItr
20210615 13:08:12.339 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.122 [DU] INFO  [JTS-AuthDispatcherS2-16] - Received NS_FIX_START
20210615 13:08:12.340 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.124 [DU] INFO  [JTS-AuthDispatcherS2-16] - Terminating dispatcher
20210615 13:08:12.340 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.125 [DU] INFO  [JTS-AuthTimeoutMonitorThread-CCP-17] - Terminating auth timeout monitor thread for CCP
20210615 13:08:12.340 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.125 [DU] INFO  [JTS-PostAuthenticateS2-22] - AuthTimeoutMonitor-CCP: deactivate
20210615 13:08:12.341 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.126 [DU] INFO  [JTS-PostAuthenticateS2-22] - Authentication complete
20210615 13:08:12.341 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.126 [DU] INFO  [JTS-PostAuthenticateS2-22] - Creating a FixLogonHandlerFactory instance...
20210615 13:08:12.342 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.127 [DU] INFO  [JTS-PostAuthenticateS2-22] - Instance created.
20210615 13:08:12.342 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.133 [DU] INFO  [AWT-EventQueue-0] - initPreUI() - entry
20210615 13:08:12.343 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.134 [DU] INFO  [AWT-EventQueue-0] - jtsLookAndFeel uninitialized
20210615 13:08:12.343 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.134 [DU] INFO  [AWT-EventQueue-0] - JtsLookAndFeel initialized
20210615 13:08:12.344 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.135 [DU] INFO  [JTS-PostAuthenticateS2-22] - JDOMFactory Class =interface org.jdom.JDOMFactory
20210615 13:08:12.344 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.140 [DU] INFO  [JTS-PostAuthenticateS2-22] - hsqldb jdbc =class org.hsqldb.jdbcDriver 2.4
20210615 13:08:12.345 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.145 [DU] INFO  [AWT-EventQueue-0] - Current LAF: TWS LAF
20210615 13:08:12.345 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.145 [DU] INFO  [AWT-EventQueue-0] - initPreUI() - exit
20210615 13:08:12.346 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.145 [DU] INFO  [JTS-PostAuthenticateS2-22] - Changing state for connection from PRELOGON to PRELOGON in A 440325285: SessionSocket:2
20210615 13:08:12.346 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.147 [DU] INFO  [JTS-PostAuthenticateS2-22] - createOrRenameToNewStyleUserDirectory: create newDir=/root/Jts/cjfplkclkimgdleijblgbhobjbahdlldbanngelj
20210615 13:08:12.346 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.147 [DU] INFO  [JTS-PostAuthenticateS2-22] - performLogSwitch: forceToSwitch=false
20210615 13:08:12.347 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): [IB Launcher] 2021-06-15 12:58:24.159 [DU] INFO  [JTS-PostAuthenticateS2-22] - Switching to a new encrypted log...
20210615 13:08:12.348 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): Start IBAutomaterScreenCapture.sh
20210615 13:08:12.352 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterErrorDataReceived(): IBAutomaterScreenCapture.sh: /Lean/Launcher/bin/Debug/IBAutomaterScreenCapture.sh: line 3: convert: command not found
20210615 13:08:12.352 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterErrorDataReceived(): IBAutomaterScreenCapture.sh: /Lean/Launcher/bin/Debug/IBAutomaterScreenCapture.sh: line 3: xwd: command not found
20210615 13:08:12.353 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): IBAutomaterScreenCapture.sh /tmp/IBAutomater-20210615130812.png: process exit code: 127
20210615 13:08:12.353 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): IB Automater initialization timeout.
20210615 13:08:12.354 TRACE:: InteractiveBrokersBrokerage.OnIbAutomaterOutputDataReceived(): IBAutomater error - Code: InitializationTimeout Message: The IBAutomater initialization timed out.
20210615 13:08:12.355 ERROR:: Brokerage.OnMessage(): Error - Code: InitializationTimeout - The IBAutomater initialization timed out.
20210615 13:08:12.375 ERROR:: Engine.Run():  System.Exception: InteractiveBrokersBrokerage.CheckIbAutomaterError(): InitializationTimeout - The IBAutomater initialization timed out.
   at QuantConnect.Brokerages.InteractiveBrokers.InteractiveBrokersBrokerage.CheckIbAutomaterError(StartResult result, Boolean throwException) in /LeanCloud/CI.Builder/bin/Debug/src/QuantConnect/Lean/Brokerages/InteractiveBrokers/InteractiveBrokersBrokerage.cs:line 3367
   at QuantConnect.Brokerages.InteractiveBrokers.InteractiveBrokersBrokerage..ctor(IAlgorithm algorithm, IOrderProvider orderProvider, ISecurityProvider securityProvider, IDataAggregator aggregator, IMapFileProvider mapFileProvider, String account, String host, Int32 port, String ibDirectory, String  
ibVersion, String userName, String password, String tradingMode, String agentDescription, Boolean loadExistingHoldings) in /LeanCloud/CI.Builder/bin/Debug/src/QuantConnect/Lean/Brokerages/InteractiveBrokers/InteractiveBrokersBrokerage.cs:line 279
   at QuantConnect.Brokerages.InteractiveBrokers.InteractiveBrokersBrokerageFactory.CreateBrokerage(LiveNodePacket job, IAlgorithm algorithm) in /LeanCloud/CI.Builder/bin/Debug/src/QuantConnect/Lean/Brokerages/InteractiveBrokers/InteractiveBrokersBrokerageFactory.cs:line 101
   at QuantConnect.Lean.Engine.Setup.BrokerageSetupHandler.CreateBrokerage(AlgorithmNodePacket algorithmNodePacket, IAlgorithm uninitializedAlgorithm, IBrokerageFactory& factory) in /LeanCloud/CI.Builder/bin/Debug/src/QuantConnect/Lean/Engine/Setup/BrokerageSetupHandler.cs:line 135
   at QuantConnect.Lean.Engine.Engine.Run(AlgorithmNodePacket job, AlgorithmManager manager, String assemblyPath, WorkerThread workerThread) in /LeanCloud/CI.Builder/bin/Debug/src/QuantConnect/Lean/Engine/Engine.cs:line 134
20210615 13:08:12.377 TRACE:: JOB HANDLERS:
20210615 13:08:12.377 TRACE::          DataFeed:     QuantConnect.Lean.Engine.DataFeeds.LiveTradingDataFeed
20210615 13:08:12.378 TRACE::          Setup:        QuantConnect.Lean.Engine.Setup.BrokerageSetupHandler
20210615 13:08:12.378 TRACE::          RealTime:     QuantConnect.Lean.Engine.RealTime.LiveTradingRealTimeHandler
20210615 13:08:12.379 TRACE::          Results:      QuantConnect.Lean.Engine.Results.LiveTradingResultHandler
20210615 13:08:12.379 TRACE::          Transactions: QuantConnect.Lean.Engine.TransactionHandlers.BrokerageTransactionHandler
20210615 13:08:12.379 TRACE::          Alpha:        QuantConnect.Lean.Engine.Alphas.DefaultAlphaHandler
20210615 13:08:12.381 TRACE::          Brokerage:
20210615 13:08:12.382 TRACE:: DefaultAlphaHandler.Exit(): Exiting...
20210615 13:08:12.388 TRACE:: DefaultAlphaHandler.Exit(): Ended
20210615 13:08:12.391 ERROR:: Algorithm.Initialize() Error: InteractiveBrokersBrokerage.CheckIbAutomaterError(): InitializationTimeout - The IBAutomater initialization timed out. Stack Trace: InteractiveBrokersBrokerage.CheckIbAutomaterError(): InitializationTimeout - The IBAutomater 
initialization timed out.
 InteractiveBrokersBrokerage.CheckIbAutomaterError(): InitializationTimeout - The IBAutomater initialization timed out.
20210615 13:08:12.391 ERROR:: LiveTradingResultHandler.Update(): Algorithm not yet initialized.
20210615 13:08:12.392 TRACE:: LiveTradingResultHandler.Run(): Ending Thread...
20210615 13:08:12.395 TRACE:: StopSafely(): waiting for 'Result Thread' thread to stop...
20210615 13:08:12.398 TRACE:: LiveTradingResultHandler.SendFinalResult(): Starting...
20210615 13:08:12.458 TRACE:: LiveTradingResultHandler.SendFinalResult(): Finished storing results. Start sending...
20210615 13:08:12.459 TRACE:: LiveTradingResultHandler.SendFinalResult(): Ended
20210615 13:08:12.460 TRACE:: Engine.Run(): Disposing of setup handler...
20210615 13:08:12.461 TRACE:: BrokerageSetupHandler.Setup(): did not find any data queue handler to dispose
20210615 13:08:12.462 TRACE:: Engine.Main(): Analysis Completed and Results Posted.
Engine.Main(): Analysis Complete.
20210615 13:08:12.464 TRACE:: Engine.Main(): Packet removed from queue: main
20210615 13:08:12.464 TRACE:: LeanEngineSystemHandlers.Dispose(): start...
20210615 13:08:12.465 TRACE:: LeanEngineSystemHandlers.Dispose(): Disposed of system handlers.
20210615 13:08:12.466 TRACE:: LeanEngineAlgorithmHandlers.Dispose(): start...
20210615 13:08:12.466 TRACE:: BrokerageSetupHandler.Setup(): did not find any data queue handler to dispose
20210615 13:08:12.476 TRACE:: LeanEngineAlgorithmHandlers.Dispose(): Disposed of algorithm handlers.
20210615 13:08:12.481 TRACE:: Program.Main(): Exiting Lean...
Error: Something went wrong while running 'ExuberLocal' in the 'lean-cli' environment, the output is stored in 'ExuberLocal\live\2021-06-15_14-57-56'

Author