🐛 Postgresus restarts during scheduled backups #444

Closed
opened 2026-04-05 16:16:29 +02:00 by MrUnknownDE · 0 comments
Owner

Originally created by @yuntian3008 on 11/1/2025

Description
When the hourly backup starts, the Postgresus container randomly restarts, causing the backup to fail with:

Backup failed due to application restart

After restart, the retry finishes successfully.

Example
At 12:49, the backup triggered → container restarted → backup failed → retried → succeeded.

Expected
Scheduled backups should complete without restarting the application.

Environment

  • Database: PostgreSQL 16
  • Docker image version: 1.23.0

Logs

2025-11-01T12:43:58.671447419Z 2025-11-01 12:43:58.670 UTC [20] LOG:  checkpoint starting: time
2025-11-01T12:43:59.385474057Z 2025-11-01 12:43:59.385 UTC [20] LOG:  checkpoint complete: wrote 8 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.705 s, sync=0.003 s, total=0.715 s; sync files=5, longest=0.002 s, average=0.001 s; distance=32 kB, estimate=52 kB; lsn=0/1B74B50, redo lsn=0/1B74AF8
2025-11-01T12:48:58.478295009Z 2025-11-01 12:48:58.477 UTC [20] LOG:  checkpoint starting: time
2025-11-01T12:48:59.201251744Z 2025-11-01 12:48:59.200 UTC [20] LOG:  checkpoint complete: wrote 8 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.708 s, sync=0.003 s, total=0.724 s; sync files=5, longest=0.003 s, average=0.001 s; distance=34 kB, estimate=50 kB; lsn=0/1B7D490, redo lsn=0/1B7D438
2025-11-01T12:49:02.058056445Z time="2025/11/01 12:49:02" msg="Triggering scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 intervalType=HOURLY
2025-11-01T12:49:02.058574106Z time="2025/11/01 12:49:02" msg="Successfully triggered scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966
2025-11-01T12:49:02.083728033Z time="2025/11/01 12:49:02" msg="Creating PostgreSQL backup via pg_dump custom format" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 storageId=66272e3a-68e5-4d0e-8b3f-f84ca193e36a
2025-11-01T12:49:02.084059212Z time="2025/11/01 12:49:02" msg="Using zstd compression level 5" version=16
2025-11-01T12:49:02.084481441Z time="2025/11/01 12:49:02" msg="Streaming PostgreSQL backup to storage" pgBin=/usr/lib/postgresql/16/bin/pg_dump args="[-Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5]"
2025-11-01T12:49:02.085559084Z time="2025/11/01 12:49:02" msg="Temporary .pgpass file created successfully" pgpassFile=/tmp/pgpass1845245955/.pgpass size=43 mode=-rw-------
2025-11-01T12:49:02.086810195Z time="2025/11/01 12:49:02" msg="Executing PostgreSQL backup command" command="/usr/lib/postgresql/16/bin/pg_dump -Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5"
2025-11-01T12:49:02.087101776Z time="2025/11/01 12:49:02" msg="Using temporary .pgpass file for authentication" pgpassFile=/tmp/pgpass1845245955/.pgpass
2025-11-01T12:49:02.087510815Z time="2025/11/01 12:49:02" msg="Setting up PostgreSQL environment" passwordLength=28 passwordEmpty=false pgBin=/usr/lib/postgresql/16/bin/pg_dump usingPgpassFile=true parallelJobs=1
2025-11-01T12:49:02.087819188Z time="2025/11/01 12:49:02" msg="Using preferred SSL mode" configuredHttps=false
2025-11-01T12:49:06.981472519Z Setting up data directory permissions...
2025-11-01T12:49:07.195927414Z Starting PostgreSQL...
2025-11-01T12:49:07.195959060Z Waiting for PostgreSQL to be ready...
2025-11-01T12:49:07.462459977Z 2025-11-01 12:49:07.462 UTC [9] LOG:  starting PostgreSQL 17.6 (Debian 17.6-2.pgdg12+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 12.2.0-14+deb12u1) 12.2.0, 64-bit
2025-11-01T12:49:07.463286400Z 2025-11-01 12:49:07.463 UTC [9] LOG:  listening on IPv6 address "::1", port 5437
2025-11-01T12:49:07.463564271Z 2025-11-01 12:49:07.463 UTC [9] LOG:  listening on IPv4 address "127.0.0.1", port 5437
2025-11-01T12:49:07.468473491Z 2025-11-01 12:49:07.468 UTC [9] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5437"
2025-11-01T12:49:07.479963623Z 2025-11-01 12:49:07.479 UTC [22] LOG:  database system was interrupted; last known up at 2025-11-01 12:48:59 UTC
2025-11-01T12:49:07.628930331Z 2025-11-01 12:49:07.628 UTC [22] LOG:  database system was not properly shut down; automatic recovery in progress
2025-11-01T12:49:07.632926212Z 2025-11-01 12:49:07.632 UTC [22] LOG:  redo starts at 0/1B7D438
2025-11-01T12:49:07.635589075Z 2025-11-01 12:49:07.635 UTC [22] LOG:  invalid record length at 0/1B89920: expected at least 24, got 0
2025-11-01T12:49:07.635722646Z 2025-11-01 12:49:07.635 UTC [22] LOG:  redo done at 0/1B898F8 system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2025-11-01T12:49:07.641025877Z 2025-11-01 12:49:07.640 UTC [20] LOG:  checkpoint starting: end-of-recovery immediate wait
2025-11-01T12:49:07.651829127Z 2025-11-01 12:49:07.651 UTC [20] LOG:  checkpoint complete: wrote 16 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.004 s, sync=0.003 s, total=0.013 s; sync files=13, longest=0.002 s, average=0.001 s; distance=49 kB, estimate=49 kB; lsn=0/1B89920, redo lsn=0/1B89920
2025-11-01T12:49:07.662714515Z 2025-11-01 12:49:07.662 UTC [9] LOG:  database system is ready to accept connections
2025-11-01T12:49:08.403792287Z PostgreSQL is ready!
2025-11-01T12:49:08.404142047Z Setting up database and user...
2025-11-01T12:49:08.487283090Z ALTER ROLE
2025-11-01T12:49:08.506985730Z Starting Postgresus application...
2025-11-01T12:49:08.635829691Z time="2025/11/01 12:49:08" msg="Text structured logger initialized"
2025-11-01T12:49:08.715547971Z time="2025/11/01 12:49:08" msg="Running database migrations..."
2025-11-01T12:49:08.717574632Z time="2025/11/01 12:49:08" msg="Trying to load .env" path=/app/.env
2025-11-01T12:49:08.718632737Z time="2025/11/01 12:49:08" msg="Successfully loaded .env" path=/app/.env
2025-11-01T12:49:08.718961246Z time="2025/11/01 12:49:08" msg="ENV_MODE loaded" mode=production
2025-11-01T12:49:08.719169691Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=13 path=/usr/lib/postgresql/13/bin
2025-11-01T12:49:08.720182813Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=13 path=/usr/lib/postgresql/13/bin/pg_dump
2025-11-01T12:49:08.720872384Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=13
2025-11-01T12:49:08.721171319Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=13 path=/usr/lib/postgresql/13/bin/psql
2025-11-01T12:49:08.721502850Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=13
2025-11-01T12:49:08.721771932Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=13 path=/usr/lib/postgresql/13/bin
2025-11-01T12:49:08.722059605Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=14 path=/usr/lib/postgresql/14/bin
2025-11-01T12:49:08.722802470Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=14 path=/usr/lib/postgresql/14/bin/pg_dump
2025-11-01T12:49:08.724046063Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=14
2025-11-01T12:49:08.724355987Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=14 path=/usr/lib/postgresql/14/bin/psql
2025-11-01T12:49:08.724764213Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=14
2025-11-01T12:49:08.725050988Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=14 path=/usr/lib/postgresql/14/bin
2025-11-01T12:49:08.725393563Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=15 path=/usr/lib/postgresql/15/bin
2025-11-01T12:49:08.726309001Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=15 path=/usr/lib/postgresql/15/bin/pg_dump
2025-11-01T12:49:08.727024128Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=15
2025-11-01T12:49:08.727358251Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=15 path=/usr/lib/postgresql/15/bin/psql
2025-11-01T12:49:08.727728375Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=15
2025-11-01T12:49:08.727966600Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=15 path=/usr/lib/postgresql/15/bin
2025-11-01T12:49:08.728202981Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=16 path=/usr/lib/postgresql/16/bin
2025-11-01T12:49:08.728708101Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=16 path=/usr/lib/postgresql/16/bin/pg_dump
2025-11-01T12:49:08.729070144Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=16
2025-11-01T12:49:08.729391279Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=16 path=/usr/lib/postgresql/16/bin/psql
2025-11-01T12:49:08.730168107Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=16
2025-11-01T12:49:08.730503853Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=16 path=/usr/lib/postgresql/16/bin
2025-11-01T12:49:08.730744717Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=17 path=/usr/lib/postgresql/17/bin
2025-11-01T12:49:08.731029671Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=17 path=/usr/lib/postgresql/17/bin/pg_dump
2025-11-01T12:49:08.731428419Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=17
2025-11-01T12:49:08.731667451Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=17 path=/usr/lib/postgresql/17/bin/psql
2025-11-01T12:49:08.731933219Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=17
2025-11-01T12:49:08.732166653Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=17 path=/usr/lib/postgresql/17/bin
2025-11-01T12:49:08.732499034Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=18 path=/usr/lib/postgresql/18/bin
2025-11-01T12:49:08.733294477Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=18 path=/usr/lib/postgresql/18/bin/pg_dump
2025-11-01T12:49:08.734123753Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=18
2025-11-01T12:49:08.734473535Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=18 path=/usr/lib/postgresql/18/bin/psql
2025-11-01T12:49:08.734810814Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=18
2025-11-01T12:49:08.735050915Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=18 path=/usr/lib/postgresql/18/bin
2025-11-01T12:49:08.735387949Z time="2025/11/01 12:49:08" msg="All PostgreSQL version-specific client tools verification completed successfully!"
2025-11-01T12:49:08.735634130Z time="2025/11/01 12:49:08" msg="Environment variables loaded successfully!"
2025-11-01T12:49:08.985345156Z time="2025/11/01 12:49:08" msg="Database migrations completed successfully" output="2025/11/01 12:49:08 goose: no migrations to run. current version: 20250912092352\n"
2025-11-01T12:49:08.986388046Z time="2025/11/01 12:49:08" msg="Preparing to run background tasks..."
2025-11-01T12:49:08.991138751Z time="2025/11/01 12:49:08" msg="Connection to database..."
2025-11-01T12:49:09.014313806Z time="2025/11/01 12:49:09" msg="Main database connected successfully!"
2025-11-01T12:49:10.363201007Z time="2025/11/01 12:49:10" msg="Triggering scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 intervalType=HOURLY
2025-11-01T12:49:10.364918830Z time="2025/11/01 12:49:10" msg="Successfully triggered scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966
2025-11-01T12:49:10.397650257Z time="2025/11/01 12:49:10" msg="Creating PostgreSQL backup via pg_dump custom format" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 storageId=66272e3a-68e5-4d0e-8b3f-f84ca193e36a
2025-11-01T12:49:10.398003302Z time="2025/11/01 12:49:10" msg="Using zstd compression level 5" version=16
2025-11-01T12:49:10.398325869Z time="2025/11/01 12:49:10" msg="Streaming PostgreSQL backup to storage" pgBin=/usr/lib/postgresql/16/bin/pg_dump args="[-Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5]"
2025-11-01T12:49:10.398941437Z time="2025/11/01 12:49:10" msg="Temporary .pgpass file created successfully" pgpassFile=/tmp/pgpass4096126573/.pgpass size=43 mode=-rw-------
2025-11-01T12:49:10.399210823Z time="2025/11/01 12:49:10" msg="Executing PostgreSQL backup command" command="/usr/lib/postgresql/16/bin/pg_dump -Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5"
2025-11-01T12:49:10.399539622Z time="2025/11/01 12:49:10" msg="Using temporary .pgpass file for authentication" pgpassFile=/tmp/pgpass4096126573/.pgpass
2025-11-01T12:49:10.399846461Z time="2025/11/01 12:49:10" msg="Setting up PostgreSQL environment" passwordLength=28 passwordEmpty=false pgBin=/usr/lib/postgresql/16/bin/pg_dump usingPgpassFile=true parallelJobs=1
2025-11-01T12:49:10.400126148Z time="2025/11/01 12:49:10" msg="Using preferred SSL mode" configuredHttps=false


