24210 2022-06-28 02:19:12.074 UTC LOG: starting PostgreSQL 15devel on x86_64-pc-linux-gnu, compiled by gcc (GCC) 4.8.5 20150623 (Red Hat 4.8.5-44), 64-bit 24210 2022-06-28 02:19:12.074 UTC LOG: listening on IPv4 address "0.0.0.0", port 11002 24210 2022-06-28 02:19:12.074 UTC LOG: listening on IPv6 address "::", port 11002 24210 2022-06-28 02:19:12.080 UTC LOG: listening on Unix socket "/tmp/.s.PGSQL.11002" 24214 2022-06-28 02:19:12.087 UTC LOG: database system was shut down at 2022-06-28 02:19:10 UTC 24210 2022-06-28 02:19:12.095 UTC LOG: database system is ready to accept connections 24221 2022-06-28 02:19:12.164 UTC LOG: statement: CREATE EXTENSION pgpool_regclass; 24221 2022-06-28 02:19:12.170 UTC LOG: statement: CREATE EXTENSION pgpool_recovery; 24221 2022-06-28 02:19:12.173 UTC LOG: statement: CREATE DATABASE test; 24260 2022-06-28 02:19:12.428 UTC LOG: statement: SELECT pg_is_in_recovery() 24260 2022-06-28 02:19:12.429 UTC LOG: statement: SELECT current_setting('server_version_num') 24265 2022-06-28 02:19:12.436 UTC LOG: statement: SELECT pg_is_in_recovery() 24267 2022-06-28 02:19:13.288 UTC LOG: statement: SELECT version() 24269 2022-06-28 02:19:13.298 UTC LOG: statement: SELECT pg_is_in_recovery() 24267 2022-06-28 02:19:13.300 UTC LOG: statement: DISCARD ALL 24281 2022-06-28 02:19:13.327 UTC LOG: statement: SELECT current_setting('server_version_num') 24281 2022-06-28 02:19:13.328 UTC LOG: statement: SELECT pg_current_wal_lsn() 24281 2022-06-28 02:19:13.328 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24279 2022-06-28 02:19:13.331 UTC LOG: statement: DISCARD ALL 24281 2022-06-28 02:19:13.333 UTC LOG: statement: SELECT pg_is_in_recovery() 24285 2022-06-28 02:19:13.347 UTC LOG: statement: SELECT pg_is_in_recovery() 24283 2022-06-28 02:19:13.353 UTC LOG: statement: DISCARD ALL 24289 2022-06-28 02:19:13.362 UTC LOG: statement: SET statement_timeout To 0 24289 2022-06-28 02:19:13.363 UTC LOG: statement: SELECT pgpool_recovery('basebackup.sh', 'localhost', '$TESTDIR/data1', '11002', 1, '11003', 'localhost') 24212 2022-06-28 02:19:13.454 UTC LOG: checkpoint starting: immediate force wait 24212 2022-06-28 02:19:13.594 UTC LOG: checkpoint complete: wrote 797 buffers (4.9%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.040 s, sync=0.025 s, total=0.141 s; sync files=199, longest=0.005 s, average=0.001 s; distance=9006 kB, estimate=9006 kB 24289 2022-06-28 02:19:14.049 UTC LOG: statement: SELECT pgpool_remote_start('localhost', '$TESTDIR/data1') 24314 2022-06-28 02:19:23.337 UTC LOG: statement: SELECT pg_current_wal_lsn() 24314 2022-06-28 02:19:23.338 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24314 2022-06-28 02:19:23.342 UTC LOG: statement: SELECT pg_is_in_recovery() 24316 2022-06-28 02:19:33.347 UTC LOG: statement: SELECT pg_current_wal_lsn() 24316 2022-06-28 02:19:33.347 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24316 2022-06-28 02:19:33.352 UTC LOG: statement: SELECT pg_is_in_recovery() 24326 2022-06-28 02:19:43.356 UTC LOG: statement: SELECT pg_current_wal_lsn() 24326 2022-06-28 02:19:43.357 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24326 2022-06-28 02:19:43.361 UTC LOG: statement: SELECT pg_is_in_recovery() 24328 2022-06-28 02:19:53.365 UTC LOG: statement: SELECT pg_current_wal_lsn() 24328 2022-06-28 02:19:53.366 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24328 2022-06-28 02:19:53.371 UTC LOG: statement: SELECT pg_is_in_recovery() 24341 2022-06-28 02:20:03.375 UTC LOG: statement: SELECT pg_current_wal_lsn() 24341 2022-06-28 02:20:03.376 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24341 2022-06-28 02:20:03.380 UTC LOG: statement: SELECT pg_is_in_recovery() 24344 2022-06-28 02:20:13.385 UTC LOG: statement: SELECT pg_current_wal_lsn() 24344 2022-06-28 02:20:13.386 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24344 2022-06-28 02:20:13.390 UTC LOG: statement: SELECT pg_is_in_recovery() 24346 2022-06-28 02:20:23.394 UTC LOG: statement: SELECT pg_current_wal_lsn() 24346 2022-06-28 02:20:23.395 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24346 2022-06-28 02:20:23.400 UTC LOG: statement: SELECT pg_is_in_recovery() 24349 2022-06-28 02:20:33.404 UTC LOG: statement: SELECT pg_current_wal_lsn() 24349 2022-06-28 02:20:33.405 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24349 2022-06-28 02:20:33.410 UTC LOG: statement: SELECT pg_is_in_recovery() 24359 2022-06-28 02:20:43.414 UTC LOG: statement: SELECT pg_current_wal_lsn() 24359 2022-06-28 02:20:43.415 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24359 2022-06-28 02:20:43.419 UTC LOG: statement: SELECT pg_is_in_recovery() 24362 2022-06-28 02:20:53.423 UTC LOG: statement: SELECT pg_current_wal_lsn() 24362 2022-06-28 02:20:53.424 UTC LOG: statement: SELECT application_name, state, sync_state FROM pg_stat_replication 24362 2022-06-28 02:20:53.428 UTC LOG: statement: SELECT pg_is_in_recovery() 24367 2022-06-28 02:20:59.102 UTC LOG: statement: SELECT pg_is_in_recovery() 24365 2022-06-28 02:20:59.104 UTC LOG: statement: DISCARD ALL 24374 2022-06-28 02:20:59.127 UTC LOG: statement: SELECT pg_is_in_recovery() 24372 2022-06-28 02:20:59.129 UTC LOG: statement: DISCARD ALL 24210 2022-06-28 02:21:00.138 UTC LOG: received fast shutdown request 24210 2022-06-28 02:21:00.141 UTC LOG: aborting any active transactions 24218 2022-06-28 02:21:00.142 UTC FATAL: terminating background worker "lrg launcher" due to administrator command 24210 2022-06-28 02:21:00.142 UTC LOG: background worker "lrg launcher" (PID 24218) exited with exit code 1 24210 2022-06-28 02:21:00.142 UTC LOG: background worker "logical replication launcher" (PID 24219) exited with exit code 1 24212 2022-06-28 02:21:00.143 UTC LOG: shutting down 24212 2022-06-28 02:21:00.256 UTC LOG: checkpoint starting: shutdown immediate 24212 2022-06-28 02:21:00.343 UTC LOG: checkpoint complete: wrote 54 buffers (0.3%); 0 WAL file(s) added, 0 removed, 2 recycled; write=0.003 s, sync=0.018 s, total=0.089 s; sync files=15, longest=0.015 s, average=0.002 s; distance=32768 kB, estimate=32768 kB 24210 2022-06-28 02:21:00.351 UTC LOG: database system is shut down