500637 2022-06-28 11:59:13.125 JST LOG: starting PostgreSQL 15beta1 on x86_64-pc-linux-gnu, compiled by gcc (Ubuntu 9.4.0-1ubuntu1~20.04.1) 9.4.0, 64-bit 500637 2022-06-28 11:59:13.125 JST LOG: listening on IPv4 address "0.0.0.0", port 11002 500637 2022-06-28 11:59:13.125 JST LOG: listening on IPv6 address "::", port 11002 500637 2022-06-28 11:59:13.130 JST LOG: listening on Unix socket "/tmp/.s.PGSQL.11002" 500641 2022-06-28 11:59:13.135 JST LOG: database system was shut down at 2022-06-28 11:59:12 JST 500637 2022-06-28 11:59:13.141 JST LOG: database system is ready to accept connections 500647 2022-06-28 11:59:13.231 JST LOG: statement: CREATE EXTENSION pgpool_regclass; 500647 2022-06-28 11:59:13.232 JST ERROR: extension "pgpool_regclass" is not available 500647 2022-06-28 11:59:13.232 JST DETAIL: Could not open extension control file "/usr/local/src/pgsql/current/share/extension/pgpool_regclass.control": No such file or directory. 500647 2022-06-28 11:59:13.232 JST HINT: The extension must first be installed on the system where PostgreSQL is running. 500647 2022-06-28 11:59:13.232 JST STATEMENT: CREATE EXTENSION pgpool_regclass; 500647 2022-06-28 11:59:13.232 JST LOG: statement: CREATE EXTENSION pgpool_recovery; 500647 2022-06-28 11:59:13.240 JST LOG: statement: CREATE DATABASE test; 500687 2022-06-28 11:59:13.383 JST LOG: statement: SELECT pg_is_in_recovery() 500687 2022-06-28 11:59:13.384 JST LOG: statement: SELECT current_setting('server_version_num') 500691 2022-06-28 11:59:13.386 JST LOG: statement: SELECT pg_is_in_recovery() 500694 2022-06-28 11:59:14.317 JST LOG: statement: SELECT version() 500696 2022-06-28 11:59:14.329 JST LOG: statement: SELECT pg_is_in_recovery() 500694 2022-06-28 11:59:14.330 JST LOG: statement: DISCARD ALL 500707 2022-06-28 11:59:14.361 JST LOG: statement: SELECT current_setting('server_version_num') 500707 2022-06-28 11:59:14.362 JST LOG: statement: SELECT pg_current_wal_lsn() 500707 2022-06-28 11:59:14.363 JST LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 500707 2022-06-28 11:59:14.368 JST LOG: statement: SELECT pg_is_in_recovery() 500708 2022-06-28 11:59:14.371 JST LOG: statement: SELECT version() 500708 2022-06-28 11:59:14.374 JST LOG: statement: DISCARD ALL 500712 2022-06-28 11:59:14.398 JST LOG: statement: SELECT pg_is_in_recovery() 500710 2022-06-28 11:59:14.404 JST LOG: statement: DISCARD ALL 500717 2022-06-28 11:59:14.415 JST LOG: statement: SET statement_timeout To 0 500717 2022-06-28 11:59:14.416 JST LOG: statement: SELECT pgpool_recovery('basebackup.sh', 'localhost', '/home/t-ishii/work/Pgpool-II/current/z/data1', '11002', 1, '11003', 'localhost') 500639 2022-06-28 11:59:14.466 JST LOG: checkpoint starting: immediate force wait 500639 2022-06-28 11:59:14.568 JST LOG: checkpoint complete: wrote 763 buffers (4.7%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.018 s, sync=0.034 s, total=0.103 s; sync files=193, longest=0.003 s, average=0.001 s; distance=9366 kB, estimate=9366 kB 500798 2022-06-28 11:59:15.157 JST LOG: starting PostgreSQL 15beta1 on x86_64-pc-linux-gnu, compiled by gcc (Ubuntu 9.4.0-1ubuntu1~20.04.1) 9.4.0, 64-bit 500798 2022-06-28 11:59:15.158 JST LOG: listening on IPv4 address "0.0.0.0", port 11003 500798 2022-06-28 11:59:15.158 JST LOG: listening on IPv6 address "::", port 11003 500798 2022-06-28 11:59:15.163 JST LOG: listening on Unix socket "/tmp/.s.PGSQL.11003" 500802 2022-06-28 11:59:15.170 JST LOG: database system was interrupted; last known up at 2022-06-28 11:59:14 JST 500802 2022-06-28 11:59:15.358 JST LOG: entering standby mode 500802 2022-06-28 11:59:15.369 JST LOG: restored log file "000000010000000000000002" from archive 500802 2022-06-28 11:59:15.402 JST LOG: redo starts at 0/2000028 500802 2022-06-28 11:59:15.406 JST LOG: consistent recovery state reached at 0/2000100 500798 2022-06-28 11:59:15.406 JST LOG: database system is ready to accept read-only connections 500811 2022-06-28 11:59:15.412 JST LOG: started streaming WAL from primary at 0/3000000 on timeline 1 500815 2022-06-28 11:59:17.928 JST LOG: statement: SELECT pg_is_in_recovery() 500815 2022-06-28 11:59:17.929 JST LOG: statement: SELECT current_setting('server_version_num') 500825 2022-06-28 11:59:17.969 JST LOG: statement: SELECT pg_is_in_recovery() 500835 2022-06-28 11:59:17.998 JST LOG: statement: SELECT pg_is_in_recovery() 500811 2022-06-28 11:59:19.173 JST LOG: replication terminated by primary server 500811 2022-06-28 11:59:19.173 JST DETAIL: End of WAL reached on timeline 1 at 0/40000A0. 500811 2022-06-28 11:59:19.173 JST 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 500802 2022-06-28 11:59:19.177 JST LOG: invalid record length at 0/40000A0: wanted 24, got 0 500847 2022-06-28 11:59:19.179 JST FATAL: could not connect to the primary server: connection to server at "localhost" (127.0.0.1), port 11002 failed: Connection refused Is the server running on that host and accepting TCP/IP connections? 500802 2022-06-28 11:59:19.181 JST LOG: waiting for WAL to become available at 0/40000B8 500798 2022-06-28 11:59:19.210 JST LOG: received fast shutdown request 500798 2022-06-28 11:59:19.212 JST LOG: aborting any active transactions 500800 2022-06-28 11:59:19.214 JST LOG: shutting down 500800 2022-06-28 11:59:19.214 JST LOG: restartpoint starting: shutdown immediate 500800 2022-06-28 11:59:19.236 JST LOG: restartpoint complete: wrote 2 buffers (0.0%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.006 s, sync=0.003 s, total=0.023 s; sync files=1, longest=0.003 s, average=0.003 s; distance=32768 kB, estimate=32768 kB 500800 2022-06-28 11:59:19.236 JST LOG: recovery restart point at 0/4000028 500798 2022-06-28 11:59:19.238 JST LOG: database system is shut down