Image
*Originally created by @yuntian3008 on 11/1/2025* **Description** When the hourly backup starts, the Postgresus container randomly restarts, causing the backup to fail with: > `Backup failed due to application restart` After restart, the retry finishes successfully. **Example** At `12:49`, the backup triggered → container restarted → backup failed → retried → succeeded. **Expected** Scheduled backups should complete without restarting the application. **Environment** * Database: PostgreSQL 16 * Docker image version: 1.23.0 **Logs** ``` 2025-11-01T12:43:58.671447419Z 2025-11-01 12:43:58.670 UTC [20] LOG: checkpoint starting: time 2025-11-01T12:43:59.385474057Z 2025-11-01 12:43:59.385 UTC [20] LOG: checkpoint complete: wrote 8 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.705 s, sync=0.003 s, total=0.715 s; sync files=5, longest=0.002 s, average=0.001 s; distance=32 kB, estimate=52 kB; lsn=0/1B74B50, redo lsn=0/1B74AF8 2025-11-01T12:48:58.478295009Z 2025-11-01 12:48:58.477 UTC [20] LOG: checkpoint starting: time 2025-11-01T12:48:59.201251744Z 2025-11-01 12:48:59.200 UTC [20] LOG: checkpoint complete: wrote 8 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.708 s, sync=0.003 s, total=0.724 s; sync files=5, longest=0.003 s, average=0.001 s; distance=34 kB, estimate=50 kB; lsn=0/1B7D490, redo lsn=0/1B7D438 2025-11-01T12:49:02.058056445Z time="2025/11/01 12:49:02" msg="Triggering scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 intervalType=HOURLY 2025-11-01T12:49:02.058574106Z time="2025/11/01 12:49:02" msg="Successfully triggered scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 2025-11-01T12:49:02.083728033Z time="2025/11/01 12:49:02" msg="Creating PostgreSQL backup via pg_dump custom format" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 storageId=66272e3a-68e5-4d0e-8b3f-f84ca193e36a 2025-11-01T12:49:02.084059212Z time="2025/11/01 12:49:02" msg="Using zstd compression level 5" version=16 2025-11-01T12:49:02.084481441Z time="2025/11/01 12:49:02" msg="Streaming PostgreSQL backup to storage" pgBin=/usr/lib/postgresql/16/bin/pg_dump args="[-Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5]" 2025-11-01T12:49:02.085559084Z time="2025/11/01 12:49:02" msg="Temporary .pgpass file created successfully" pgpassFile=/tmp/pgpass1845245955/.pgpass size=43 mode=-rw------- 2025-11-01T12:49:02.086810195Z time="2025/11/01 12:49:02" msg="Executing PostgreSQL backup command" command="/usr/lib/postgresql/16/bin/pg_dump -Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5" 2025-11-01T12:49:02.087101776Z time="2025/11/01 12:49:02" msg="Using temporary .pgpass file for authentication" pgpassFile=/tmp/pgpass1845245955/.pgpass 2025-11-01T12:49:02.087510815Z time="2025/11/01 12:49:02" msg="Setting up PostgreSQL environment" passwordLength=28 passwordEmpty=false pgBin=/usr/lib/postgresql/16/bin/pg_dump usingPgpassFile=true parallelJobs=1 2025-11-01T12:49:02.087819188Z time="2025/11/01 12:49:02" msg="Using preferred SSL mode" configuredHttps=false 2025-11-01T12:49:06.981472519Z Setting up data directory permissions... 2025-11-01T12:49:07.195927414Z Starting PostgreSQL... 2025-11-01T12:49:07.195959060Z Waiting for PostgreSQL to be ready... 2025-11-01T12:49:07.462459977Z 2025-11-01 12:49:07.462 UTC [9] LOG: starting PostgreSQL 17.6 (Debian 17.6-2.pgdg12+1) on x86_64-pc-linux-gnu, compiled by gcc (Debian 12.2.0-14+deb12u1) 12.2.0, 64-bit 2025-11-01T12:49:07.463286400Z 2025-11-01 12:49:07.463 UTC [9] LOG: listening on IPv6 address "::1", port 5437 2025-11-01T12:49:07.463564271Z 2025-11-01 12:49:07.463 UTC [9] LOG: listening on IPv4 address "127.0.0.1", port 5437 2025-11-01T12:49:07.468473491Z 2025-11-01 12:49:07.468 UTC [9] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5437" 2025-11-01T12:49:07.479963623Z 2025-11-01 12:49:07.479 UTC [22] LOG: database system was interrupted; last known up at 2025-11-01 12:48:59 UTC 2025-11-01T12:49:07.628930331Z 2025-11-01 12:49:07.628 UTC [22] LOG: database system was not properly shut down; automatic recovery in progress 2025-11-01T12:49:07.632926212Z 2025-11-01 12:49:07.632 UTC [22] LOG: redo starts at 0/1B7D438 2025-11-01T12:49:07.635589075Z 2025-11-01 12:49:07.635 UTC [22] LOG: invalid record length at 0/1B89920: expected at least 24, got 0 2025-11-01T12:49:07.635722646Z 2025-11-01 12:49:07.635 UTC [22] LOG: redo done at 0/1B898F8 system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s 2025-11-01T12:49:07.641025877Z 2025-11-01 12:49:07.640 UTC [20] LOG: checkpoint starting: end-of-recovery immediate wait 2025-11-01T12:49:07.651829127Z 2025-11-01 12:49:07.651 UTC [20] LOG: checkpoint complete: wrote 16 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.004 s, sync=0.003 s, total=0.013 s; sync files=13, longest=0.002 s, average=0.001 s; distance=49 kB, estimate=49 kB; lsn=0/1B89920, redo lsn=0/1B89920 2025-11-01T12:49:07.662714515Z 2025-11-01 12:49:07.662 UTC [9] LOG: database system is ready to accept connections 2025-11-01T12:49:08.403792287Z PostgreSQL is ready! 2025-11-01T12:49:08.404142047Z Setting up database and user... 2025-11-01T12:49:08.487283090Z ALTER ROLE 2025-11-01T12:49:08.506985730Z Starting Postgresus application... 2025-11-01T12:49:08.635829691Z time="2025/11/01 12:49:08" msg="Text structured logger initialized" 2025-11-01T12:49:08.715547971Z time="2025/11/01 12:49:08" msg="Running database migrations..." 2025-11-01T12:49:08.717574632Z time="2025/11/01 12:49:08" msg="Trying to load .env" path=/app/.env 2025-11-01T12:49:08.718632737Z time="2025/11/01 12:49:08" msg="Successfully loaded .env" path=/app/.env 2025-11-01T12:49:08.718961246Z time="2025/11/01 12:49:08" msg="ENV_MODE loaded" mode=production 2025-11-01T12:49:08.719169691Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=13 path=/usr/lib/postgresql/13/bin 2025-11-01T12:49:08.720182813Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=13 path=/usr/lib/postgresql/13/bin/pg_dump 2025-11-01T12:49:08.720872384Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=13 2025-11-01T12:49:08.721171319Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=13 path=/usr/lib/postgresql/13/bin/psql 2025-11-01T12:49:08.721502850Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=13 2025-11-01T12:49:08.721771932Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=13 path=/usr/lib/postgresql/13/bin 2025-11-01T12:49:08.722059605Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=14 path=/usr/lib/postgresql/14/bin 2025-11-01T12:49:08.722802470Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=14 path=/usr/lib/postgresql/14/bin/pg_dump 2025-11-01T12:49:08.724046063Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=14 2025-11-01T12:49:08.724355987Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=14 path=/usr/lib/postgresql/14/bin/psql 2025-11-01T12:49:08.724764213Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=14 2025-11-01T12:49:08.725050988Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=14 path=/usr/lib/postgresql/14/bin 2025-11-01T12:49:08.725393563Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=15 path=/usr/lib/postgresql/15/bin 2025-11-01T12:49:08.726309001Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=15 path=/usr/lib/postgresql/15/bin/pg_dump 2025-11-01T12:49:08.727024128Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=15 2025-11-01T12:49:08.727358251Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=15 path=/usr/lib/postgresql/15/bin/psql 2025-11-01T12:49:08.727728375Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=15 2025-11-01T12:49:08.727966600Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=15 path=/usr/lib/postgresql/15/bin 2025-11-01T12:49:08.728202981Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=16 path=/usr/lib/postgresql/16/bin 2025-11-01T12:49:08.728708101Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=16 path=/usr/lib/postgresql/16/bin/pg_dump 2025-11-01T12:49:08.729070144Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=16 2025-11-01T12:49:08.729391279Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=16 path=/usr/lib/postgresql/16/bin/psql 2025-11-01T12:49:08.730168107Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=16 2025-11-01T12:49:08.730503853Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=16 path=/usr/lib/postgresql/16/bin 2025-11-01T12:49:08.730744717Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=17 path=/usr/lib/postgresql/17/bin 2025-11-01T12:49:08.731029671Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=17 path=/usr/lib/postgresql/17/bin/pg_dump 2025-11-01T12:49:08.731428419Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=17 2025-11-01T12:49:08.731667451Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=17 path=/usr/lib/postgresql/17/bin/psql 2025-11-01T12:49:08.731933219Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=17 2025-11-01T12:49:08.732166653Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=17 path=/usr/lib/postgresql/17/bin 2025-11-01T12:49:08.732499034Z time="2025/11/01 12:49:08" msg="Verifying PostgreSQL installation" version=18 path=/usr/lib/postgresql/18/bin 2025-11-01T12:49:08.733294477Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=pg_dump version=18 path=/usr/lib/postgresql/18/bin/pg_dump 2025-11-01T12:49:08.734123753Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=pg_dump version=18 2025-11-01T12:49:08.734473535Z time="2025/11/01 12:49:08" msg="Checking for PostgreSQL command" command=psql version=18 path=/usr/lib/postgresql/18/bin/psql 2025-11-01T12:49:08.734810814Z time="2025/11/01 12:49:08" msg="PostgreSQL command found" command=psql version=18 2025-11-01T12:49:08.735050915Z time="2025/11/01 12:49:08" msg="Installation of PostgreSQL verified" version=18 path=/usr/lib/postgresql/18/bin 2025-11-01T12:49:08.735387949Z time="2025/11/01 12:49:08" msg="All PostgreSQL version-specific client tools verification completed successfully!" 2025-11-01T12:49:08.735634130Z time="2025/11/01 12:49:08" msg="Environment variables loaded successfully!" 2025-11-01T12:49:08.985345156Z time="2025/11/01 12:49:08" msg="Database migrations completed successfully" output="2025/11/01 12:49:08 goose: no migrations to run. current version: 20250912092352\n" 2025-11-01T12:49:08.986388046Z time="2025/11/01 12:49:08" msg="Preparing to run background tasks..." 2025-11-01T12:49:08.991138751Z time="2025/11/01 12:49:08" msg="Connection to database..." 2025-11-01T12:49:09.014313806Z time="2025/11/01 12:49:09" msg="Main database connected successfully!" 2025-11-01T12:49:10.363201007Z time="2025/11/01 12:49:10" msg="Triggering scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 intervalType=HOURLY 2025-11-01T12:49:10.364918830Z time="2025/11/01 12:49:10" msg="Successfully triggered scheduled backup" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 2025-11-01T12:49:10.397650257Z time="2025/11/01 12:49:10" msg="Creating PostgreSQL backup via pg_dump custom format" databaseId=68b70999-984d-4fc2-827e-f59fe7450966 storageId=66272e3a-68e5-4d0e-8b3f-f84ca193e36a 2025-11-01T12:49:10.398003302Z time="2025/11/01 12:49:10" msg="Using zstd compression level 5" version=16 2025-11-01T12:49:10.398325869Z time="2025/11/01 12:49:10" msg="Streaming PostgreSQL backup to storage" pgBin=/usr/lib/postgresql/16/bin/pg_dump args="[-Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5]" 2025-11-01T12:49:10.398941437Z time="2025/11/01 12:49:10" msg="Temporary .pgpass file created successfully" pgpassFile=/tmp/pgpass4096126573/.pgpass size=43 mode=-rw------- 2025-11-01T12:49:10.399210823Z time="2025/11/01 12:49:10" msg="Executing PostgreSQL backup command" command="/usr/lib/postgresql/16/bin/pg_dump -Fc --no-password -h pg -p 5432 -U ente -d ente_db --verbose --compress=zstd:5" 2025-11-01T12:49:10.399539622Z time="2025/11/01 12:49:10" msg="Using temporary .pgpass file for authentication" pgpassFile=/tmp/pgpass4096126573/.pgpass 2025-11-01T12:49:10.399846461Z time="2025/11/01 12:49:10" msg="Setting up PostgreSQL environment" passwordLength=28 passwordEmpty=false pgBin=/usr/lib/postgresql/16/bin/pg_dump usingPgpassFile=true parallelJobs=1 2025-11-01T12:49:10.400126148Z time="2025/11/01 12:49:10" msg="Using preferred SSL mode" configuredHttps=false ``` --- <img width="261" height="577" alt="Image" src="https://github.com/user-attachments/assets/8c5a9b3d-a451-49dc-a9de-c0a406e944ff" />
Sign in to join this conversation.
1 Participants
Notifications
Due Date
No due date set.
Dependencies

No dependencies set.

Reference: github/databasus#444