[pgpool-general: 9305] Re: pgpool Connection issue: ERROR: unable to read message kind

Mukesh Tanuku mukesh.postgres at gmail.com
Wed Dec 25 12:52:32 JST 2024 

I revalidated everything and now the connection is established successfully
to the DB via pgpool, but falling with the actual error.  I have tried
using remote_apply, still seeing the same error.

Error in Virtual User 1: Error: Connection to database failed
connection to server at "10.35.8.30", port 9999 failed: ERROR:  unable to
read message kind
DETAIL:  kind does not match between main(53) slot[1] (45)

here are my parameters


*pgpool.conf*
listen_addresses = '*'
port = 9999
pcp_listen_addresses = '*'
backend_hostname0 = '10.35.8.30'
backend_port0 = 5432
backend_weight0 = 1
backend_hostname1 = '10.35.8.31'
backend_port1 = 5432
backend_weight1 = 1
load_balance_mode = 'on'
master_slave_mode = 'on'
master_slave_sub_mode = 'stream'
sr_check_period = 10
sr_check_user = 'monitor'
sr_check_password = 'Monitor at 321'
sr_check_database = 'monitor'
delay_threshold = 10240
allow_clear_text_frontend_auth = 'on'
pid_file_name = '/u01/app/admin/Postgresql/pgpool/pgpool.pid'
 # pgpool pid location
logging_collector = on
log_directory = '/u01/app/admin/Data//PG_LOGS'                  #pgpool log
location
log_filename = 'pgpool.log'
num_init_children = 30
enable_pool_hba = on
pool_passwd = '/usr/local/etc/pool_passwd'
health_check_period = 30
health_check_timeout = 20
health_check_user = 'pgbigboss'
health_check_password = ''
health_check_database = 'postgres'
health_check_max_retries = 3
health_check_retry_delay = 1
connect_timeout = 10000
statement_level_load_balance = on
use_watchdog = on
delegate_ip = '10.35.8.4'
if_up_cmd = '/usr/bin/sudo /sbin/ip addr add $_IP_$/21 dev eth0 label
eth0:1'
if_down_cmd = '/usr/bin/sudo /sbin/ip addr del $_IP_$/21 dev eth0'
arping_cmd = '/usr/bin/sudo /usr/sbin/arping -U $_IP_$ -w 1 -I eth0'


hostname0 = '10.35.8.30'
wd_port0 = 9000
pgpool_port0 = 9999

hostname1 = '10.35.8.31'
wd_port1 = 9000
pgpool_port1 = 9999

heartbeat_hostname0 = '10.35.8.30'
heartbeat_port0 = 9694
heartbeat_device0 = ''

heartbeat_hostname1 = '10.35.8.31'
heartbeat_port1 = 9694
heartbeat_device1 = ''

wd_heartbeat_keepalive = 2
wd_heartbeat_deadtime = 30
#trusted_servers = ''
trusted_server_command = 'ping -q -c3 3 %h'
backend_clustering_mode = 'streaming_replication'
enable_consensus_with_half_votes = on
failover_require_consensus = on
failover_when_quorum_exists = on
log_min_messages = DEBUG5
log_per_node_statement = on
log_hostname = on
log_connections = on
log_error_verbosity = VERBOSE


*postgresql.conf*
listen_addresses = '*'
port = 5432
superuser_reserved_connections = 3     # (change requires restart)
dynamic_shared_memory_type = posix      # the default is usually the first
option
min_dynamic_shared_memory = 500MB        # (change requires restart)
wal_level = replica
synchronous_commit = remote_apply
wal_log_hints = on
max_replication_slots = 10
wal_keep_size = 2048             # in megabytes; 0 disables
hot_standby = on
log_destination = 'stderr'
        # Valid values are combinations of
