Mendix app startup hangs and take too long to complete

[aum2440]

I am running docker image on windows 10. Another observation is the logs configuration doesn't seem to work, as all logs are at Info level even after setting below config. I am providing the env variables through file and using below command to run the image docker run -itd -p 8080:8080 --env-file env.txt myimage:latest

env.txt:-

LOGGING_CONFIG_CLIENT={"Client":"DEBUG"}
LOGGING_CONFIG_CORE={"Core":"DEBUG"}
LOGGING_CONFIG_CONNECTION={"ConnectionBus": "DEBUG"}
LOGGING_CONFIG_ACTION={"ActionManager":"DEBUG"}
LOGGING_CONFIG_CONFIGR={"Configuration":"DEBUG"}
ADMIN_PASSWORD=*****
DATABASE_ENDPOINT=****

It takes ~2 hours to statup. Below are start up logs:-

INFO - Core: Initializing ConnectionBus...
INFO - Core: Initialized ConnectionBus.
INFO - ConnectionBus: Database product information: PostgreSQL 13.6
INFO - ConnectionBus: Database engine version: 13.6
INFO - ConnectionBus: Starting uniqueness constraint violation diagnostics...
INFO - ConnectionBus: Uniqueness constraint violation diagnostics has been successfully finished. No violations are found.
INFO - Core: Waiting for configured storage service: com.mendix.storage.localfilesystem
INFO - Core: Storage service: Local file system storage, uploaded files path: /opt/mendix/build/data/files
INFO - ConnectionBus: Database: PostgreSQL 13.6, name: 'jdbc:postgresql://*******:port/mydb?tcpKeepAlive=true'
Driver: PostgreSQL JDBC Driver 42.2.20
INFO - Core: Configured storage service started.
INFO - Core: Initializing license...
INFO - Core: Project company name is '******'
INFO - Core: License expiration date is '******'
INFO - Core: License type is: '*******'
**2022-06-07T09:54:34.273485300Z**  INFO - Core: Initialized license.
2022-06-07T09:54:36.344590300Z  INFO - Queue: Initializing task queue manager with configuration: .
2022-06-07T09:54:36.357240600Z  INFO - Core: Running after-startup-action...
2022-06-07T09:54:36.370178400Z  INFO - Submarine: Is logging service available: false
2022-06-07T10:30:50.342785600Z  WARNING - ConnectionBus_Queries: Query executed in 21 seconds and 365 milliseconds: INSERT INTO "mxmodelreflection$mxobjectmember_mxobjecttype" ("mxmodelreflection$mxobjectmemberid",
2022-06-07T10:30:50.342872800Z  "mxmodelreflection$mxobjecttypeid")
2022-06-07T10:30:50.342898000Z   VALUES (?,
2022-06-07T10:30:50.342900800Z  ?)
2022-06-07T10:54:23.191017400Z  INFO: MENDIX-LOGGING-HEARTBEAT: Heartbeat number 2
2022-06-07T11:40:23.707243500Z  INFO - Subscribe to exchange: Successfully subscribed to exchange submarine-dev-exchange at rabbitmq.snpaas.eu.
2022-06-07T11:40:23.838640100Z  INFO - Core: Successfully ran after-startup-action.
2022-06-07T11:40:23.844485700Z  INFO - ActionManager: Scheduling Escalation.ScE_GetEmailConfigIds every 1 day, starting from 2020-11-03 20:00:00 (UTC)
2022-06-07T11:40:23.976700300Z  INFO - ActionManager: Scheduling DataHandling.ScE_GetBoardSection every 1 week, starting from 2022-02-05 13:00:00 (UTC)
2022-06-07T11:40:23.977141000Z  INFO - ActionManager: Scheduling Escalation.ScE_Escalation_CheckRecentUpdateForTicket every 1 hour, starting from 2021-01-12 10:59:41 (UTC)
2022-06-07T11:40:23.977442300Z  INFO - ActionManager: Scheduling EmailTemplate.SendQueuedEmails every 1 minute, starting from 2015-04-10 00:00:00 (UTC)
2022-06-07T11:40:23.977856800Z  INFO - ActionManager: Scheduling DataHandling.ScE_Attributes_Reset every 1 minute, starting from 2020-09-01 00:00:01 (UTC)
2022-06-07T11:40:23.978036400Z  INFO - ActionManager: Scheduling DeepLink.CleanUp every 1 day, starting from 2010-04-20 00:00:00 (UTC)
2022-06-07T11:40:23.978436700Z  INFO - ActionManager: Scheduling EmailTemplate.Cleanup every 1 week, starting from 2015-04-10 01:00:00 (UTC)
2022-06-07T11:40:23.978731800Z  INFO - ActionManager: Scheduling Reporting.ScE_GenerateSLAReport every 15 minutes, starting from 2020-06-04 11:30:00 (UTC)
2022-06-07T11:40:23.979016200Z  INFO - ActionManager: Scheduling SubmarineMain.ScE_CheckNextStatus every 15 minutes, starting from 2022-03-01 12:45:00 (UTC)
2022-06-07T11:40:23.979177500Z  INFO - ActionManager: Scheduling EmailCustom.ScE_SendChaserMailforMSState every 1 day, starting from 2020-07-14 03:30:24 (UTC)
2022-06-07T11:40:23.979504500Z  INFO - ActionManager: Scheduling WidgetConfig.ScE_EnableNotificationBanner at 2020-12-16 18:24:07 (UTC)...
2022-06-07T11:40:23.979528600Z  WARNING - ActionManager: Couldn't create scheduled action 'WidgetConfig.ScE_EnableNotificationBanner'. Actions cannot be executed before the current moment.
2022-06-07T11:40:23.979570900Z  INFO - ActionManager: Scheduling DataHandling.ScE_Elastic_FullReindexing every 1 minute, starting from 2020-09-01 00:00:01 (UTC)
2022-06-07T11:40:23.979830300Z  INFO - ActionManager: Scheduling DataHandling.ScE_Elastic_IndexLatestChanges every 20 seconds, starting from 2022-01-01 12:00:05 (UTC)
2022-06-07T11:40:23.980038000Z  INFO - ActionManager: Scheduling DataHandling.ScE_GetUpdateSubmission every 10 seconds, starting from 2020-08-10 12:00:13 (UTC)
2022-06-07T11:40:23.980347700Z  INFO - ActionManager: Scheduling EmailCustom.ScE_SendChaserMailforMyActions every 1 day, starting from 2020-07-14 03:30:06 (UTC)
2022-06-07T11:40:23.980484000Z  INFO - ActionManager: Scheduling DataHandling.ScE_GetNextMarker every 5 seconds, starting from 2022-03-01 12:00:07 (UTC)
2022-06-07T11:40:23.980790800Z  INFO - ActionManager: Scheduling Escalation.ScE_GetAgentIds every 1 day, starting from 2020-11-03 19:31:00 (UTC)
2022-06-07T11:40:23.980989000Z  INFO - ActionManager: Scheduling DataHandling.ScE_FailedScreeningRequests_Resend every 5 minutes, starting from 2022-03-01 12:01:00 (UTC)
**2022-06-07T11:40:24.153330900Z**  INFO - Core: Mendix Runtime successfully started, the application is now available.