standby.log
application/octet-stream
Filename: standby.log
Type: application/octet-stream
Part: 3
2023-11-06 18:59:59.048 AEDT [17119] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 18:59:59.049 AEDT [17119] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 18:59:59.050 AEDT [17119] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 18:59:59.076 AEDT [17119] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 18:59:59.083 AEDT [17123] LOG: database system was interrupted; last known up at 2023-11-06 18:59:58 AEDT 2023-11-06 18:59:59.650 AEDT [17123] LOG: entering standby mode 2023-11-06 18:59:59.655 AEDT [17123] LOG: redo starts at 0/2000028 2023-11-06 18:59:59.657 AEDT [17123] LOG: consistent recovery state reached at 0/2000120 2023-11-06 18:59:59.657 AEDT [17119] LOG: database system is ready to accept read-only connections 2023-11-06 18:59:59.664 AEDT [17148] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:00:25.639 AEDT [17148] LOG: replication terminated by primary server 2023-11-06 19:00:25.639 AEDT [17148] DETAIL: End of WAL reached on timeline 1 at 0/30000D8. 2023-11-06 19:00:25.639 AEDT [17148] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:00:25.640 AEDT [17123] LOG: invalid record length at 0/30000D8: expected at least 24, got 0 2023-11-06 19:00:25.651 AEDT [17909] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:00:25.652 AEDT [17123] LOG: waiting for WAL to become available at 0/30000F0 2023-11-06 19:00:25.707 AEDT [17119] LOG: received fast shutdown request 2023-11-06 19:00:25.726 AEDT [17119] LOG: aborting any active transactions 2023-11-06 19:00:25.727 AEDT [17121] LOG: shutting down 2023-11-06 19:00:25.727 AEDT [17121] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:00:25.741 AEDT [17121] LOG: restartpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.014 s; sync files=0, longest=0.000 s, average=0.000 s; distance=16384 kB, estimate=16384 kB; lsn=0/3000060, redo lsn=0/3000060 2023-11-06 19:00:25.741 AEDT [17121] LOG: recovery restart point at 0/3000060 2023-11-06 19:00:25.752 AEDT [17119] LOG: database system is shut down 2023-11-06 19:00:30.922 AEDT [18082] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:00:30.923 AEDT [18082] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:00:30.923 AEDT [18082] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:00:30.928 AEDT [18082] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:00:30.934 AEDT [18085] LOG: database system was interrupted; last known up at 2023-11-06 19:00:29 AEDT 2023-11-06 19:00:31.574 AEDT [18085] LOG: entering standby mode 2023-11-06 19:00:31.580 AEDT [18085] LOG: redo starts at 0/2000028 2023-11-06 19:00:31.582 AEDT [18085] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:00:31.582 AEDT [18082] LOG: database system is ready to accept read-only connections 2023-11-06 19:00:31.592 AEDT [18106] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:05:46.046 AEDT [18083] LOG: restartpoint starting: time 2023-11-06 19:05:48.793 AEDT [18083] LOG: restartpoint complete: wrote 28 buffers (0.2%); 0 WAL file(s) added, 0 removed, 1 recycled; write=2.719 s, sync=0.016 s, total=2.747 s; sync files=9, longest=0.007 s, average=0.002 s; distance=16545 kB, estimate=16545 kB; lsn=0/30285D0, redo lsn=0/3028578 2023-11-06 19:05:48.793 AEDT [18083] LOG: recovery restart point at 0/3028578 2023-11-06 19:05:48.793 AEDT [18083] DETAIL: Last completed transaction was at log time 2023-11-06 19:00:59.896887+11. 2023-11-06 19:07:50.055 AEDT [18106] LOG: replication terminated by primary server 2023-11-06 19:07:50.055 AEDT [18106] DETAIL: End of WAL reached on timeline 1 at 0/30286F8. 2023-11-06 19:07:50.055 AEDT [18106] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:07:50.056 AEDT [18085] LOG: invalid record length at 0/30286F8: expected at least 24, got 0 2023-11-06 19:07:50.067 AEDT [30242] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:07:50.068 AEDT [18085] LOG: waiting for WAL to become available at 0/3028710 2023-11-06 19:07:50.128 AEDT [18082] LOG: received fast shutdown request 2023-11-06 19:07:50.141 AEDT [18082] LOG: aborting any active transactions 2023-11-06 19:07:50.144 AEDT [18083] LOG: shutting down 2023-11-06 19:07:50.144 AEDT [18083] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:07:50.156 AEDT [18083] LOG: restartpoint complete: wrote 0 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.013 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=14890 kB; lsn=0/3028680, redo lsn=0/3028680 2023-11-06 19:07:50.156 AEDT [18083] LOG: recovery restart point at 0/3028680 2023-11-06 19:07:50.156 AEDT [18083] DETAIL: Last completed transaction was at log time 2023-11-06 19:00:59.896887+11. 2023-11-06 19:07:50.168 AEDT [18082] LOG: database system is shut down 2023-11-06 19:07:55.152 AEDT [30417] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:07:55.153 AEDT [30417] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:07:55.153 AEDT [30417] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:07:55.174 AEDT [30417] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:07:55.188 AEDT [30424] LOG: database system was interrupted; last known up at 2023-11-06 19:07:54 AEDT 2023-11-06 19:07:55.706 AEDT [30424] LOG: entering standby mode 2023-11-06 19:07:55.710 AEDT [30424] LOG: redo starts at 0/2000028 2023-11-06 19:07:55.712 AEDT [30424] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:07:55.712 AEDT [30417] LOG: database system is ready to accept read-only connections 2023-11-06 19:07:55.721 AEDT [30445] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:07:59.176 AEDT [30554] ERROR: relation "t1" does not exist at character 15 2023-11-06 19:07:59.176 AEDT [30554] STATEMENT: select * from t1; 2023-11-06 19:09:07.011 AEDT [30445] LOG: replication terminated by primary server 2023-11-06 19:09:07.011 AEDT [30445] DETAIL: End of WAL reached on timeline 1 at 0/303F610. 2023-11-06 19:09:07.011 AEDT [30445] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:09:07.012 AEDT [30424] LOG: invalid record length at 0/303F610: expected at least 24, got 0 2023-11-06 19:09:07.028 AEDT [32451] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:09:07.029 AEDT [30424] LOG: waiting for WAL to become available at 0/303F628 2023-11-06 19:09:07.054 AEDT [30417] LOG: received fast shutdown request 2023-11-06 19:09:07.056 AEDT [30417] LOG: aborting any active transactions 2023-11-06 19:09:07.058 AEDT [30422] LOG: shutting down 2023-11-06 19:09:07.058 AEDT [30422] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:09:07.108 AEDT [30422] LOG: restartpoint complete: wrote 51 buffers (0.3%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.008 s, sync=0.034 s, total=0.051 s; sync files=29, longest=0.010 s, average=0.002 s; distance=16637 kB, estimate=16637 kB; lsn=0/303F598, redo lsn=0/303F598 2023-11-06 19:09:07.108 AEDT [30422] LOG: recovery restart point at 0/303F598 2023-11-06 19:09:07.108 AEDT [30422] DETAIL: Last completed transaction was at log time 2023-11-06 19:08:24.246235+11. 2023-11-06 19:09:07.120 AEDT [30417] LOG: database system is shut down 2023-11-06 19:09:12.771 AEDT [32646] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:09:12.772 AEDT [32646] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:09:12.772 AEDT [32646] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:09:12.776 AEDT [32646] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:09:12.782 AEDT [32649] LOG: database system was interrupted; last known up at 2023-11-06 19:09:11 AEDT 2023-11-06 19:09:13.410 AEDT [32649] LOG: entering standby mode 2023-11-06 19:09:13.414 AEDT [32649] LOG: redo starts at 0/2000028 2023-11-06 19:09:13.415 AEDT [32649] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:09:13.415 AEDT [32646] LOG: database system is ready to accept read-only connections 2023-11-06 19:09:13.425 AEDT [32670] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:09:34.623 AEDT [32670] LOG: replication terminated by primary server 2023-11-06 19:09:34.623 AEDT [32670] DETAIL: End of WAL reached on timeline 1 at 0/30171D0. 2023-11-06 19:09:34.623 AEDT [32670] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:09:34.624 AEDT [32649] LOG: invalid record length at 0/30171D0: expected at least 24, got 0 2023-11-06 19:09:34.635 AEDT [847] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:09:34.635 AEDT [32649] LOG: waiting for WAL to become available at 0/30171E8 2023-11-06 19:09:34.672 AEDT [32646] LOG: received fast shutdown request 2023-11-06 19:09:34.678 AEDT [32646] LOG: aborting any active transactions 2023-11-06 19:09:34.680 AEDT [32647] LOG: shutting down 2023-11-06 19:09:34.680 AEDT [32647] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:09:34.724 AEDT [32647] LOG: restartpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.007 s, sync=0.014 s, total=0.044 s; sync files=21, longest=0.003 s, average=0.001 s; distance=16476 kB, estimate=16476 kB; lsn=0/3017158, redo lsn=0/3017158 2023-11-06 19:09:34.724 AEDT [32647] LOG: recovery restart point at 0/3017158 2023-11-06 19:09:34.724 AEDT [32647] DETAIL: Last completed transaction was at log time 2023-11-06 19:09:17.025368+11. 2023-11-06 19:09:34.737 AEDT [32646] LOG: database system is shut down 2023-11-06 19:09:40.354 AEDT [1045] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:09:40.355 AEDT [1045] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:09:40.355 AEDT [1045] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:09:40.359 AEDT [1045] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:09:40.366 AEDT [1056] LOG: database system was interrupted; last known up at 2023-11-06 19:09:39 AEDT 2023-11-06 19:09:40.871 AEDT [1056] LOG: entering standby mode 2023-11-06 19:09:40.875 AEDT [1056] LOG: redo starts at 0/2000028 2023-11-06 19:09:40.877 AEDT [1056] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:09:40.878 AEDT [1045] LOG: database system is ready to accept read-only connections 2023-11-06 19:09:40.895 AEDT [1061] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:11:15.738 AEDT [1061] LOG: replication terminated by primary server 2023-11-06 19:11:15.738 AEDT [1061] DETAIL: End of WAL reached on timeline 1 at 0/3044A30. 2023-11-06 19:11:15.738 AEDT [1061] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:11:15.739 AEDT [1056] LOG: invalid record length at 0/3044A30: expected at least 24, got 0 2023-11-06 19:11:15.753 AEDT [3907] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:11:15.753 AEDT [1056] LOG: waiting for WAL to become available at 0/3044A48 2023-11-06 19:11:15.782 AEDT [1045] LOG: received fast shutdown request 2023-11-06 19:11:15.785 AEDT [1045] LOG: aborting any active transactions 2023-11-06 19:11:15.787 AEDT [1054] LOG: shutting down 2023-11-06 19:11:15.787 AEDT [1054] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:11:15.839 AEDT [1054] LOG: restartpoint complete: wrote 53 buffers (0.3%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.008 s, sync=0.019 s, total=0.052 s; sync files=29, longest=0.005 s, average=0.001 s; distance=16658 kB, estimate=16658 kB; lsn=0/30449B8, redo lsn=0/30449B8 2023-11-06 19:11:15.839 AEDT [1054] LOG: recovery restart point at 0/30449B8 2023-11-06 19:11:15.839 AEDT [1054] DETAIL: Last completed transaction was at log time 2023-11-06 19:10:09.308223+11. 2023-11-06 19:11:15.854 AEDT [1045] LOG: database system is shut down 2023-11-06 19:11:20.981 AEDT [4107] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:11:20.982 AEDT [4107] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:11:20.982 AEDT [4107] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:11:20.986 AEDT [4107] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:11:20.992 AEDT [4110] LOG: database system was interrupted; last known up at 2023-11-06 19:11:20 AEDT 2023-11-06 19:11:21.572 AEDT [4110] LOG: entering standby mode 2023-11-06 19:11:21.577 AEDT [4110] LOG: redo starts at 0/2000028 2023-11-06 19:11:21.579 AEDT [4110] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:11:21.579 AEDT [4107] LOG: database system is ready to accept read-only connections 2023-11-06 19:11:21.588 AEDT [4133] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:15:54.437 AEDT [4133] LOG: replication terminated by primary server 2023-11-06 19:15:54.437 AEDT [4133] DETAIL: End of WAL reached on timeline 1 at 0/3045B60. 2023-11-06 19:15:54.437 AEDT [4133] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:15:54.438 AEDT [4110] LOG: invalid record length at 0/3045B60: expected at least 24, got 0 2023-11-06 19:15:54.452 AEDT [11755] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:15:54.452 AEDT [4110] LOG: waiting for WAL to become available at 0/3045B78 2023-11-06 19:15:54.464 AEDT [4107] LOG: received fast shutdown request 2023-11-06 19:15:54.476 AEDT [4107] LOG: aborting any active transactions 2023-11-06 19:15:54.486 AEDT [4108] LOG: shutting down 2023-11-06 19:15:54.486 AEDT [4108] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:15:54.535 AEDT [4108] LOG: restartpoint complete: wrote 67 buffers (0.4%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.010 s, sync=0.025 s, total=0.050 s; sync files=36, longest=0.004 s, average=0.001 s; distance=16662 kB, estimate=16662 kB; lsn=0/3045AE8, redo lsn=0/3045AE8 2023-11-06 19:15:54.535 AEDT [4108] LOG: recovery restart point at 0/3045AE8 2023-11-06 19:15:54.535 AEDT [4108] DETAIL: Last completed transaction was at log time 2023-11-06 19:11:50.042308+11. 2023-11-06 19:15:54.547 AEDT [4107] LOG: database system is shut down 2023-11-06 19:15:59.960 AEDT [11925] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:15:59.961 AEDT [11925] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:15:59.961 AEDT [11925] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:15:59.965 AEDT [11925] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:15:59.971 AEDT [11928] LOG: database system was interrupted; last known up at 2023-11-06 19:15:58 AEDT 2023-11-06 19:16:00.533 AEDT [11928] LOG: entering standby mode 2023-11-06 19:16:00.538 AEDT [11928] LOG: redo starts at 0/2000028 2023-11-06 19:16:00.540 AEDT [11928] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:16:00.540 AEDT [11925] LOG: database system is ready to accept read-only connections 2023-11-06 19:16:00.549 AEDT [11953] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:16:14.172 AEDT [12336] LOG: received replication command: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:16:14.172 AEDT [12336] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:16:29.020 AEDT [12336] LOG: logical decoding found consistent point at 0/3035C88 2023-11-06 19:16:29.020 AEDT [12336] DETAIL: There are no running transactions. 2023-11-06 19:16:29.020 AEDT [12336] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:16:29.052 AEDT [12774] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-06 19:16:29.052 AEDT [12774] STATEMENT: IDENTIFY_SYSTEM 2023-11-06 19:16:29.053 AEDT [12774] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:29.053 AEDT [12774] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:29.053 AEDT [12774] LOG: walsender process with PID 12774 acquired logical replication slot "sub1" 2023-11-06 19:16:29.053 AEDT [12774] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:29.053 AEDT [12774] LOG: starting logical decoding for slot "sub1" 2023-11-06 19:16:29.053 AEDT [12774] DETAIL: Streaming transactions committing after 0/3035CC0, reading WAL from 0/30182D8. 2023-11-06 19:16:29.053 AEDT [12774] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:29.054 AEDT [12774] LOG: logical decoding found consistent point at 0/3035C88 2023-11-06 19:16:29.054 AEDT [12774] DETAIL: There are no running transactions. 2023-11-06 19:16:29.054 AEDT [12774] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:29.080 AEDT [12779] LOG: received replication command: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:16:29.080 AEDT [12779] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:16:44.204 AEDT [12779] LOG: logical decoding found consistent point at 0/30407D0 2023-11-06 19:16:44.204 AEDT [12779] DETAIL: There are no running transactions. 2023-11-06 19:16:44.204 AEDT [12779] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:16:44.241 AEDT [12779] LOG: received replication command: START_REPLICATION SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL 0/3040808 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:44.241 AEDT [12779] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL 0/3040808 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:44.241 AEDT [12779] LOG: walsender process with PID 12779 acquired logical replication slot "pg_16387_sync_16384_7298259945407069881" 2023-11-06 19:16:44.241 AEDT [12779] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL 0/3040808 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:44.241 AEDT [12779] LOG: starting logical decoding for slot "pg_16387_sync_16384_7298259945407069881" 2023-11-06 19:16:44.241 AEDT [12779] DETAIL: Streaming transactions committing after 0/3040808, reading WAL from 0/30407D0. 2023-11-06 19:16:44.241 AEDT [12779] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL 0/3040808 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:44.242 AEDT [12779] LOG: logical decoding found consistent point at 0/30407D0 2023-11-06 19:16:44.242 AEDT [12779] DETAIL: There are no running transactions. 2023-11-06 19:16:44.242 AEDT [12779] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL 0/3040808 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:44.242 AEDT [12779] LOG: walsender process with PID 12779 released logical replication slot "pg_16387_sync_16384_7298259945407069881" 2023-11-06 19:16:44.242 AEDT [12779] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298259945407069881" LOGICAL 0/3040808 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:16:44.242 AEDT [12779] LOG: received replication command: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298259945407069881 WAIT 2023-11-06 19:16:44.242 AEDT [12779] STATEMENT: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298259945407069881 WAIT 2023-11-06 19:21:15.038 AEDT [11926] LOG: restartpoint starting: time 2023-11-06 19:21:21.658 AEDT [11926] LOG: restartpoint complete: wrote 66 buffers (0.4%); 0 WAL file(s) added, 0 removed, 1 recycled; write=6.571 s, sync=0.026 s, total=6.620 s; sync files=36, longest=0.008 s, average=0.001 s; distance=16641 kB, estimate=16641 kB; lsn=0/3040860, redo lsn=0/3040808 2023-11-06 19:21:21.658 AEDT [11926] LOG: recovery restart point at 0/3040808 2023-11-06 19:21:21.658 AEDT [11926] DETAIL: Last completed transaction was at log time 2023-11-06 19:16:29.051823+11. 2023-11-06 19:23:41.478 AEDT [11953] LOG: replication terminated by primary server 2023-11-06 19:23:41.478 AEDT [11953] DETAIL: End of WAL reached on timeline 1 at 0/3040988. 2023-11-06 19:23:41.478 AEDT [11953] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:23:41.479 AEDT [11928] LOG: invalid record length at 0/3040988: expected at least 24, got 0 2023-11-06 19:23:41.492 AEDT [24998] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:23:41.493 AEDT [11928] LOG: waiting for WAL to become available at 0/30409A0 2023-11-06 19:23:41.530 AEDT [11925] LOG: received fast shutdown request 2023-11-06 19:23:41.532 AEDT [11925] LOG: aborting any active transactions 2023-11-06 19:23:41.535 AEDT [11926] LOG: shutting down 2023-11-06 19:23:41.535 AEDT [12774] LOG: walsender process with PID 12774 released logical replication slot "sub1" 2023-11-06 19:23:41.544 AEDT [25001] FATAL: the database system is shutting down 2023-11-06 19:23:41.545 AEDT [11926] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:23:41.573 AEDT [11926] LOG: restartpoint complete: wrote 0 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.001 s, total=0.028 s; sync files=0, longest=0.000 s, average=0.000 s; distance=0 kB, estimate=14977 kB; lsn=0/3040910, redo lsn=0/3040910 2023-11-06 19:23:41.573 AEDT [11926] LOG: recovery restart point at 0/3040910 2023-11-06 19:23:41.573 AEDT [11926] DETAIL: Last completed transaction was at log time 2023-11-06 19:16:29.051823+11. 2023-11-06 19:23:41.591 AEDT [11925] LOG: database system is shut down 2023-11-06 19:23:46.777 AEDT [25184] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:23:46.778 AEDT [25184] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:23:46.778 AEDT [25184] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:23:46.782 AEDT [25184] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:23:46.788 AEDT [25187] LOG: database system was interrupted; last known up at 2023-11-06 19:23:45 AEDT 2023-11-06 19:23:47.372 AEDT [25187] LOG: entering standby mode 2023-11-06 19:23:47.377 AEDT [25187] LOG: redo starts at 0/2000028 2023-11-06 19:23:47.379 AEDT [25187] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:23:47.379 AEDT [25184] LOG: database system is ready to accept read-only connections 2023-11-06 19:23:47.401 AEDT [25206] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:23:56.055 AEDT [25461] LOG: received replication command: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:23:56.055 AEDT [25461] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:24:00.836 AEDT [25461] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:24:00.836 AEDT [25461] DETAIL: There are no running transactions. 2023-11-06 19:24:00.836 AEDT [25461] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:24:00.855 AEDT [25596] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-06 19:24:00.855 AEDT [25596] STATEMENT: IDENTIFY_SYSTEM 2023-11-06 19:24:00.855 AEDT [25596] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:00.855 AEDT [25596] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:00.855 AEDT [25596] LOG: walsender process with PID 25596 acquired logical replication slot "sub1" 2023-11-06 19:24:00.855 AEDT [25596] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:00.856 AEDT [25596] LOG: starting logical decoding for slot "sub1" 2023-11-06 19:24:00.856 AEDT [25596] DETAIL: Streaming transactions committing after 0/30182D8, reading WAL from 0/30182A0. 2023-11-06 19:24:00.856 AEDT [25596] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:00.856 AEDT [25596] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:24:00.856 AEDT [25596] DETAIL: There are no running transactions. 2023-11-06 19:24:00.856 AEDT [25596] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:00.872 AEDT [25598] LOG: received replication command: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:24:00.872 AEDT [25598] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:24:15.800 AEDT [25598] LOG: logical decoding found consistent point at 0/3025740 2023-11-06 19:24:15.800 AEDT [25598] DETAIL: There are no running transactions. 2023-11-06 19:24:15.800 AEDT [25598] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:24:15.855 AEDT [25598] LOG: received replication command: START_REPLICATION SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL 0/3025778 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:15.855 AEDT [25598] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL 0/3025778 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:15.855 AEDT [25598] LOG: walsender process with PID 25598 acquired logical replication slot "pg_16387_sync_16384_7298261950654059136" 2023-11-06 19:24:15.855 AEDT [25598] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL 0/3025778 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:15.856 AEDT [25598] LOG: starting logical decoding for slot "pg_16387_sync_16384_7298261950654059136" 2023-11-06 19:24:15.856 AEDT [25598] DETAIL: Streaming transactions committing after 0/3025778, reading WAL from 0/30182D8. 2023-11-06 19:24:15.856 AEDT [25598] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL 0/3025778 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:15.856 AEDT [25598] LOG: walsender process with PID 25598 released logical replication slot "pg_16387_sync_16384_7298261950654059136" 2023-11-06 19:24:15.856 AEDT [25598] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298261950654059136" LOGICAL 0/3025778 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:24:15.856 AEDT [25598] LOG: received replication command: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298261950654059136 WAIT 2023-11-06 19:24:15.856 AEDT [25598] STATEMENT: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298261950654059136 WAIT 2023-11-06 19:24:41.454 AEDT [25206] LOG: replication terminated by primary server 2023-11-06 19:24:41.454 AEDT [25206] DETAIL: End of WAL reached on timeline 1 at 0/3040880. 2023-11-06 19:24:41.454 AEDT [25206] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:24:41.455 AEDT [25187] LOG: invalid record length at 0/3040880: expected at least 24, got 0 2023-11-06 19:24:41.467 AEDT [26701] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:24:41.467 AEDT [25187] LOG: waiting for WAL to become available at 0/3040898 2023-11-06 19:24:41.487 AEDT [25184] LOG: received fast shutdown request 2023-11-06 19:24:41.489 AEDT [25184] LOG: aborting any active transactions 2023-11-06 19:24:41.490 AEDT [25185] LOG: shutting down 2023-11-06 19:24:41.490 AEDT [25596] LOG: walsender process with PID 25596 released logical replication slot "sub1" 2023-11-06 19:24:41.503 AEDT [25185] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:24:41.506 AEDT [26704] FATAL: the database system is shutting down 2023-11-06 19:24:41.563 AEDT [25185] LOG: restartpoint complete: wrote 66 buffers (0.4%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.012 s, sync=0.024 s, total=0.061 s; sync files=36, longest=0.005 s, average=0.001 s; distance=16641 kB, estimate=16641 kB; lsn=0/3040808, redo lsn=0/3040808 2023-11-06 19:24:41.563 AEDT [25185] LOG: recovery restart point at 0/3040808 2023-11-06 19:24:41.563 AEDT [25185] DETAIL: Last completed transaction was at log time 2023-11-06 19:24:15.855627+11. 2023-11-06 19:24:41.575 AEDT [25184] LOG: database system is shut down 2023-11-06 19:24:46.761 AEDT [26879] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:24:46.762 AEDT [26879] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:24:46.762 AEDT [26879] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:24:46.769 AEDT [26879] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:24:46.777 AEDT [26882] LOG: database system was interrupted; last known up at 2023-11-06 19:24:45 AEDT 2023-11-06 19:24:47.405 AEDT [26882] LOG: entering standby mode 2023-11-06 19:24:47.411 AEDT [26882] LOG: redo starts at 0/2000028 2023-11-06 19:24:47.413 AEDT [26882] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:24:47.413 AEDT [26879] LOG: database system is ready to accept read-only connections 2023-11-06 19:24:47.420 AEDT [26905] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:24:56.300 AEDT [27148] LOG: received replication command: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:24:56.300 AEDT [27148] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:25:00.637 AEDT [27148] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:25:00.637 AEDT [27148] DETAIL: There are no running transactions. 2023-11-06 19:25:00.637 AEDT [27148] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:25:00.661 AEDT [27267] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-06 19:25:00.661 AEDT [27267] STATEMENT: IDENTIFY_SYSTEM 2023-11-06 19:25:00.661 AEDT [27267] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:00.661 AEDT [27267] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:00.661 AEDT [27267] LOG: walsender process with PID 27267 acquired logical replication slot "sub1" 2023-11-06 19:25:00.661 AEDT [27267] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:00.662 AEDT [27267] LOG: starting logical decoding for slot "sub1" 2023-11-06 19:25:00.662 AEDT [27267] DETAIL: Streaming transactions committing after 0/30182D8, reading WAL from 0/30182A0. 2023-11-06 19:25:00.662 AEDT [27267] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:00.662 AEDT [27267] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:25:00.662 AEDT [27267] DETAIL: There are no running transactions. 2023-11-06 19:25:00.662 AEDT [27267] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:00.676 AEDT [27269] LOG: received replication command: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:25:00.676 AEDT [27269] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:25:15.809 AEDT [27269] LOG: logical decoding found consistent point at 0/303AFB8 2023-11-06 19:25:15.809 AEDT [27269] DETAIL: There are no running transactions. 2023-11-06 19:25:15.809 AEDT [27269] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:25:15.849 AEDT [27269] LOG: received replication command: START_REPLICATION SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL 0/303AFF0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:15.849 AEDT [27269] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL 0/303AFF0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:15.849 AEDT [27269] LOG: walsender process with PID 27269 acquired logical replication slot "pg_16387_sync_16384_7298262208649935137" 2023-11-06 19:25:15.849 AEDT [27269] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL 0/303AFF0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:15.849 AEDT [27269] LOG: starting logical decoding for slot "pg_16387_sync_16384_7298262208649935137" 2023-11-06 19:25:15.849 AEDT [27269] DETAIL: Streaming transactions committing after 0/303AFF0, reading WAL from 0/30182D8. 2023-11-06 19:25:15.849 AEDT [27269] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL 0/303AFF0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:15.851 AEDT [27269] LOG: logical decoding found consistent point at 0/303AFB8 2023-11-06 19:25:15.851 AEDT [27269] DETAIL: There are no running transactions. 2023-11-06 19:25:15.851 AEDT [27269] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL 0/303AFF0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:15.851 AEDT [27269] LOG: walsender process with PID 27269 released logical replication slot "pg_16387_sync_16384_7298262208649935137" 2023-11-06 19:25:15.851 AEDT [27269] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262208649935137" LOGICAL 0/303AFF0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:25:15.852 AEDT [27269] LOG: received replication command: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298262208649935137 WAIT 2023-11-06 19:25:15.852 AEDT [27269] STATEMENT: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298262208649935137 WAIT 2023-11-06 19:26:09.362 AEDT [26905] LOG: replication terminated by primary server 2023-11-06 19:26:09.362 AEDT [26905] DETAIL: End of WAL reached on timeline 1 at 0/3045B98. 2023-11-06 19:26:09.362 AEDT [26905] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:26:09.364 AEDT [26882] LOG: invalid record length at 0/3045B98: expected at least 24, got 0 2023-11-06 19:26:09.377 AEDT [29237] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:26:09.377 AEDT [26882] LOG: waiting for WAL to become available at 0/3045BB0 2023-11-06 19:26:09.393 AEDT [26879] LOG: received fast shutdown request 2023-11-06 19:26:09.395 AEDT [26879] LOG: aborting any active transactions 2023-11-06 19:26:09.400 AEDT [26880] LOG: shutting down 2023-11-06 19:26:09.400 AEDT [27267] LOG: walsender process with PID 27267 released logical replication slot "sub1" 2023-11-06 19:26:09.410 AEDT [26880] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:26:09.419 AEDT [29244] FATAL: the database system is shutting down 2023-11-06 19:26:09.462 AEDT [26880] LOG: restartpoint complete: wrote 67 buffers (0.4%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.008 s, sync=0.022 s, total=0.052 s; sync files=36, longest=0.005 s, average=0.001 s; distance=16662 kB, estimate=16662 kB; lsn=0/3045B20, redo lsn=0/3045B20 2023-11-06 19:26:09.462 AEDT [26880] LOG: recovery restart point at 0/3045B20 2023-11-06 19:26:09.462 AEDT [26880] DETAIL: Last completed transaction was at log time 2023-11-06 19:25:15.847348+11. 2023-11-06 19:26:09.473 AEDT [26879] LOG: database system is shut down 2023-11-06 19:26:14.784 AEDT [29430] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:26:14.785 AEDT [29430] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:26:14.785 AEDT [29430] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:26:14.790 AEDT [29430] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:26:14.795 AEDT [29433] LOG: database system was interrupted; last known up at 2023-11-06 19:26:13 AEDT 2023-11-06 19:26:15.347 AEDT [29433] LOG: entering standby mode 2023-11-06 19:26:15.357 AEDT [29433] LOG: redo starts at 0/2000028 2023-11-06 19:26:15.360 AEDT [29433] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:26:15.360 AEDT [29430] LOG: database system is ready to accept read-only connections 2023-11-06 19:26:15.369 AEDT [29452] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:26:24.027 AEDT [29718] LOG: received replication command: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:26:24.027 AEDT [29718] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:26:28.718 AEDT [29718] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:26:28.718 AEDT [29718] DETAIL: There are no running transactions. 2023-11-06 19:26:28.718 AEDT [29718] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:26:28.742 AEDT [29863] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-06 19:26:28.742 AEDT [29863] STATEMENT: IDENTIFY_SYSTEM 2023-11-06 19:26:28.742 AEDT [29863] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:28.742 AEDT [29863] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:28.742 AEDT [29863] LOG: walsender process with PID 29863 acquired logical replication slot "sub1" 2023-11-06 19:26:28.742 AEDT [29863] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:28.743 AEDT [29863] LOG: starting logical decoding for slot "sub1" 2023-11-06 19:26:28.743 AEDT [29863] DETAIL: Streaming transactions committing after 0/30182D8, reading WAL from 0/30182A0. 2023-11-06 19:26:28.743 AEDT [29863] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:28.743 AEDT [29863] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:26:28.743 AEDT [29863] DETAIL: There are no running transactions. 2023-11-06 19:26:28.743 AEDT [29863] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:28.758 AEDT [29865] LOG: received replication command: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:26:28.758 AEDT [29865] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:26:43.795 AEDT [29865] LOG: logical decoding found consistent point at 0/30233A0 2023-11-06 19:26:43.795 AEDT [29865] DETAIL: There are no running transactions. 2023-11-06 19:26:43.795 AEDT [29865] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:26:43.827 AEDT [29865] LOG: received replication command: START_REPLICATION SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL 0/30233D8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:43.827 AEDT [29865] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL 0/30233D8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:43.827 AEDT [29865] LOG: walsender process with PID 29865 acquired logical replication slot "pg_16387_sync_16384_7298262586222617370" 2023-11-06 19:26:43.827 AEDT [29865] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL 0/30233D8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:43.827 AEDT [29865] LOG: starting logical decoding for slot "pg_16387_sync_16384_7298262586222617370" 2023-11-06 19:26:43.827 AEDT [29865] DETAIL: Streaming transactions committing after 0/30233D8, reading WAL from 0/30182D8. 2023-11-06 19:26:43.827 AEDT [29865] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL 0/30233D8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:43.828 AEDT [29865] LOG: logical decoding found consistent point at 0/30233A0 2023-11-06 19:26:43.828 AEDT [29865] DETAIL: There are no running transactions. 2023-11-06 19:26:43.828 AEDT [29865] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL 0/30233D8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:43.828 AEDT [29865] LOG: walsender process with PID 29865 released logical replication slot "pg_16387_sync_16384_7298262586222617370" 2023-11-06 19:26:43.828 AEDT [29865] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298262586222617370" LOGICAL 0/30233D8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:26:43.828 AEDT [29865] LOG: received replication command: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298262586222617370 WAIT 2023-11-06 19:26:43.828 AEDT [29865] STATEMENT: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298262586222617370 WAIT 2023-11-06 19:27:46.601 AEDT [29452] LOG: replication terminated by primary server 2023-11-06 19:27:46.601 AEDT [29452] DETAIL: End of WAL reached on timeline 1 at 0/3048EA0. 2023-11-06 19:27:46.601 AEDT [29452] FATAL: could not send end-of-streaming message to primary: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. no COPY in progress 2023-11-06 19:27:46.606 AEDT [29433] LOG: invalid record length at 0/3048EA0: expected at least 24, got 0 2023-11-06 19:27:46.616 AEDT [32128] FATAL: could not connect to the primary server: connection to server at "127.0.0.1", port 5433 failed: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request. 2023-11-06 19:27:46.616 AEDT [29433] LOG: waiting for WAL to become available at 0/3048EB8 2023-11-06 19:27:46.639 AEDT [29430] LOG: received fast shutdown request 2023-11-06 19:27:46.641 AEDT [29430] LOG: aborting any active transactions 2023-11-06 19:27:46.643 AEDT [29431] LOG: shutting down 2023-11-06 19:27:46.643 AEDT [29863] LOG: walsender process with PID 29863 released logical replication slot "sub1" 2023-11-06 19:27:46.651 AEDT [32131] FATAL: the database system is shutting down 2023-11-06 19:27:46.654 AEDT [29431] LOG: restartpoint starting: shutdown immediate 2023-11-06 19:27:46.722 AEDT [29431] LOG: restartpoint complete: wrote 69 buffers (0.4%); 0 WAL file(s) added, 1 removed, 0 recycled; write=0.009 s, sync=0.023 s, total=0.069 s; sync files=36, longest=0.005 s, average=0.001 s; distance=16675 kB, estimate=16675 kB; lsn=0/3048E28, redo lsn=0/3048E28 2023-11-06 19:27:46.722 AEDT [29431] LOG: recovery restart point at 0/3048E28 2023-11-06 19:27:46.722 AEDT [29431] DETAIL: Last completed transaction was at log time 2023-11-06 19:26:43.936485+11. 2023-11-06 19:27:46.733 AEDT [29430] LOG: database system is shut down 2023-11-06 19:27:52.010 AEDT [32322] LOG: starting PostgreSQL 17devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-36), 64-bit 2023-11-06 19:27:52.011 AEDT [32322] LOG: listening on IPv6 address "::1", port 5434 2023-11-06 19:27:52.011 AEDT [32322] LOG: listening on IPv4 address "127.0.0.1", port 5434 2023-11-06 19:27:52.015 AEDT [32322] LOG: listening on Unix socket "/tmp/.s.PGSQL.5434" 2023-11-06 19:27:52.027 AEDT [32325] LOG: database system was interrupted; last known up at 2023-11-06 19:27:51 AEDT 2023-11-06 19:27:52.661 AEDT [32325] LOG: entering standby mode 2023-11-06 19:27:52.666 AEDT [32325] LOG: redo starts at 0/2000028 2023-11-06 19:27:52.667 AEDT [32325] LOG: consistent recovery state reached at 0/2000120 2023-11-06 19:27:52.667 AEDT [32322] LOG: database system is ready to accept read-only connections 2023-11-06 19:27:52.676 AEDT [32348] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-06 19:28:01.373 AEDT [32620] LOG: received replication command: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:28:01.373 AEDT [32620] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:28:05.958 AEDT [32620] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:28:05.958 AEDT [32620] DETAIL: There are no running transactions. 2023-11-06 19:28:05.958 AEDT [32620] STATEMENT: CREATE_REPLICATION_SLOT "sub1" LOGICAL pgoutput (SNAPSHOT 'nothing') 2023-11-06 19:28:06.022 AEDT [326] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-06 19:28:06.022 AEDT [326] STATEMENT: IDENTIFY_SYSTEM 2023-11-06 19:28:06.022 AEDT [326] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:06.022 AEDT [326] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:06.022 AEDT [326] LOG: walsender process with PID 326 acquired logical replication slot "sub1" 2023-11-06 19:28:06.022 AEDT [326] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:06.023 AEDT [326] LOG: starting logical decoding for slot "sub1" 2023-11-06 19:28:06.023 AEDT [326] DETAIL: Streaming transactions committing after 0/30182D8, reading WAL from 0/30182A0. 2023-11-06 19:28:06.023 AEDT [326] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:06.023 AEDT [326] LOG: logical decoding found consistent point at 0/30182A0 2023-11-06 19:28:06.023 AEDT [326] DETAIL: There are no running transactions. 2023-11-06 19:28:06.023 AEDT [326] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:06.035 AEDT [328] LOG: received replication command: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:28:06.035 AEDT [328] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:28:21.112 AEDT [328] LOG: logical decoding found consistent point at 0/3045AB0 2023-11-06 19:28:21.112 AEDT [328] DETAIL: There are no running transactions. 2023-11-06 19:28:21.112 AEDT [328] STATEMENT: CREATE_REPLICATION_SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL pgoutput (SNAPSHOT 'use') 2023-11-06 19:28:21.139 AEDT [328] LOG: received replication command: START_REPLICATION SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL 0/3045AE8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:21.139 AEDT [328] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL 0/3045AE8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:21.140 AEDT [328] LOG: walsender process with PID 328 acquired logical replication slot "pg_16387_sync_16384_7298263004251438692" 2023-11-06 19:28:21.140 AEDT [328] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL 0/3045AE8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:21.140 AEDT [328] LOG: starting logical decoding for slot "pg_16387_sync_16384_7298263004251438692" 2023-11-06 19:28:21.140 AEDT [328] DETAIL: Streaming transactions committing after 0/3045AE8, reading WAL from 0/30182D8. 2023-11-06 19:28:21.140 AEDT [328] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL 0/3045AE8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:21.140 AEDT [328] LOG: walsender process with PID 328 released logical replication slot "pg_16387_sync_16384_7298263004251438692" 2023-11-06 19:28:21.140 AEDT [328] STATEMENT: START_REPLICATION SLOT "pg_16387_sync_16384_7298263004251438692" LOGICAL 0/3045AE8 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-06 19:28:21.140 AEDT [328] LOG: received replication command: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298263004251438692 WAIT 2023-11-06 19:28:21.140 AEDT [328] STATEMENT: DROP_REPLICATION_SLOT pg_16387_sync_16384_7298263004251438692 WAIT 2023-11-06 19:33:07.087 AEDT [32323] LOG: restartpoint starting: time 2023-11-06 19:33:14.380 AEDT [32323] LOG: restartpoint complete: wrote 67 buffers (0.4%); 0 WAL file(s) added, 0 removed, 1 recycled; write=7.233 s, sync=0.037 s, total=7.294 s; sync files=36, longest=0.008 s, average=0.002 s; distance=16662 kB, estimate=16662 kB; lsn=0/3045B40, redo lsn=0/3045AE8 2023-11-06 19:33:14.380 AEDT [32323] LOG: recovery restart point at 0/3045AE8 2023-11-06 19:33:14.380 AEDT [32323] DETAIL: Last completed transaction was at log time 2023-11-06 19:28:21.090086+11. 2023-11-07 01:03:05.102 AEDT [32348] FATAL: terminating walreceiver due to timeout 2023-11-07 01:03:05.103 AEDT [32325] LOG: invalid record length at 0/3045BF0: expected at least 24, got 0 2023-11-07 01:03:05.114 AEDT [9334] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 01:03:05.874 AEDT [326] LOG: walsender process with PID 326 released logical replication slot "sub1" 2023-11-07 01:03:05.893 AEDT [9344] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 01:03:05.893 AEDT [9344] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 01:03:05.893 AEDT [9344] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 01:03:05.893 AEDT [9344] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 01:03:05.894 AEDT [9344] LOG: walsender process with PID 9344 acquired logical replication slot "sub1" 2023-11-07 01:03:05.894 AEDT [9344] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 01:03:05.895 AEDT [9344] LOG: starting logical decoding for slot "sub1" 2023-11-07 01:03:05.895 AEDT [9344] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 01:03:05.895 AEDT [9344] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 01:03:05.895 AEDT [9344] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 01:03:05.895 AEDT [9344] DETAIL: There are no running transactions. 2023-11-07 01:03:05.895 AEDT [9344] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 03:33:04.614 AEDT [9334] FATAL: terminating walreceiver due to timeout 2023-11-07 03:33:04.626 AEDT [23427] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 03:33:05.347 AEDT [9344] LOG: walsender process with PID 9344 released logical replication slot "sub1" 2023-11-07 03:33:05.372 AEDT [23430] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 03:33:05.372 AEDT [23430] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 03:33:05.372 AEDT [23430] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 03:33:05.372 AEDT [23430] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 03:33:05.372 AEDT [23430] LOG: walsender process with PID 23430 acquired logical replication slot "sub1" 2023-11-07 03:33:05.372 AEDT [23430] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 03:33:05.373 AEDT [23430] LOG: starting logical decoding for slot "sub1" 2023-11-07 03:33:05.373 AEDT [23430] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 03:33:05.373 AEDT [23430] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 03:33:05.374 AEDT [23430] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 03:33:05.374 AEDT [23430] DETAIL: There are no running transactions. 2023-11-07 03:33:05.374 AEDT [23430] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:13:11.126 AEDT [23427] FATAL: terminating walreceiver due to timeout 2023-11-07 04:13:11.138 AEDT [12785] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 04:13:11.500 AEDT [23430] LOG: walsender process with PID 23430 released logical replication slot "sub1" 2023-11-07 04:13:11.525 AEDT [12801] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 04:13:11.525 AEDT [12801] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 04:13:11.525 AEDT [12801] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:13:11.525 AEDT [12801] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:13:11.525 AEDT [12801] LOG: walsender process with PID 12801 acquired logical replication slot "sub1" 2023-11-07 04:13:11.525 AEDT [12801] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:13:11.526 AEDT [12801] LOG: starting logical decoding for slot "sub1" 2023-11-07 04:13:11.526 AEDT [12801] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 04:13:11.526 AEDT [12801] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:13:11.527 AEDT [12801] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 04:13:11.527 AEDT [12801] DETAIL: There are no running transactions. 2023-11-07 04:13:11.527 AEDT [12801] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:43:03.355 AEDT [12801] LOG: walsender process with PID 12801 released logical replication slot "sub1" 2023-11-07 04:43:03.379 AEDT [24315] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 04:43:03.379 AEDT [24315] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 04:43:03.379 AEDT [24315] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:43:03.379 AEDT [24315] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:43:03.379 AEDT [24315] LOG: walsender process with PID 24315 acquired logical replication slot "sub1" 2023-11-07 04:43:03.379 AEDT [24315] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:43:03.381 AEDT [24315] LOG: starting logical decoding for slot "sub1" 2023-11-07 04:43:03.381 AEDT [24315] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 04:43:03.381 AEDT [24315] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:43:03.381 AEDT [24315] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 04:43:03.381 AEDT [24315] DETAIL: There are no running transactions. 2023-11-07 04:43:03.381 AEDT [24315] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 04:43:03.490 AEDT [12785] FATAL: terminating walreceiver due to timeout 2023-11-07 04:43:03.505 AEDT [24322] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 05:23:11.579 AEDT [24322] FATAL: terminating walreceiver due to timeout 2023-11-07 05:23:11.591 AEDT [8072] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 05:23:12.485 AEDT [24315] LOG: walsender process with PID 24315 released logical replication slot "sub1" 2023-11-07 05:23:12.520 AEDT [8093] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 05:23:12.520 AEDT [8093] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 05:23:12.520 AEDT [8093] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:23:12.520 AEDT [8093] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:23:12.520 AEDT [8093] LOG: walsender process with PID 8093 acquired logical replication slot "sub1" 2023-11-07 05:23:12.520 AEDT [8093] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:23:12.521 AEDT [8093] LOG: starting logical decoding for slot "sub1" 2023-11-07 05:23:12.521 AEDT [8093] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 05:23:12.521 AEDT [8093] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:23:12.521 AEDT [8093] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 05:23:12.521 AEDT [8093] DETAIL: There are no running transactions. 2023-11-07 05:23:12.521 AEDT [8093] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:53:11.483 AEDT [8072] FATAL: terminating walreceiver due to timeout 2023-11-07 05:53:11.496 AEDT [19888] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 05:53:12.457 AEDT [8093] LOG: walsender process with PID 8093 released logical replication slot "sub1" 2023-11-07 05:53:12.503 AEDT [19905] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 05:53:12.503 AEDT [19905] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 05:53:12.503 AEDT [19905] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:53:12.503 AEDT [19905] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:53:12.503 AEDT [19905] LOG: walsender process with PID 19905 acquired logical replication slot "sub1" 2023-11-07 05:53:12.503 AEDT [19905] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:53:12.504 AEDT [19905] LOG: starting logical decoding for slot "sub1" 2023-11-07 05:53:12.504 AEDT [19905] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 05:53:12.504 AEDT [19905] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 05:53:12.504 AEDT [19905] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 05:53:12.504 AEDT [19905] DETAIL: There are no running transactions. 2023-11-07 05:53:12.504 AEDT [19905] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 06:23:08.199 AEDT [19905] LOG: walsender process with PID 19905 released logical replication slot "sub1" 2023-11-07 06:23:08.201 AEDT [19888] FATAL: terminating walreceiver due to timeout 2023-11-07 06:23:08.216 AEDT [30136] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 06:23:08.224 AEDT [30139] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 06:23:08.224 AEDT [30139] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 06:23:08.224 AEDT [30139] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 06:23:08.224 AEDT [30139] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 06:23:08.224 AEDT [30139] LOG: walsender process with PID 30139 acquired logical replication slot "sub1" 2023-11-07 06:23:08.224 AEDT [30139] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 06:23:08.225 AEDT [30139] LOG: starting logical decoding for slot "sub1" 2023-11-07 06:23:08.225 AEDT [30139] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 06:23:08.225 AEDT [30139] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 06:23:08.225 AEDT [30139] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 06:23:08.225 AEDT [30139] DETAIL: There are no running transactions. 2023-11-07 06:23:08.225 AEDT [30139] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:03:11.213 AEDT [30139] LOG: walsender process with PID 30139 released logical replication slot "sub1" 2023-11-07 07:03:11.242 AEDT [15210] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 07:03:11.242 AEDT [15210] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 07:03:11.243 AEDT [15210] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:03:11.243 AEDT [15210] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:03:11.243 AEDT [15210] LOG: walsender process with PID 15210 acquired logical replication slot "sub1" 2023-11-07 07:03:11.243 AEDT [15210] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:03:11.244 AEDT [15210] LOG: starting logical decoding for slot "sub1" 2023-11-07 07:03:11.244 AEDT [15210] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 07:03:11.244 AEDT [15210] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:03:11.244 AEDT [15210] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 07:03:11.244 AEDT [15210] DETAIL: There are no running transactions. 2023-11-07 07:03:11.244 AEDT [15210] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:03:20.206 AEDT [30136] FATAL: terminating walreceiver due to timeout 2023-11-07 07:03:20.220 AEDT [15351] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 07:33:15.198 AEDT [15351] FATAL: terminating walreceiver due to timeout 2023-11-07 07:33:15.222 AEDT [26084] LOG: started streaming WAL from primary at 0/3000000 on timeline 1 2023-11-07 07:33:16.201 AEDT [15210] LOG: walsender process with PID 15210 released logical replication slot "sub1" 2023-11-07 07:33:16.222 AEDT [26109] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 07:33:16.222 AEDT [26109] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 07:33:16.222 AEDT [26109] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:33:16.222 AEDT [26109] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:33:16.222 AEDT [26109] LOG: walsender process with PID 26109 acquired logical replication slot "sub1" 2023-11-07 07:33:16.222 AEDT [26109] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:33:16.229 AEDT [26109] LOG: starting logical decoding for slot "sub1" 2023-11-07 07:33:16.229 AEDT [26109] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 07:33:16.229 AEDT [26109] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 07:33:16.229 AEDT [26109] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 07:33:16.229 AEDT [26109] DETAIL: There are no running transactions. 2023-11-07 07:33:16.229 AEDT [26109] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 08:03:11.310 AEDT [26109] LOG: walsender process with PID 26109 released logical replication slot "sub1" 2023-11-07 08:03:11.350 AEDT [6270] LOG: received replication command: IDENTIFY_SYSTEM 2023-11-07 08:03:11.350 AEDT [6270] STATEMENT: IDENTIFY_SYSTEM 2023-11-07 08:03:11.350 AEDT [6270] LOG: received replication command: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 08:03:11.350 AEDT [6270] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 08:03:11.350 AEDT [6270] LOG: walsender process with PID 6270 acquired logical replication slot "sub1" 2023-11-07 08:03:11.350 AEDT [6270] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 08:03:11.351 AEDT [6270] LOG: starting logical decoding for slot "sub1" 2023-11-07 08:03:11.351 AEDT [6270] DETAIL: Streaming transactions committing after 0/3045BF0, reading WAL from 0/3045BB8. 2023-11-07 08:03:11.351 AEDT [6270] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 08:03:11.351 AEDT [6270] LOG: logical decoding found consistent point at 0/3045BB8 2023-11-07 08:03:11.351 AEDT [6270] DETAIL: There are no running transactions. 2023-11-07 08:03:11.351 AEDT [6270] STATEMENT: START_REPLICATION SLOT "sub1" LOGICAL 0/0 (proto_version '4', origin 'any', publication_names '"pub1"') 2023-11-07 08:03:21.316 AEDT [26084] FATAL: terminating walreceiver due to timeout 2023-11-07 08:03:21.329 AEDT [6447] LOG: started streaming WAL from primary at 0/3000000 on timeline 1