logging_collector = on                  # Enable capturing of stderr,
jsonlog,
log_directory = '/u01/app/admin/Data/PG_LOGS/'                           #
directory where log files are written,
log_filename = 'postgresql-%Y-%m-%d_%H%M%S.log' # log file name pattern,
log_file_mode = 0600                    # creation mode for log files,
log_rotation_age = 7d                   # Automatic rotation of logfiles
will
log_timezone = 'Etc/UTC'
autovacuum = on                        # Enable autovacuum subprocess?  'on'
autovacuum_max_workers = 3             # max number of autovacuum
subprocesses
idle_in_transaction_session_timeout = 60000        # in milliseconds, 0 is
disabled
idle_session_timeout = 300000               # in milliseconds, 0 is disabled
datestyle = 'iso, mdy'
timezone = 'Etc/UTC'
lc_messages = 'en_US.UTF-8'                     # locale for system error
message
lc_monetary = 'en_US.UTF-8'                     # locale for monetary
formatting
lc_numeric = 'en_US.UTF-8'                      # locale for number
formatting
lc_time = 'en_US.UTF-8'                         # locale for time formatting
default_text_search_config = 'pg_catalog.english'
max_connections =  40
shared_buffers = 2GB
effective_cache_size = 6GB
maintenance_work_mem = 512MB
checkpoint_completion_target = 0.9
wal_buffers = 16MB
default_statistics_target = 100
random_page_cost = 4
effective_io_concurrency = 2
work_mem = 51MB
min_wal_size = 2GB
max_wal_size = 8GB
max_worker_processes = 2
max_parallel_workers_per_gather = 2
max_parallel_workers = 2
max_parallel_maintenance_workers = 2
shared_preload_libraries = 'pg_stat_statements,repmgr'
pg_stat_statements.track = all
pg_stat_statements.max = 10000
track_io_timing = on



*pgpool.log*
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:  processing
ReadyForQuery
2024-12-25 03:20:40.968: [unknown] pid 2485248: DETAIL:  transaction state
of node 0 'I'(49)
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:2223
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: kind:Z po:0
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: length:4 po:1
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:  pool_stream.c:481
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: kind:I po:5
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 6
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:
 pool_query_context_destroy: query context:0x84e448 query: "CREATE DATABASE
