Deployment of 2.0.6 on Northflank

[tlodge]

Hi.

Any help or pointers appreciated. I have been trying to deploy a 2.0.6 version of vendure on Northflank. I have had no problems deploying 1.9.3 using: https://github.com/vendure-ecommerce/one-click-deploy.

However when I try and do the same with a 2.0.6 repo (created using @vendure/create) - see: https://github.com/tlodge/one-click-jb, the services fail to start.

It looks like there is an issue populating the database; I get the following logs, which suggest that the system is timing out creating the initial database schema:

2023-08-22T08:07:03.668022576Z stdout F [2023-08-22T08:07:03Z INFO ] Process terminated with exit code 0

[..bunch of additional table query errors]

2023-08-22T08:06:53.345656762Z stdout F QueryFailedError: relation "public.zone" does not exist
2023-08-22T08:06:53.332892295Z stdout F at async callModuleInitHook (/usr/src/app/node_modules/@nestjs/core/hooks/on-module-init.hook.js:43:5)
2023-08-22T08:06:53.332889974Z stdout F at async Promise.all (index 1)
2023-08-22T08:06:53.332887355Z stdout F at async InitializerService.onModuleInit (/usr/src/app/node_modules/@vendure/core/dist/service/initializer.service.js:47:9)
2023-08-22T08:06:53.332885524Z stdout F at async InitializerService.awaitDbSchemaGeneration (/usr/src/app/node_modules/@vendure/core/dist/service/initializer.service.js:79:32)
2023-08-22T08:06:53.332883618Z stdout F at async SelectQueryBuilder.getMany (/usr/src/app/node_modules/typeorm/query-builder/SelectQueryBuilder.js:733:25)
2023-08-22T08:06:53.332881701Z stdout F at async SelectQueryBuilder.getRawAndEntities (/usr/src/app/node_modules/typeorm/query-builder/SelectQueryBuilder.js:667:29)
2023-08-22T08:06:53.332879765Z stdout F at async SelectQueryBuilder.executeEntitiesAndRawResults (/usr/src/app/node_modules/typeorm/query-builder/SelectQueryBuilder.js:1992:26)
2023-08-22T08:06:53.332877379Z stdout F at async SelectQueryBuilder.loadRawResults (/usr/src/app/node_modules/typeorm/query-builder/SelectQueryBuilder.js:2132:25)
2023-08-22T08:06:53.33287529Z stdout F at processTicksAndRejections (node:internal/process/task_queues:96:5)
2023-08-22T08:06:53.332872743Z stdout F at PostgresQueryRunner.query (/usr/src/app/node_modules/typeorm/driver/postgres/PostgresQueryRunner.js:211:19)

2023-08-22T08:06:53.332868861Z stdout F QueryFailedError: relation "public.administrator" does not exist
2023-08-22T08:06:53.332829838Z stdout F error 8/22/23, 8:06 AM - [Vendure Server] Timed out when awaiting the DB schema to be ready!
2023-08-22T08:06:53.227070734Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 18)
2023-08-22T08:06:53.123095634Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 17)
2023-08-22T08:06:53.019472792Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 16)
2023-08-22T08:06:52.916530558Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 15)
2023-08-22T08:06:52.812678081Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 14)
2023-08-22T08:06:52.709250848Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 13)
2023-08-22T08:06:52.605825073Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 12)
2023-08-22T08:06:52.502302198Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 11)
2023-08-22T08:06:52.398251183Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 10)
2023-08-22T08:06:52.295256197Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 9)
2023-08-22T08:06:52.191923896Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 8)
2023-08-22T08:06:52.088099536Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 7)
2023-08-22T08:06:51.984401525Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 6)
2023-08-22T08:06:51.881710427Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 5)
2023-08-22T08:06:51.778250047Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 4)
2023-08-22T08:06:51.674588141Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 3)
2023-08-22T08:06:51.571123538Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 2)
2023-08-22T08:06:51.466680134Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 1)
2023-08-22T08:06:51.36333443Z stdout F warn 8/22/23, 8:06 AM - [Vendure Server] Awaiting DB schema creation... (attempt 0)
2023-08-22T08:06:51.0720155Z stdout F info 8/22/23, 8:06 AM - [RouterExplorer] Mapped {/health, GET} route
2023-08-22T08:06:51.069892689Z stdout F info 8/22/23, 8:06 AM - [RoutesResolver] HealthController {/health}:
2023-08-22T08:06:50.863068847Z stdout F info 8/22/23, 8:06 AM - [EmailPlugin] Creating dev mailbox middleware
2023-08-22T08:06:50.862078919Z stdout F info 8/22/23, 8:06 AM - [AssetServerPlugin] Creating asset server middleware
2023-08-22T08:06:50.769764341Z stdout F info 8/22/23, 8:06 AM - [AdminUiPlugin] Creating admin ui middleware (prod mode)
2023-08-22T08:06:11.468420368Z stdout F info 8/22/23, 8:06 AM - [Vendure Server] Bootstrapping Vendure Server (pid: 26)...

[.. bunch of SQL commands for creating / altering vendure tables]

2023-08-22T08:06:11.267574453Z stdout F Your database schema does not match your current configuration. Generate a new migration for the following changes:
2023-08-22T08:05:15.869313192Z stdout F $ node ./dist/index.js
2023-08-22T08:05:15.168549764Z stdout F yarn run v1.22.19
2023-08-22T08:05:07.923502086Z stdout F [2023-08-22T08:05:07Z INFO ] Starting container entrypoint...
2023-08-22T08:05:07.923071661Z stdout F [2023-08-22T08:05:07Z INFO ] Successfully fetched environment variables.
2023-08-22T08:05:07.832794138Z stdout F [2023-08-22T08:05:07Z INFO ] Securely fetching environment variables...
2023-08-22T08:05:09.33446448Z stdout F [2023-08-22T08:05:09Z INFO ] Process terminated.
2023-08-22T08:05:09.139902934Z stdout F [2023-08-22T08:05:09Z INFO ] Starting container entrypoint...
2023-08-22T08:05:09.13933743Z stdout F [2023-08-22T08:05:09Z INFO ] Successfully fetched environment variables.
2023-08-22T08:05:09.040002724Z stdout F [2023-08-22T08:05:08Z INFO ] Securely fetching environment variables...
`

When I look at the database that has been generated, all I see is:

Schema | Name | Type | Owner | Persistence | Access method | Size | Description --------+------------+-------+-------------------+-------------+---------------+------------+------------- public | migrations | table |[myownerid] | permanent | heap | 8192 bytes | (1 row)

I'm not sure if this is a timeout/race condition - perhaps the database isn't getting created as quickly as needed? I have tried different supported Postgres versions on Northflank, but I get the same issue. It would be great to get this working so any advice/help gratefully received!

Incidentally the same repo works fine on my local machine using a local (dockerised) version of postgres.

[michaelbromley]

Hi!

To me this looks like it might be basically a race condition - the server and worker have some built-in logic where it will wait until the database schema is generated, see here.

However, by default it will retry 20x at 100ms intervals, for a total of 2s. It looks like due to the low resources of the Northflank free tier, this schema creation step is taking too long and timing out.

[tlodge]

Hi Michael. I wondered if it might be that, and thanks for the info; I'll try an upgrade.