base_recovery.log

application/octet-stream
Filename: base_recovery.log
Type: application/octet-stream
Part: 0
Message: AW: BUG #17393: Delete database after recovery with point-in-time is still missing datafiles
[31m Di 29. Mär 10:29:44 CEST 2022 Cleanup ... [0m

[31m Di 29. Mär 10:29:45 CEST 2022 Create and start new postgres instance ... [0m
Die Dateien, die zu diesem Datenbanksystem gehören, werden dem Benutzer
»ftlorenz« gehören. Diesem Benutzer muss auch der Serverprozess gehören.

Der Datenbankcluster wird mit der Locale »de_DE.UTF-8« initialisiert werden.
Die Standarddatenbankkodierung wurde entsprechend auf »UTF8« gesetzt.
Die Standardtextsuchekonfiguration wird auf »german« gesetzt.

Datenseitenprüfsummen sind ausgeschaltet.

berichtige Zugriffsrechte des bestehenden Verzeichnisses /home/ftlorenz/pg_test/inst ... ok
erzeuge Unterverzeichnisse ... ok
wähle Implementierung von dynamischem Shared Memory ... posix
wähle Vorgabewert für max_connections ... 100
wähle Vorgabewert für shared_buffers ... 128MB
wähle Vorgabewert für Zeitzone ... Europe/Berlin
erzeuge Konfigurationsdateien ... ok
führe Bootstrap-Skript aus ... ok
führe Post-Bootstrap-Initialisierung durch ... ok
synchronisiere Daten auf Festplatte ... ok

initdb: Warnung: Authentifizierung für lokale Verbindungen auf »trust« gesetzt
Sie können dies ändern, indem Sie pg_hba.conf bearbeiten oder beim
nächsten Aufruf von initdb die Option -A, oder --auth-local und
--auth-host, verwenden.

Erfolg. Sie können den Datenbankserver jetzt mit

    pg_ctl -D /home/ftlorenz/pg_test/inst -l logdatei start

starten.

warte auf Start des Servers.... fertig
Server gestartet

[31m Di 29. Mär 10:29:51 CEST 2022 Create database test ... [0m
CREATE DATABASE

[31m Di 29. Mär 10:29:53 CEST 2022 What is the oid for datafiles ? ... [0m
 datname |  oid  
---------+-------
 test    | 16384
(1 Zeile)


[31m Di 29. Mär 10:29:53 CEST 2022 Looking for datafiles, oid in base-directory ... [0m
insgesamt 24
drwx------  6 ftlorenz ftlorenz 4096 29. Mär 10:29 .
drwx------ 19 ftlorenz ftlorenz 4096 29. Mär 10:29 ..
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 1
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 14173
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 14174
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 16384

[31m Di 29. Mär 10:29:53 CEST 2022 Create basebackup ... [0m

[31m Di 29. Mär 10:30:03 CEST 2022 saved date for Point-In-Time where database test still exists: 2022-03-29 10:30:03 [0m

[31m Di 29. Mär 10:30:03 CEST 2022 wait some seconds ... [0m

[31m Di 29. Mär 10:30:08 CEST 2022 drop the database test ... [0m
DROP DATABASE

[31m Di 29. Mär 10:30:08 CEST 2022 Switch wal to save all changes to wal-backup directory we need for complete recovery ... [0m
 pg_switch_wal 
---------------
 0/3000678
(1 Zeile)


[31m Di 29. Mär 10:30:08 CEST 2022 The datafiles of database test are deleted - ok, it was dropped [0m
insgesamt 24
drwx------  6 ftlorenz ftlorenz 4096 29. Mär 10:30 .
drwx------ 19 ftlorenz ftlorenz 4096 29. Mär 10:29 ..
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 1
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 14173
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 14174
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:29 pgsql_tmp

[31m Di 29. Mär 10:30:13 CEST 2022 Stop db ... [0m
warte auf Herunterfahren des Servers.... fertig
Server angehalten

[31m Di 29. Mär 10:30:13 CEST 2022 remove PGDATA and start recovery to 2022-03-29 10:30:03 (before deletion) ... [0m

[31m Di 29. Mär 10:30:14 CEST 2022 Check datafiles of test (before start recover with wal) ... - ok there are the datafiles of our database test [0m
insgesamt 24
drwx------  6 ftlorenz ftlorenz 4096 29. Mär 10:30 .
drwx------ 19 ftlorenz ftlorenz 4096 29. Mär 10:30 ..
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:30 1
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:30 14173
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:30 14174
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:30 16384

[31m Di 29. Mär 10:30:14 CEST 2022 Start restored instance which starts the recovery from wal files ... [0m
warte auf Start des Servers......... fertig
Server gestartet

[31m Di 29. Mär 10:30:24 CEST 2022 Show databases and try to connect to ... [0m
                                  Liste der Datenbanken
   Name    | Eigentümer | Kodierung | Sortierfolge | Zeichentyp  |  Zugriffsprivilegien  
-----------+------------+-----------+--------------+-------------+-----------------------
 postgres  | ftlorenz   | UTF8      | de_DE.UTF-8  | de_DE.UTF-8 | 
 template0 | ftlorenz   | UTF8      | de_DE.UTF-8  | de_DE.UTF-8 | =c/ftlorenz          +
           |            |           |              |             | ftlorenz=CTc/ftlorenz
 template1 | ftlorenz   | UTF8      | de_DE.UTF-8  | de_DE.UTF-8 | =c/ftlorenz          +
           |            |           |              |             | ftlorenz=CTc/ftlorenz
 test      | ftlorenz   | UTF8      | de_DE.UTF-8  | de_DE.UTF-8 | 
(4 Zeilen)

\connect: FATAL:  database "test" does not exist
DETAIL:  The database subdirectory "base/16384" is missing.

[31m Di 29. Mär 10:30:24 CEST 2022 Check datafiles of test ... - there are missing datafiles [0m
insgesamt 20
drwx------  5 ftlorenz ftlorenz 4096 29. Mär 10:30 .
drwx------ 19 ftlorenz ftlorenz 4096 29. Mär 10:30 ..
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:30 1
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:30 14173
drwx------  2 ftlorenz ftlorenz 4096 29. Mär 10:30 14174

[31m Di 29. Mär 10:30:24 CEST 2022 Check the logs what's happened ... [0m
2022-03-29 10:29:51.535 CEST [15087] LOG:  redirecting log output to logging collector process
2022-03-29 10:29:51.535 CEST [15087] HINT:  Future log output will appear in directory "/home/ftlorenz/pg_test/log_dir".
2022-03-29 10:30:14.223 CEST [15167] LOG:  redirecting log output to logging collector process
2022-03-29 10:30:14.223 CEST [15167] HINT:  Future log output will appear in directory "/home/ftlorenz/pg_test/log_dir".
2022-03-29 10:29:51.535 CEST [15087] LOG:  starting PostgreSQL 13.6 on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-44), 64-bit
2022-03-29 10:29:51.536 CEST [15087] LOG:  listening on IPv4 address "127.0.0.1", port 5555
2022-03-29 10:29:51.542 CEST [15087] LOG:  listening on Unix socket "/home/ftlorenz/pg_test/run/.s.PGSQL.5555"
2022-03-29 10:29:51.552 CEST [15089] LOG:  database system was shut down at 2022-03-29 10:29:48 CEST
2022-03-29 10:29:51.559 CEST [15087] LOG:  database system is ready to accept connections
2022-03-29 10:30:13.804 CEST [15087] LOG:  received fast shutdown request
2022-03-29 10:30:13.806 CEST [15087] LOG:  aborting any active transactions
2022-03-29 10:30:13.809 CEST [15087] LOG:  background worker "logical replication launcher" (PID 15096) exited with exit code 1
2022-03-29 10:30:13.810 CEST [15090] LOG:  shutting down
2022-03-29 10:30:13.842 CEST [15087] LOG:  database system is shut down
2022-03-29 10:30:14.223 CEST [15167] LOG:  starting PostgreSQL 13.6 on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-44), 64-bit
2022-03-29 10:30:14.224 CEST [15167] LOG:  listening on IPv4 address "127.0.0.1", port 5555
2022-03-29 10:30:14.230 CEST [15167] LOG:  listening on Unix socket "/home/ftlorenz/pg_test/run/.s.PGSQL.5555"
2022-03-29 10:30:14.244 CEST [15169] LOG:  database system was interrupted; last known up at 2022-03-29 10:29:53 CEST
cp: cannot stat ‘/home/ftlorenz/pg_test/wal_backup/00000002.history’: No such file or directory
2022-03-29 10:30:19.595 CEST [15169] LOG:  starting point-in-time recovery to 2022-03-29 10:30:03+02
2022-03-29 10:30:19.617 CEST [15169] LOG:  restored log file "000000010000000000000002" from archive
2022-03-29 10:30:19.655 CEST [15169] LOG:  redo starts at 0/2000028
2022-03-29 10:30:19.663 CEST [15169] LOG:  consistent recovery state reached at 0/2000100
2022-03-29 10:30:19.664 CEST [15167] LOG:  database system is ready to accept read only connections
2022-03-29 10:30:19.686 CEST [15169] LOG:  restored log file "000000010000000000000003" from archive
2022-03-29 10:30:19.727 CEST [15169] LOG:  recovery stopping before commit of transaction 486, time 2022-03-29 10:30:08.739965+02
2022-03-29 10:30:19.727 CEST [15169] LOG:  pausing at the end of recovery
2022-03-29 10:30:19.727 CEST [15169] HINT:  Execute pg_wal_replay_resume() to promote.
2022-03-29 10:30:24.761 CEST [15664] FATAL:  database "test" does not exist
2022-03-29 10:30:24.761 CEST [15664] DETAIL:  The database subdirectory "base/16384" is missing.

[31m Di 29. Mär 10:30:24 CEST 2022 Postgresql knows the Database test but don't have the datafiles anymore. [0m