"tpcc" OWNER "tpcc""
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:109
2024-12-25 03:20:40.968: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.968: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  processing frontend
response
2024-12-25 03:20:40.970: [unknown] pid 2485248: DETAIL:  received kind
'Q'(51) from frontend
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:2732
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  session context:
clearing doing extended query messaging. DONE
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:341
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  invoking the
standard parser
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:  parser.c:101
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  session context:
setting query in progress. DONE
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:253
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  Pgversion: local
cache returned
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_pg_utils.c:707
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  decide where to
send the query
2024-12-25 03:20:40.970: [unknown] pid 2485248: DETAIL:  destination = 3
for query= "commit"
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:1975
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOG:  DB node id: 0 backend
pid: 2486391 statement: commit
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3674
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  pool_write: to
backend: 0 kind:Q
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:  pool_stream.c:467
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 12
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  waiting for query
response
2024-12-25 03:20:40.970: [unknown] pid 2485248: DETAIL:  waiting for
backend:0 to complete the query
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:539
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:  pool_read: read 18
bytes from backend 0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:  pool_stream.c:199
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.970: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.970: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  main node id: 0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3364
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  backend:0 kind:'C'
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3426
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  backend:0 of 2
kind = 'C'
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3491
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 read_kind_from_backend max_count:1.000000 num_executed_nodes:1
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3507
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  processing backend
response
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  received kind
'C'(43) from backend
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3008
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: kind:C po:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: length:4 po:1
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:481
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: length:7 po:5
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:481
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 12
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  session context:
setting command success. DONE
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:930
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  main node id: 0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3364
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  backend:0 kind:'Z'
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3426
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  backend:0 of 2
kind = 'Z'
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3491
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 read_kind_from_backend max_count:1.000000 num_executed_nodes:1
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3507
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  processing backend
response
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  received kind
'Z'(5a) from backend
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3008
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  processing backend
response
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  Ready For Query
received
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3026
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  session context:
clearing ignore till sync. DONE
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:374
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  reading message
length
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  slot: 0 length: 5
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:4486
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  processing
ReadyForQuery
2024-12-25 03:20:40.971: [unknown] pid 2485248: DETAIL:  transaction state
of node 0 'I'(49)
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:2223
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: kind:Z po:0
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: length:4 po:1
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:481
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_write: to
frontend: kind:I po:5
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 6
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  session context:
clearing writing transaction. DONE
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:736
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  session context:
clearing failed transaction. DONE
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:774
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  session context:
clearing failed transaction. DONE
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:807
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:
 pool_query_context_destroy: query context:0x84e448 query: "commit"
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:109
2024-12-25 03:20:40.971: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.971: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  processing frontend
response
2024-12-25 03:20:40.973: [unknown] pid 2485248: DETAIL:  received kind
'X'(58) from frontend
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:2732
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  session context:
clearing doing extended query messaging. DONE
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:341
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  Frontend terminated
2024-12-25 03:20:40.973: [unknown] pid 2485248: DETAIL:  received message
kind 'X' from frontend
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:2808
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  session context:
clearing doing extended query messaging. DONE
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:341
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  session context:
clearing doing extended query messaging. DONE
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:341
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  invoking the
standard parser
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:  parser.c:101
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  session context:
setting query in progress. DONE
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:253
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  Pgversion: local
cache returned
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_pg_utils.c:707
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  decide where to
send the query
2024-12-25 03:20:40.973: [unknown] pid 2485248: DETAIL:  destination = 3
for query= "DISCARD ALL"
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:1975
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOG:  DB node id: 0 backend
pid: 2486391 statement: DISCARD ALL
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3674
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  pool_write: to
backend: 0 kind:Q
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:  pool_stream.c:467
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 17
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  waiting for query
response
2024-12-25 03:20:40.973: [unknown] pid 2485248: DETAIL:  waiting for
backend:0 to complete the query
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:539
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  pool_read: read 23
bytes from backend 0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:  pool_stream.c:199
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  extract_message:
kind: C
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:4489
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  extract_message:
kind: C
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:4489
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  extract_message:
kind: C
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:4489
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  extract_message:
kind: C
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:4489
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOG:  DB node id: 1 backend
pid: 2277851 statement: DISCARD ALL
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3674
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  pool_write: to
backend: 1 kind:Q
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:  pool_stream.c:467
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 17
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.973: [unknown] pid 2485248: DEBUG:  waiting for query
response
2024-12-25 03:20:40.973: [unknown] pid 2485248: DETAIL:  waiting for
backend:1 to complete the query
2024-12-25 03:20:40.973: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:539
2024-12-25 03:20:40.974: [unknown] pid 2485248: DEBUG:  pool_read: read 23
bytes from backend 1
2024-12-25 03:20:40.974: [unknown] pid 2485248: LOCATION:  pool_stream.c:199
2024-12-25 03:20:40.974: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.974: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.974: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.974: [unknown] pid 2485248: DETAIL:  main node id: 0
2024-12-25 03:20:40.974: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3364
2024-12-25 03:20:40.974: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:0 kind:'C'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3426
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:0 of 2
kind = 'C'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3491
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:1 kind:'C'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3426
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:1 of 2
kind = 'C'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3491
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3507
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  processing backend
response
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  received kind
'C'(43) from backend
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3008
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  session context:
setting command success. DONE
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:930
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  main node id: 0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3364
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:0 kind:'Z'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3426
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:0 of 2
kind = 'Z'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3491
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:1 kind:'Z'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3426
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading backend
data packet kind
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  backend:1 of 2
kind = 'Z'
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3491
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 read_kind_from_backend max_count:2.000000 num_executed_nodes:2
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_process_query.c:3507
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  processing backend
response
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  received kind
'Z'(5a) from backend
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3008
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  processing backend
response
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  Ready For Query
received
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:3026
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  session context:
clearing ignore till sync. DONE
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:374
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading message
length
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  slot: 0 length: 5
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:4486
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_virtual_main_db_node_id: virtual_main_node_id:0
load_balance_node_id:0 PRIMARY_NODE_ID:0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:403
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  reading message
length
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  slot: 1 length: 5
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:4500
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  processing
ReadyForQuery
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  transaction state
of node 0 'I'(49)
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:2223
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  processing
ReadyForQuery
2024-12-25 03:20:40.975: [unknown] pid 2485248: DETAIL:  transaction state
of node 1 'I'(49)
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_proto_modules.c:2223
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 0
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:
 pool_query_context_destroy: query context:0x84e448 query: "DISCARD ALL"
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_query_context.c:109
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.975: [unknown] pid 2485248: DEBUG:  session context:
clearing doing extended query messaging. DONE
2024-12-25 03:20:40.975: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:341
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  setting backend
connection close timer
2024-12-25 03:20:40.976: [unknown] pid 2485248: DETAIL:  close time
1735096840
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:
 pool_connection_pool.c:354
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  session context:
unsetting query in progress. DONE
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:
 pool_session_context.c:267
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  pool_write: to
backend: 0 kind:X
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:  pool_stream.c:467
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 5
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  pool_write: to
backend: 1 kind:X
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:  pool_stream.c:467
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  pool_flush_it:
flush size: 5
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:  RESET ALL CONFIG
VARIABLES
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:
 pool_config_variables.c:5918
