server_start_20240209T115112.963.log

application/octet-stream

Filename: server_start_20240209T115112.963.log
Type: application/octet-stream
Part: 2
Message: RE: speed up a logical replica setup
2024-02-09 11:52:13.242 UTC [3909] LOG:  starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 8.3.1 20190311 (Red Hat 8.3.1-3), 64-bit
2024-02-09 11:52:13.243 UTC [3909] LOG:  listening on IPv6 address "::1", port 5432
2024-02-09 11:52:13.243 UTC [3909] LOG:  listening on IPv4 address "127.0.0.1", port 5432
2024-02-09 11:52:13.246 UTC [3909] LOG:  listening on Unix socket "/tmp/.s.PGSQL.5432"
2024-02-09 11:52:13.252 UTC [3912] LOG:  database system was shut down in recovery at 2024-02-09 11:51:53 UTC
2024-02-09 11:52:13.252 UTC [3912] LOG:  entering standby mode
2024-02-09 11:52:13.256 UTC [3912] LOG:  redo starts at 0/2000028
2024-02-09 11:52:13.257 UTC [3912] LOG:  consistent recovery state reached at 0/3001800
2024-02-09 11:52:13.257 UTC [3912] LOG:  invalid record length at 0/3001800: expected at least 24, got 0
2024-02-09 11:52:13.257 UTC [3909] LOG:  database system is ready to accept read-only connections
2024-02-09 11:52:13.264 UTC [3913] LOG:  started streaming WAL from primary at 0/3000000 on timeline 1
2024-02-09 11:52:28.513 UTC [3912] LOG:  recovery stopping after WAL location (LSN) "0/30033C8"
2024-02-09 11:52:28.513 UTC [3912] LOG:  redo done at 0/30033C8 system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 15.25 s
2024-02-09 11:52:28.513 UTC [3912] LOG:  last completed transaction was at log time 2024-02-09 11:51:42.822078+00
2024-02-09 11:52:28.513 UTC [3913] FATAL:  terminating walreceiver process due to administrator command
2024-02-09 11:52:28.516 UTC [3912] LOG:  selected new timeline ID: 2
2024-02-09 11:52:28.562 UTC [3912] LOG:  archive recovery complete
2024-02-09 11:52:28.564 UTC [3910] LOG:  checkpoint starting: end-of-recovery immediate wait
2024-02-09 11:52:28.578 UTC [3910] LOG:  checkpoint complete: wrote 28 buffers (0.2%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.003 s, sync=0.005 s, total=0.015 s; sync files=19, longest=0.002 s, average=0.001 s; distance=16403 kB, estimate=16403 kB; lsn=0/3004F20, redo lsn=0/3004F20
2024-02-09 11:52:28.581 UTC [3909] LOG:  database system is ready to accept connections
2024-02-09 11:52:29.410 UTC [3923] LOG:  logical replication apply worker for subscription "pg_createsubscriber_5_3884" has started
2024-02-09 11:52:29.417 UTC [3922] ERROR:  subscription "pg_createsubscriber_16389_3884" already exists
2024-02-09 11:52:29.417 UTC [3922] STATEMENT:  CREATE SUBSCRIPTION pg_createsubscriber_16389_3884 CONNECTION 'user=postgres port=5431 dbname=testdb' PUBLICATION pg_createsubscriber_16389 WITH (create_slot = false, copy_data = false, enabled = false)
2024-02-09 11:52:29.426 UTC [3923] FATAL:  terminating logical replication worker due to administrator command
2024-02-09 11:52:29.427 UTC [3909] LOG:  background worker "logical replication apply worker" (PID 3923) exited with exit code 1
2024-02-09 11:52:29.445 UTC [3925] ERROR:  publication "pg_createsubscriber_5" does not exist
2024-02-09 11:52:29.445 UTC [3925] STATEMENT:  DROP PUBLICATION pg_createsubscriber_5