server.log

application/octet-stream
Filename: server.log
Type: application/octet-stream
Part: 1
Message: Re: BUG #18897: Logical replication conflict after using pg_createsubscriber under heavy load
2025-07-20 16:50:17.924 IST [1780326] port=5340 ubuntu@test_db/[unknown] LOG:  statement: SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('sub', 'pgoutput', false, false, false)
2025-07-20 16:50:17.976 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  snapshot of 2+0 running transaction ids (lsn 0/3C81418 oldest xid 1351 latest complete 1350 next xid 1353)
2025-07-20 16:50:17.982 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  xmin required by slots: data 0, catalog 1351
2025-07-20 16:50:18.001 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  find_in_dynamic_libpath: trying "/home/ubuntu/Project/inst/pg_20_7_debug/lib/postgresql/pgoutput"
2025-07-20 16:50:18.001 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  find_in_dynamic_libpath: trying "/home/ubuntu/Project/inst/pg_20_7_debug/lib/postgresql/pgoutput.so"
2025-07-20 16:50:18.001 IST [1780326] port=5340 ubuntu@test_db/[unknown] LOG:  #### DecodingContextFindStartpoint called inside create_logical_replication_slot
2025-07-20 16:50:18.001 IST [1780326] port=5340 ubuntu@test_db/[unknown] STATEMENT:  SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('sub', 'pgoutput', false, false, false)
2025-07-20 16:50:18.001 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  searching for logical decoding starting point, starting at 0/3C811F8
2025-07-20 16:50:18.001 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  switched to timeline 1 valid until 0/0
2025-07-20 16:50:18.002 IST [1780326] port=5340 ubuntu@test_db/[unknown] LOG:  logical decoding found initial starting point at 0/3C813D8
2025-07-20 16:50:18.002 IST [1780326] port=5340 ubuntu@test_db/[unknown] DETAIL:  Waiting for transactions (approximately 2) older than 1353 to end.
2025-07-20 16:50:18.002 IST [1780326] port=5340 ubuntu@test_db/[unknown] STATEMENT:  SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('sub', 'pgoutput', false, false, false)
2025-07-20 16:50:18.003 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  snapshot of 8+0 running transaction ids (lsn 0/3C979A0 oldest xid 1354 latest complete 1353 next xid 1362)
2025-07-20 16:50:18.003 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  purged committed transactions from 0 to 0, xmin: 1351, xmax: 1353
2025-07-20 16:50:18.003 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  xmin: 1351, xmax: 1353, oldest running: 1351, oldest xmin: 1351
2025-07-20 16:50:18.007 IST [1780326] port=5340 ubuntu@test_db/[unknown] LOG:  logical decoding found initial consistent point at 0/3C97948
2025-07-20 16:50:18.007 IST [1780326] port=5340 ubuntu@test_db/[unknown] DETAIL:  Waiting for transactions (approximately 8) older than 1362 to end.
2025-07-20 16:50:18.007 IST [1780326] port=5340 ubuntu@test_db/[unknown] STATEMENT:  SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('sub', 'pgoutput', false, false, false)
2025-07-20 16:50:18.031 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  snapshot of 5+0 running transaction ids (lsn 0/3CBCCA0 oldest xid 1365 latest complete 1364 next xid 1370)
2025-07-20 16:50:18.031 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  purged committed transactions from 0 to 0, xmin: 1354, xmax: 1353
2025-07-20 16:50:18.031 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  xmin: 1354, xmax: 1353, oldest running: 1354, oldest xmin: 1354
2025-07-20 16:50:18.031 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  got new catalog xmin 1354 at 0/3C97948
2025-07-20 16:50:18.031 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  aborting old transaction 1352
2025-07-20 16:50:18.031 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  aborting old transaction 1351
2025-07-20 16:50:18.032 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  aborting old transaction 1353
2025-07-20 16:50:18.038 IST [1780326] port=5340 ubuntu@test_db/[unknown] LOG:  logical decoding found consistent point at 0/3CBCC58
2025-07-20 16:50:18.038 IST [1780326] port=5340 ubuntu@test_db/[unknown] DETAIL:  There are no old transactions anymore.
2025-07-20 16:50:18.038 IST [1780326] port=5340 ubuntu@test_db/[unknown] STATEMENT:  SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('sub', 'pgoutput', false, false, false)
2025-07-20 16:50:18.038 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  purged committed transactions from 0 to 0, xmin: 1365, xmax: 1353
2025-07-20 16:50:18.038 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  xmin: 1365, xmax: 1353, oldest running: 1365, oldest xmin: 1354
2025-07-20 16:50:18.039 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  aborting old transaction 1354
2025-07-20 16:50:18.039 IST [1780326] port=5340 ubuntu@test_db/[unknown] DEBUG:  aborting old transaction 1355
2025-07-20 16:50:18.039 IST [1780326] port=5340 ubuntu@test_db/[unknown] LOG:  #### confirmed_flush =  0/03CBCCA0 inside DecodingContextFindStartpoint
2025-07-20 16:50:18.039 IST [1780326] port=5340 ubuntu@test_db/[unknown] STATEMENT:  SELECT lsn FROM pg_catalog.pg_create_logical_replication_slot('sub', 'pgoutput', false, false, false)