2024-12-25 03:20:40.976: [unknown] pid 2485248: DEBUG:
 connection_count_down: number of connected children: 2
2024-12-25 03:20:40.976: [unknown] pid 2485248: LOCATION:  child.c:1427
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  set_config_option
"log_per_node_statement" = "on"
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  set_config_option
"log_hostname" = "on"
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  set_config_option
"log_connections" = "on"
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  set_config_option
"log_error_verbosity" = "VERBOSE"
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  read pgpool node id
file /usr/local/etc/pgpool_node_id
2024-12-25 03:20:40.976: child pid 2485255: DETAIL:  pgpool node id: 0
2024-12-25 03:20:40.976: child pid 2485255: LOCATION:
 pool_config_variables.c:5273
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  initializing pool
configuration
2024-12-25 03:20:40.976: child pid 2485255: DETAIL:  num_backends: 2
total_weight: 2.000000
2024-12-25 03:20:40.976: child pid 2485255: LOCATION:
 pool_config_variables.c:4879
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  initializing pool
configuration
2024-12-25 03:20:40.976: child pid 2485255: DETAIL:  backend 0 weight:
1073741823.500000 flag: 0000
2024-12-25 03:20:40.976: child pid 2485255: LOCATION:
 pool_config_variables.c:4899
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  initializing pool
configuration
2024-12-25 03:20:40.976: child pid 2485255: DETAIL:  backend 1 weight:
1073741823.500000 flag: 0000
2024-12-25 03:20:40.976: child pid 2485255: LOCATION:
 pool_config_variables.c:4899
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  extracting string
tokens from [10.35.8.31] based on ;
2024-12-25 03:20:40.976: child pid 2485255: LOCATION:
 pool_config_variables.c:3076
2024-12-25 03:20:40.976: child pid 2485255: DEBUG:  initializing pool
configuration
2024-12-25 03:20:40.976: child pid 2485255: DETAIL:  extracting string
tokens [token[0]: 10.35.8.31]
2024-12-25 03:20:40.976: child pid 2485255: LOCATION:
 pool_config_variables.c:3082
2024-12-25 03:20:40.977: child pid 2485255: DEBUG:  I am 2485255 accept fd 7
2024-12-25 03:20:40.977: child pid 2485255: LOCATION:  child.c:1910
*....*
2024-12-25 03:20:40.980: child pid 2485255: LOG:  new connection received
2024-12-25 03:20:40.980: child pid 2485255: DETAIL:  connecting host=
dev01windowsjum.internal.cloudapp.net port=49942
2024-12-25 03:20:40.980: child pid 2485255: LOCATION:  child.c:1921
2024-12-25 03:20:40.980: child pid 2485255: DEBUG:  reading startup packet
2024-12-25 03:20:40.980: child pid 2485255: DETAIL:  Protocol Major: 1234
Minor: 5679 database:  user:
2024-12-25 03:20:40.980: child pid 2485255: LOCATION:  child.c:775
2024-12-25 03:20:40.980: child pid 2485255: DEBUG:  selecting backend
connection
2024-12-25 03:20:40.980: child pid 2485255: DETAIL:  SSLRequest from client
2024-12-25 03:20:40.980: child pid 2485255: LOCATION:  child.c:1991
2024-12-25 03:20:40.980: child pid 2485255: DEBUG:  SSL is requested but
SSL support is not available
2024-12-25 03:20:40.980: child pid 2485255: LOCATION:  pool_ssl.c:1105
2024-12-25 03:20:40.980: child pid 2485255: DEBUG:  pool_write: to
frontend: kind:N po:0
2024-12-25 03:20:40.980: child pid 2485255: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.980: child pid 2485255: DEBUG:  pool_flush_it: flush
size: 1
2024-12-25 03:20:40.980: child pid 2485255: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.982: child pid 2485255: DEBUG:  reading startup packet
2024-12-25 03:20:40.982: child pid 2485255: DETAIL:  Protocol Major: 3
Minor: 0 database: tpcc user: tpcc
2024-12-25 03:20:40.982: child pid 2485255: LOCATION:  child.c:775
2024-12-25 03:20:40.982: child pid 2485255: DEBUG:  creating new connection
to backend
2024-12-25 03:20:40.982: child pid 2485255: DETAIL:  connecting 0 backend
2024-12-25 03:20:40.982: child pid 2485255: LOCATION:
 pool_connection_pool.c:884
2024-12-25 03:20:40.982: child pid 2485255: DEBUG:  creating new connection
to backend
2024-12-25 03:20:40.982: child pid 2485255: DETAIL:  connecting 1 backend
2024-12-25 03:20:40.982: child pid 2485255: LOCATION:
 pool_connection_pool.c:884
2024-12-25 03:20:40.983: child pid 2485255: DEBUG:  SSL is requested but
SSL support is not available
2024-12-25 03:20:40.983: child pid 2485255: LOCATION:  pool_ssl.c:1115
2024-12-25 03:20:40.983: child pid 2485255: DEBUG:  pool_flush_it: flush
size: 33
2024-12-25 03:20:40.983: child pid 2485255: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.983: child pid 2485255: DEBUG:  SSL is requested but
SSL support is not available
2024-12-25 03:20:40.983: child pid 2485255: LOCATION:  pool_ssl.c:1115
2024-12-25 03:20:40.983: child pid 2485255: DEBUG:  pool_flush_it: flush
size: 33
2024-12-25 03:20:40.983: child pid 2485255: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_read: read 381
bytes from backend 0
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:199
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_read: read 99
bytes from backend 1
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:199
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  reading message length
2024-12-25 03:20:40.992: child pid 2485255: DETAIL:  slot: 0 length: 8
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:
 pool_proto_modules.c:4486
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  reading message length
2024-12-25 03:20:40.992: child pid 2485255: DETAIL:  slot: 1 length: 8
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:
 pool_proto_modules.c:4500
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  authentication backend
2024-12-25 03:20:40.992: child pid 2485255: DETAIL:  auth kind:0
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_auth.c:419
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_write: to
frontend: kind:R po:0
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_write: to
frontend: length:4 po:1
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:481
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_write: to
frontend: length:4 po:5
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:481
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_flush_it: flush
size: 9
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:650
2024-12-25 03:20:40.992: child pid 2485255: LOG:  pool_read_kind: error
message from 1 th backend:database "tpcc" does not exist
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:
 pool_proto_modules.c:4653
2024-12-25 03:20:40.992: child pid 2485255: ERROR:  unable to read message
kind
2024-12-25 03:20:40.992: child pid 2485255: DETAIL:  kind does not match
between main(53) slot[1] (45)
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:
 pool_proto_modules.c:4658
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_write: to
frontend: kind:E po:0
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:478
2024-12-25 03:20:40.992: child pid 2485255: DEBUG:  pool_write: to
frontend: length:4 po:1
2024-12-25 03:20:40.992: child pid 2485255: LOCATION:  pool_stream.c:481





                                                        List of databases
    Name    |      Owner       | Encoding |   Collate   |    Ctype    | ICU
Locale | Locale Provider |     Access privileges
------------+------------------+----------+-------------+-------------+------------+-----------------+----------------------------
 monitor    | pgbigboss        | UTF8     | en_US.UTF-8 | en_US.UTF-8 |
       | libc            |
 postgres   | pgbigboss        | UTF8     | en_US.UTF-8 | en_US.UTF-8 |
       | libc            | =Tc/pgbigboss             +
            |                  |          |             |             |
       |                 | pgbigboss=CTc/pgbigboss   +
            |                  |          |             |             |
       |                 | readonly_role=c/pgbigboss +
            |                  |          |             |             |
       |                 | readwrite_role=c/pgbigboss
 repmgr     | repmgr_owner     | UTF8     | en_US.UTF-8 | en_US.UTF-8 |
       | libc            |
 template0  | pgbigboss        | UTF8     | en_US.UTF-8 | en_US.UTF-8 |
       | libc            | =c/pgbigboss              +
            |                  |          |             |             |
       |                 | pgbigboss=CTc/pgbigboss
 template1  | pgbigboss        | UTF8     | en_US.UTF-8 | en_US.UTF-8 |
       | libc            | =c/pgbigboss              +
            |                  |          |             |             |
       |                 | pgbigboss=CTc/pgbigboss
 *tpcc       | tpcc             | UTF8     | en_US.UTF-8 | en_US.UTF-8 |
         | libc     *       |
 tst20dec01 | tst20dec01_owner | UTF8     | en_US.UTF-8 | en_US.UTF-8 |
       | libc            |
(7 rows)



*Please help me understand what's happening with the database 'tpcc' that
is been created and trying to access the same with new session gives error
saying 'tpcc' does not exist.*

Regards
Mukesh Tanuku

On Wed, Dec 25, 2024 at 5:28 AM Tatsuo Ishii <ishii at postgresql.org> wrote:

> > Sometimes i see destination = 3, what this meas?
>
> It means the query is sent to both primary and standby(s).
>
> > BTW, Yes, I tried setting it to "remote_apply", but from then on I'm
> > getting connection timed-out errors from client.
> >
> > Error in Virtual User 1: Error: Connection to database failed
> > connection to server at "10.35.8.4", port 6211 failed: Connection timed
> out
> > (0x0000274C/10060)
> > Is the server running on that host and accepting TCP/IP connections?
> >
> > Nothing is been logged in pgpool.log
>
> Can you connect to the PostgreSQL (IP=10.35.8.4, port=6211) using psql?
> If you cannot, the problem is in PostgreSQL, not pgpool.
>
> Best reagards,
> --
> Tatsuo Ishii
> SRA OSS K.K.
> English: http://www.sraoss.co.jp/index_en/
> Japanese:http://www.sraoss.co.jp
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.pgpool.net/pipermail/pgpool-general/attachments/20241225/3b4fb23a/attachment-0001.htm>

More information about the pgpool-general mailing list