Skip to content

Instantly share code, notes, and snippets.

@sebastianwebber

sebastianwebber/README.md

Last active February 13, 2026 20:27
Show Gist options

  • Select an option

    Learn more about clone URLs
  • Save sebastianwebber/2cd25d298bfe85cabcd8d41f83591acb to your computer and use it in GitHub Desktop.

Select an option

Learn more about clone URLs
Save sebastianwebber/2cd25d298bfe85cabcd8d41f83591acb to your computer and use it in GitHub Desktop.
Download ZIP
PG 17.8 standby crashes during WAL replay from 17.5 primary: "could not access status of transaction"
Raw
README.md

17.8 standby crashes during WAL replay from 17.5 primary: "could not access status of transaction"

PostgreSQL version

  • Primary: PostgreSQL 17.5 (Debian 17.5-1.pgdg130+1) on aarch64-unknown-linux-gnu
  • Standby: PostgreSQL 17.8 (Debian 17.8-1.pgdg13+1) on aarch64-unknown-linux-gnu

Platform

Docker containers on macOS (Apple Silicon / aarch64), Docker Desktop

Description

A PostgreSQL 17.8 standby crashes during WAL replay when streaming from a 17.5 primary. The crash occurs after replaying a MultiXact/TRUNCATE_ID record followed by a MultiXact/CREATE_ID record.

Steps to reproduce

  1. Start a 17.5 primary configured for streaming replication
  2. Seed a database with ~2GB of data (tables with foreign key constraints)
  3. Start a 17.5 standby via pg_basebackup, confirm streaming replication
  4. Generate ~500K MultiXact IDs using concurrent SELECT ... FOR SHARE / FOR KEY SHARE on the same rows
  5. Run VACUUM on the multixact-heavy tables (generates TRUNCATE_ID WAL records)
  6. Stop the 17.5 standby
  7. Continue generating ~2M additional MultiXact IDs on the primary (builds WAL backlog)
  8. Start a 17.8 standby on the same data volume — it begins replaying the WAL backlog
  9. Standby crashes during replay

An automated reproducer is available at: https://gist.github.com/sebastianwebber/2cd25d298bfe85cabcd8d41f83591acb(requires Go 1.22+ and Docker, ~10 minutes runtime)

Actual output (standby log)

The standby successfully replays multiple SLRU page boundaries with this pattern:

DEBUG:  next offsets page is not initialized, initializing it now
CONTEXT: WAL redo at 3/28C148D8 for MultiXact/CREATE_ID: 856063 offset 6680130 nmembers 9: ...
DEBUG:  skipping initialization of offsets page 418 because it was already initialized on multixid creation
CONTEXT: WAL redo at 3/28C149B8 for MultiXact/ZERO_OFF_PAGE: 418

This repeats for pages 408 through 418. Then a truncation occurs:

DEBUG:  replaying multixact truncation: offsets [1, 490986), offsets segments [0, 7), members [1, 3864017), members segments [0, 49)
CONTEXT: WAL redo at 3/29D6D548 for MultiXact/TRUNCATE_ID: offsets [1, 490986), members [1, 3864017)

The very next CREATE_ID crashes:

FATAL:  could not access status of transaction 858112
DETAIL: Could not read from file "pg_multixact/offsets/000D" at offset 24576: read too few bytes.
CONTEXT: WAL redo at 3/2A3AB408 for MultiXact/CREATE_ID: 858111 offset 6695072 nmembers 5: 1048228 (sh) 1048271 (keysh) 1048316 (sh) 1048344 (keysh) 1048370 (sh)

LOG:  startup process (PID 29) exited with exit code 1
LOG:  shutting down due to startup process failure

Expected output

The standby should successfully replay all WAL records and reach a consistent streaming state.

Configuration (non-default on primary)

wal_level = replica
max_wal_senders = 10
max_connections = 1200
shared_buffers = 256MB
wal_keep_size = 16GB
autovacuum_multixact_freeze_max_age = 100000
vacuum_multixact_freeze_min_age = 1000
vacuum_multixact_freeze_table_age = 50000

Standby configured with log_min_messages = debug1.

Raw
go.mod
module multixact-bug-reproducer
go 1.22
require (
github.com/charmbracelet/log v0.3.1
github.com/lib/pq v1.10.9
)
require (
github.com/aymanbagabas/go-osc52/v2 v2.0.1 // indirect
github.com/charmbracelet/lipgloss v0.9.1 // indirect
github.com/go-logfmt/logfmt v0.6.0 // indirect
github.com/lucasb-eyer/go-colorful v1.2.0 // indirect
github.com/mattn/go-isatty v0.0.18 // indirect
github.com/mattn/go-runewidth v0.0.15 // indirect
github.com/muesli/reflow v0.3.0 // indirect
github.com/muesli/termenv v0.15.2 // indirect
github.com/rivo/uniseg v0.2.0 // indirect
golang.org/x/exp v0.0.0-20231006140011-7918f672742d // indirect
golang.org/x/sys v0.13.0 // indirect
)
Raw
go.sum
github.com/aymanbagabas/go-osc52/v2 v2.0.1 h1:HwpRHbFMcZLEVr42D4p7XBqjyuxQH5SMiErDT4WkJ2k=
github.com/aymanbagabas/go-osc52/v2 v2.0.1/go.mod h1:uYgXzlJ7ZpABp8OJ+exZzJJhRNQ2ASbcXHWsFqH8hp8=
github.com/charmbracelet/lipgloss v0.9.1 h1:PNyd3jvaJbg4jRHKWXnCj1akQm4rh8dbEzN1p/u1KWg=
github.com/charmbracelet/lipgloss v0.9.1/go.mod h1:1mPmG4cxScwUQALAAnacHaigiiHB9Pmr+v1VEawJl6I=
github.com/charmbracelet/log v0.3.1 h1:TjuY4OBNbxmHWSwO3tosgqs5I3biyY8sQPny/eCMTYw=
github.com/charmbracelet/log v0.3.1/go.mod h1:OR4E1hutLsax3ZKpXbgUqPtTjQfrh1pG3zwHGWuuq8g=
github.com/davecgh/go-spew v1.1.1 h1:vj9j/u1bqnvCEfJOwUhtlOARqs3+rkHYY13jYWTU97c=
github.com/davecgh/go-spew v1.1.1/go.mod h1:J7Y8YcW2NihsgmVo/mv3lAwl/skON4iLHjSsI+c5H38=
github.com/go-logfmt/logfmt v0.6.0 h1:wGYYu3uicYdqXVgoYbvnkrPVXkuLM1p1ifugDMEdRi4=
github.com/go-logfmt/logfmt v0.6.0/go.mod h1:WYhtIu8zTZfxdn5+rREduYbwxfcBr/Vr6KEVveWlfTs=
github.com/lib/pq v1.10.9 h1:YXG7RB+JIjhP29X+OtkiDnYaXQwpS4JEWq7dtCCRUEw=
github.com/lib/pq v1.10.9/go.mod h1:AlVN5x4E4T544tWzH6hKfbfQvm3HdbOxrmggDNAPY9o=
github.com/lucasb-eyer/go-colorful v1.2.0 h1:1nnpGOrhyZZuNyfu1QjKiUICQ74+3FNCN69Aj6K7nkY=
github.com/lucasb-eyer/go-colorful v1.2.0/go.mod h1:R4dSotOR9KMtayYi1e77YzuveK+i7ruzyGqttikkLy0=
github.com/mattn/go-isatty v0.0.18 h1:DOKFKCQ7FNG2L1rbrmstDN4QVRdS89Nkh85u68Uwp98=
github.com/mattn/go-isatty v0.0.18/go.mod h1:W+V8PltTTMOvKvAeJH7IuucS94S2C6jfK/D7dTCTo3Y=
github.com/mattn/go-runewidth v0.0.12/go.mod h1:RAqKPSqVFrSLVXbA8x7dzmKdmGzieGRCM46jaSJTDAk=
github.com/mattn/go-runewidth v0.0.15 h1:UNAjwbU9l54TA3KzvqLGxwWjHmMgBUVhBiTjelZgg3U=
github.com/mattn/go-runewidth v0.0.15/go.mod h1:Jdepj2loyihRzMpdS35Xk/zdY8IAYHsh153qUoGf23w=
github.com/muesli/reflow v0.3.0 h1:IFsN6K9NfGtjeggFP+68I4chLZV2yIKsXJFNZ+eWh6s=
github.com/muesli/reflow v0.3.0/go.mod h1:pbwTDkVPibjO2kyvBQRBxTWEEGDGq0FlB1BIKtnHY/8=
github.com/muesli/termenv v0.15.2 h1:GohcuySI0QmI3wN8Ok9PtKGkgkFIk7y6Vpb5PvrY+Wo=
github.com/muesli/termenv v0.15.2/go.mod h1:Epx+iuz8sNs7mNKhxzH4fWXGNpZwUaJKRS1noLXviQ8=
github.com/pmezard/go-difflib v1.0.0 h1:4DBwDE0NGyQoBHbLQYPwSUPoCMWR5BEzIk/f1lZbAQM=
github.com/pmezard/go-difflib v1.0.0/go.mod h1:iKH77koFhYxTK1pcRnkKkqfTogsbg7gZNVY4sRDYZ/4=
github.com/rivo/uniseg v0.1.0/go.mod h1:J6wj4VEh+S6ZtnVlnTBMWIodfgj8LQOQFoIToxlJtxc=
github.com/rivo/uniseg v0.2.0 h1:S1pD9weZBuJdFmowNwbpi7BJ8TNftyUImj/0WQi72jY=
github.com/rivo/uniseg v0.2.0/go.mod h1:J6wj4VEh+S6ZtnVlnTBMWIodfgj8LQOQFoIToxlJtxc=
github.com/stretchr/testify v1.8.4 h1:CcVxjf3Q8PM0mHUKJCdn+eZZtm5yQwehR5yeSVQQcUk=
github.com/stretchr/testify v1.8.4/go.mod h1:sz/lmYIOXD/1dqDmKjjqLyZ2RngseejIcXlSw2iwfAo=
golang.org/x/exp v0.0.0-20231006140011-7918f672742d h1:jtJma62tbqLibJ5sFQz8bKtEM8rJBtfilJ2qTU199MI=
golang.org/x/exp v0.0.0-20231006140011-7918f672742d/go.mod h1:ldy0pHrwJyGW56pPQzzkH36rKxoZW1tw7ZJpeKx+hdo=
golang.org/x/sys v0.6.0/go.mod h1:oPkhp1MJrh7nUepCBck5+mAzfO9JrbApNNgaTdGDITg=
golang.org/x/sys v0.13.0 h1:Af8nKPmuFypiUBjVoU9V20FiaFXOcuZI21p0ycVYYGE=
golang.org/x/sys v0.13.0/go.mod h1:oPkhp1MJrh7nUepCBck5+mAzfO9JrbApNNgaTdGDITg=
gopkg.in/yaml.v3 v3.0.1 h1:fxVm/GzAzEWqLHuvctI91KS9hhNmmWOoWu0XTYJS7CA=
gopkg.in/yaml.v3 v3.0.1/go.mod h1:K4uyk7z7BCEPqu6E+C64Yfv1cQ7kz7rIZviUmN+EgEM=
Raw
main.go
package main
import (
"context"
"database/sql"
"flag"
"fmt"
"math/rand"
"os"
"os/exec"
"strings"
"sync"
"sync/atomic"
"time"
"github.com/charmbracelet/log"
_ "github.com/lib/pq"
)
const (
preUpgradeTarget = 500_000
postUpgradeTarget = 5_000_000
backlogSize = 2_000_000
pg175Image = "postgres:17.5"
pg178Image = "postgres:17.8"
primaryPort = "5432"
standbyPort = "5433"
dbPassword = "postgres"
dbUser = "postgres"
dbName = "postgres"
)
type config struct {
dataGenWorkers int
primaryContainer string
standbyContainer string
primaryVolume string
standbyVolume string
networkName string
monitorInterval time.Duration
}
type bugReproducer struct {
cfg config
ctx context.Context
cancel context.CancelFunc
logger *log.Logger
currentMultiXact int64
operationsCount int64
startTime time.Time
}
func newBugReproducer() *bugReproducer {
ctx, cancel := context.WithCancel(context.Background())
logger := log.NewWithOptions(os.Stderr, log.Options{
ReportTimestamp: true,
TimeFormat: time.TimeOnly,
})
return &bugReproducer{
cfg: config{
dataGenWorkers: 40,
primaryContainer: "pg-primary-multixact",
standbyContainer: "pg-standby-multixact",
primaryVolume: "pg-data-multixact",
standbyVolume: "pg-standby-multixact-data",
networkName: "pg-multixact-net",
monitorInterval: 30 * time.Second,
},
ctx: ctx,
cancel: cancel,
logger: logger,
startTime: time.Now(),
}
}
func main() {
cleanup := flag.Bool("cleanup", false, "clean up existing containers/volumes before starting")
flag.Parse()
br := newBugReproducer()
defer br.teardown()
if *cleanup {
br.logger.Info("cleaning up existing containers and volumes")
br.dockerCleanup()
br.logger.Info("cleanup done")
}
br.logger.Info("starting PostgreSQL MultiXact bug reproduction (17.5→17.8 upgrade)",
"pre_upgrade_target", fmtInt(preUpgradeTarget),
"post_upgrade_target", fmtInt(postUpgradeTarget),
)
if err := br.run(); err != nil {
br.logger.Fatal("reproduction failed", "err", err)
}
br.showResults()
}
// ---------------------------------------------------------------------------
// Main flow — simulate 17.5 standby upgraded to 17.8
//
// 1. Start 17.5 primary, configure for streaming replication
// 2. pg_basebackup with 17.5, start standby as 17.5
// 3. Generate multixacts to ~500K while 17.5 standby streams
// 4. Stop the 17.5 standby (simulate upgrade window)
// 5. Keep generating multixacts on primary (WAL accumulates)
// 6. Restart standby as 17.8 — it must replay a WAL backlog with
// the buggy commit 8ba61bc0638 from cold shared buffers
// 7. Continue generating — watch for crash
// ---------------------------------------------------------------------------
func (br *bugReproducer) run() error {
if br.tryReusePrimary() {
br.logger.Info("reusing existing primary — skipping setup and seeding")
} else {
if err := br.setupPrimary(); err != nil {
return fmt.Errorf("setup primary: %w", err)
}
}
if err := br.setupStreamingStandby(pg175Image); err != nil {
return fmt.Errorf("setup standby: %w", err)
}
// Phase 2: Generate multixacts with 17.5 standby streaming.
br.logger.Info("phase 2: generating multixacts with 17.5 standby", "target", fmtInt(preUpgradeTarget))
if err := br.generateUntil(preUpgradeTarget); err != nil {
return fmt.Errorf("pre-upgrade generation: %w", err)
}
// Phase 3: Stop standby, keep generating on primary to build WAL backlog,
// then restart as 17.8.
br.logger.Info("phase 3: vacuuming to truncate old multixacts before stopping standby")
if vdb, err := br.openDB(); err == nil {
vdb.Exec("VACUUM (VERBOSE) multixact_test")
vdb.Exec("VACUUM (VERBOSE) child_multixact")
vdb.Close()
}
br.logger.Info("stopping 17.5 standby for upgrade")
if err := br.dockerRun("stop", br.cfg.standbyContainer); err != nil {
return fmt.Errorf("stop standby: %w", err)
}
br.logger.Info("generating multixacts while standby is down (building WAL backlog)")
backlogTarget := atomic.LoadInt64(&br.currentMultiXact) + backlogSize
if err := br.generateUntil(backlogTarget); err != nil {
return fmt.Errorf("backlog generation: %w", err)
}
br.logger.Info("forcing checkpoint before standby upgrade")
if cdb, err := br.openDB(); err == nil {
cdb.Exec("CHECKPOINT")
cdb.Close()
}
br.logger.Info("phase 4: restarting standby as 17.8 (the upgrade)")
if err := br.upgradeStandby(); err != nil {
return fmt.Errorf("upgrade standby: %w", err)
}
// Phase 5: Continue generating while 17.8 standby replays backlog + live WAL.
br.logger.Info("phase 5: generating multixacts with 17.8 standby", "target", fmtInt(postUpgradeTarget))
crashCh := make(chan string, 1)
go br.watchStandby(crashCh)
if err := br.generateMultiXactsOrCrash(postUpgradeTarget, crashCh); err != nil {
return err
}
return nil
}
// ---------------------------------------------------------------------------
// Phase 1: Primary setup
// ---------------------------------------------------------------------------
func (br *bugReproducer) setupPrimary() error {
br.logger.Info("phase 1: setting up 17.5 primary")
if err := br.dockerRun("network", "create", br.cfg.networkName); err != nil {
if !isAlreadyExists(err) {
return fmt.Errorf("create network: %w", err)
}
}
if err := br.dockerRun("volume", "create", br.cfg.primaryVolume); err != nil {
return fmt.Errorf("create volume: %w", err)
}
if err := br.dockerRun("run", "-d",
"--name", br.cfg.primaryContainer,
"--network", br.cfg.networkName,
"-e", "POSTGRES_PASSWORD="+dbPassword,
"-v", br.cfg.primaryVolume+":/var/lib/postgresql/data",
"-p", primaryPort+":5432",
pg175Image,
); err != nil {
return fmt.Errorf("start primary: %w", err)
}
br.logger.Info("primary started", "image", pg175Image)
if err := br.waitReady(); err != nil {
return err
}
br.logger.Info("installing pg_activity in primary container")
if err := br.dockerRun("exec", br.cfg.primaryContainer, "bash", "-c",
"apt-get update -qq && apt-get install -y -qq pg-activity > /dev/null 2>&1"); err != nil {
br.logger.Warn("failed to install pg_activity", "err", err)
}
db, err := br.openDB()
if err != nil {
return err
}
defer db.Close()
for _, s := range []string{
"ALTER SYSTEM SET wal_level = replica",
"ALTER SYSTEM SET max_wal_senders = 10",
"ALTER SYSTEM SET max_connections = 1200",
"ALTER SYSTEM SET max_replication_slots = 10",
"ALTER SYSTEM SET hot_standby = on",
"ALTER SYSTEM SET wal_keep_size = '16GB'",
"ALTER SYSTEM SET shared_buffers = '256MB'",
"ALTER SYSTEM SET autovacuum_multixact_freeze_max_age = 100000",
"ALTER SYSTEM SET vacuum_multixact_freeze_min_age = 1000",
"ALTER SYSTEM SET vacuum_multixact_freeze_table_age = 50000",
} {
if _, err := db.Exec(s); err != nil {
br.logger.Warn("config stmt", "stmt", s, "err", err)
}
}
db.Exec("CREATE USER replicator REPLICATION LOGIN PASSWORD 'replicator'")
db.Exec("SELECT pg_create_physical_replication_slot('standby_slot')")
if err := br.dockerRun("exec", br.cfg.primaryContainer, "bash", "-c",
`echo "host replication replicator 0.0.0.0/0 md5" >> /var/lib/postgresql/data/pg_hba.conf`); err != nil {
return fmt.Errorf("pg_hba: %w", err)
}
br.logger.Info("restarting primary for replication config")
if err := br.dockerRun("restart", br.cfg.primaryContainer); err != nil {
return err
}
time.Sleep(5 * time.Second)
if err := br.waitReady(); err != nil {
return err
}
return br.setupSchema()
}
func (br *bugReproducer) setupSchema() error {
db, err := br.openDB()
if err != nil {
return err
}
defer db.Close()
_, err = db.Exec(`
CREATE TABLE IF NOT EXISTS multixact_test (
id SERIAL PRIMARY KEY,
data TEXT DEFAULT 'initial',
payload TEXT DEFAULT '',
ver INTEGER DEFAULT 0
);
CREATE TABLE IF NOT EXISTS child_multixact (
id SERIAL PRIMARY KEY,
parent_id INTEGER REFERENCES multixact_test(id),
data TEXT DEFAULT 'child',
payload TEXT DEFAULT '',
ver INTEGER DEFAULT 0
);
`)
if err != nil {
return fmt.Errorf("create schema: %w", err)
}
br.logger.Info("seeding multixact_test (100K rows ~400MB)...")
_, err = db.Exec(`
INSERT INTO multixact_test (data, payload)
SELECT 'row_' || g, repeat('x', 2000 + (g % 2000))
FROM generate_series(1, 100000) g
ON CONFLICT DO NOTHING;
`)
if err != nil {
return fmt.Errorf("seed multixact_test: %w", err)
}
br.logger.Info("seeding child_multixact (2M rows ~1.5GB)...")
_, err = db.Exec(`
INSERT INTO child_multixact (parent_id, data, payload)
SELECT (g % 100000) + 1, 'child_' || g, repeat('y', 500 + (g % 500))
FROM generate_series(1, 2000000) g
ON CONFLICT DO NOTHING;
`)
if err != nil {
return fmt.Errorf("seed child_multixact: %w", err)
}
br.logger.Info("creating indexes and analyzing...")
_, err = db.Exec(`
CREATE INDEX IF NOT EXISTS idx_child_parent ON child_multixact(parent_id);
ANALYZE multixact_test;
ANALYZE child_multixact;
`)
if err != nil {
return fmt.Errorf("indexes/analyze: %w", err)
}
br.logger.Info("schema ready")
br.logger.Info("running checkpoint after seeding")
db.Exec("CHECKPOINT")
return nil
}
// ---------------------------------------------------------------------------
// Standby setup — takes a basebackup and starts the standby with the given image
// ---------------------------------------------------------------------------
func (br *bugReproducer) setupStreamingStandby(image string) error {
br.dockerRun("rm", "-f", br.cfg.standbyContainer)
br.dockerRun("volume", "rm", "-f", br.cfg.standbyVolume)
if err := br.dockerRun("volume", "create", br.cfg.standbyVolume); err != nil {
return fmt.Errorf("create standby volume: %w", err)
}
br.logger.Info("taking pg_basebackup", "image", image)
br.dockerRun("rm", "-f", "pg-basebackup-runner")
if err := br.dockerRun("run", "--rm",
"--name", "pg-basebackup-runner",
"--network", br.cfg.networkName,
"-e", "PGPASSWORD=replicator",
"-v", br.cfg.standbyVolume+":/backup",
image,
"pg_basebackup",
"-h", br.cfg.primaryContainer,
"-U", "replicator",
"-D", "/backup",
"-Fp", "-Xs", "-P", "-R",
); err != nil {
return fmt.Errorf("pg_basebackup: %w", err)
}
br.logger.Info("configuring standby")
br.dockerRun("rm", "-f", "pg-standby-setup")
if err := br.dockerRun("run", "-d",
"--name", "pg-standby-setup",
"-v", br.cfg.standbyVolume+":/var/lib/postgresql/data",
image,
"sleep", "infinity",
); err != nil {
return fmt.Errorf("start setup container: %w", err)
}
time.Sleep(2 * time.Second)
cmd := exec.Command("docker", "exec", "pg-standby-setup", "bash", "-c", `
set -e
chown -R postgres:postgres /var/lib/postgresql/data
cat >> /var/lib/postgresql/data/postgresql.conf << 'CONF'
log_min_messages = debug1
log_checkpoints = on
hot_standby = on
max_connections = 800
primary_slot_name = 'standby_slot'
CONF
`)
if out, err := cmd.CombinedOutput(); err != nil {
return fmt.Errorf("configure standby: %w\n%s", err, out)
}
br.dockerRun("rm", "-f", "pg-standby-setup")
return br.startStandby(image)
}
func (br *bugReproducer) startStandby(image string) error {
br.logger.Info("starting standby", "image", image)
if err := br.dockerRun("run", "-d",
"--name", br.cfg.standbyContainer,
"--network", br.cfg.networkName,
"-e", "POSTGRES_PASSWORD="+dbPassword,
"-v", br.cfg.standbyVolume+":/var/lib/postgresql/data",
"-p", standbyPort+":5432",
image,
"postgres",
); err != nil {
return fmt.Errorf("start standby: %w", err)
}
time.Sleep(5 * time.Second)
db, err := br.openDB()
if err != nil {
return err
}
defer db.Close()
var count int
err = db.QueryRow("SELECT count(*) FROM pg_stat_replication WHERE state = 'streaming'").Scan(&count)
if err != nil {
br.logger.Warn("could not check replication status", "err", err)
} else {
br.logger.Info("replication status", "streaming_standbys", count)
}
return nil
}
// ---------------------------------------------------------------------------
// Upgrade: stop 17.5 standby container, remove it, start 17.8 on same volume
// ---------------------------------------------------------------------------
func (br *bugReproducer) upgradeStandby() error {
br.dockerRun("rm", "-f", br.cfg.standbyContainer)
return br.startStandby(pg178Image)
}
// ---------------------------------------------------------------------------
// Generation helpers
// ---------------------------------------------------------------------------
const (
rowRange = 100
groupSize = 5
numGroups = 200
)
func (br *bugReproducer) generateUntil(target int64) error {
genCtx, genCancel := context.WithCancel(br.ctx)
defer genCancel()
var dataWg sync.WaitGroup
for i := range br.cfg.dataGenWorkers {
dataWg.Add(1)
go func() {
defer dataWg.Done()
br.dataWorker(genCtx, i)
}()
}
go br.monitorProgress(genCtx)
var groupWg sync.WaitGroup
for g := range numGroups {
groupWg.Add(1)
go func(gid int) {
defer groupWg.Done()
br.multiXactGroup(genCtx, gid, target)
}(g)
}
groupWg.Wait()
genCancel()
dataWg.Wait()
br.reportProgress()
return nil
}
func (br *bugReproducer) generateMultiXactsOrCrash(target int64, crashCh <-chan string) error {
genCtx, genCancel := context.WithCancel(br.ctx)
defer genCancel()
var dataWg sync.WaitGroup
for i := range br.cfg.dataGenWorkers {
dataWg.Add(1)
go func() {
defer dataWg.Done()
br.dataWorker(genCtx, i)
}()
}
go br.monitorProgress(genCtx)
var groupWg sync.WaitGroup
for g := range numGroups {
groupWg.Add(1)
go func(gid int) {
defer groupWg.Done()
br.multiXactGroup(genCtx, gid, target)
}(g)
}
done := make(chan struct{})
go func() {
groupWg.Wait()
close(done)
}()
select {
case crashLog := <-crashCh:
br.logger.Error("BUG REPRODUCED! Standby crashed during WAL replay", "log", crashLog)
genCancel()
groupWg.Wait()
case <-done:
br.logger.Info("target reached without crash — check standby logs manually")
}
genCancel()
dataWg.Wait()
br.reportProgress()
return nil
}
func (br *bugReproducer) watchStandby(crashCh chan<- string) {
for {
select {
case <-br.ctx.Done():
return
default:
}
cmd := exec.Command("docker", "logs", "--tail", "50", br.cfg.standbyContainer)
out, _ := cmd.CombinedOutput()
logs := string(out)
if isCrash(logs) {
crashCh <- logs
return
}
cmd = exec.Command("docker", "inspect", "-f", "{{.State.Running}}", br.cfg.standbyContainer)
out, err := cmd.CombinedOutput()
if err == nil && strings.TrimSpace(string(out)) == "false" {
cmd = exec.Command("docker", "logs", "--tail", "50", br.cfg.standbyContainer)
out, _ = cmd.CombinedOutput()
crashCh <- string(out)
return
}
time.Sleep(2 * time.Second)
}
}
// ---------------------------------------------------------------------------
// MultiXact generation workers
// ---------------------------------------------------------------------------
func (br *bugReproducer) multiXactGroup(ctx context.Context, groupID int, target int64) {
conns := make([]*sql.DB, groupSize)
for i := range groupSize {
db, err := br.openDB()
if err != nil {
br.logger.Error("group conn failed", "group", groupID, "err", err)
return
}
db.SetMaxOpenConns(1)
db.SetMaxIdleConns(1)
conns[i] = db
}
defer func() {
for _, db := range conns {
db.Close()
}
}()
lockModes := []string{"FOR SHARE", "FOR KEY SHARE"}
rowID := (groupID % rowRange) + 1
for {
select {
case <-ctx.Done():
return
default:
}
if atomic.LoadInt64(&br.currentMultiXact) >= target {
return
}
txs := make([]*sql.Tx, groupSize)
errs := make([]error, groupSize)
for i := range groupSize {
tx, err := conns[i].Begin()
if err != nil {
errs[i] = err
continue
}
txs[i] = tx
mode := lockModes[i%len(lockModes)]
_, errs[i] = tx.Exec("SELECT * FROM multixact_test WHERE id = $1 "+mode, rowID)
}
for i := range groupSize {
if txs[i] == nil || errs[i] != nil {
if txs[i] != nil {
txs[i].Rollback()
}
continue
}
txs[i].Commit()
}
atomic.AddInt64(&br.operationsCount, 1)
rowID++
if rowID > rowRange {
rowID = 1
}
}
}
func (br *bugReproducer) dataWorker(ctx context.Context, id int) {
db, err := br.openDB()
if err != nil {
br.logger.Error("data worker connect failed", "worker", id, "err", err)
return
}
defer db.Close()
i := 0
for {
select {
case <-ctx.Done():
return
default:
}
i++
parentID := rand.Intn(100000) + 1
tx, err := db.Begin()
if err != nil {
time.Sleep(50 * time.Millisecond)
continue
}
switch i % 4 {
case 0:
_, err = tx.Exec("INSERT INTO child_multixact (parent_id, data) VALUES ($1, $2)",
parentID, fmt.Sprintf("dw%d_%d", id, i))
case 1:
_, err = tx.Exec("UPDATE child_multixact SET ver = ver + 1 WHERE parent_id = $1", parentID)
case 2:
_, err = tx.Exec("DELETE FROM child_multixact WHERE parent_id = $1 AND random() < 0.05", parentID)
default:
_, err = tx.Exec("UPDATE multixact_test SET ver = ver + 1 WHERE id = $1", parentID)
}
if err != nil {
tx.Rollback()
continue
}
tx.Commit()
}
}
// ---------------------------------------------------------------------------
// Progress monitor
// ---------------------------------------------------------------------------
func (br *bugReproducer) monitorProgress(ctx context.Context) {
ticker := time.NewTicker(br.cfg.monitorInterval)
defer ticker.Stop()
for {
select {
case <-ctx.Done():
return
case <-ticker.C:
br.reportProgress()
}
}
}
func (br *bugReproducer) reportProgress() {
db, err := br.openDB()
if err != nil {
return
}
defer db.Close()
db.Exec("CHECKPOINT")
var mxid int64
if err := db.QueryRow("SELECT next_multixact_id::text::bigint FROM pg_control_checkpoint()").Scan(&mxid); err != nil {
br.logger.Warn("failed to read multixact id", "err", err)
return
}
atomic.StoreInt64(&br.currentMultiXact, mxid)
ops := atomic.LoadInt64(&br.operationsCount)
elapsed := time.Since(br.startTime).Truncate(time.Second)
pct := float64(mxid) / float64(postUpgradeTarget) * 100
var lagStr string
var lag sql.NullString
err = db.QueryRow("SELECT replay_lag::text FROM pg_stat_replication LIMIT 1").Scan(&lag)
if err == nil && lag.Valid {
lagStr = lag.String
}
br.logger.Info("progress",
"multixact", fmt.Sprintf("%s / %s (%.1f%%)", fmtInt(mxid), fmtInt(postUpgradeTarget), pct),
"rounds", fmtInt(ops),
"elapsed", elapsed,
"replay_lag", lagStr,
)
}
// ---------------------------------------------------------------------------
// Helpers
// ---------------------------------------------------------------------------
func (br *bugReproducer) openDB() (*sql.DB, error) {
dsn := fmt.Sprintf("host=localhost port=%s user=%s password=%s dbname=%s sslmode=disable",
primaryPort, dbUser, dbPassword, dbName)
return sql.Open("postgres", dsn)
}
func (br *bugReproducer) waitReady() error {
br.logger.Info("waiting for primary to be ready")
for range 60 {
db, err := br.openDB()
if err == nil {
if err = db.Ping(); err == nil {
db.Close()
br.logger.Info("primary ready")
return nil
}
db.Close()
}
time.Sleep(time.Second)
}
return fmt.Errorf("primary not ready after 60s")
}
func (br *bugReproducer) dockerRun(args ...string) error {
cmd := exec.Command("docker", args...)
out, err := cmd.CombinedOutput()
if err != nil {
return fmt.Errorf("docker %v: %w\n%s", args, err, out)
}
return nil
}
func (br *bugReproducer) tryReusePrimary() bool {
if err := br.dockerRun("start", br.cfg.primaryContainer); err != nil {
return false
}
if err := br.waitReady(); err != nil {
return false
}
db, err := br.openDB()
if err != nil {
return false
}
defer db.Close()
var count int
if err := db.QueryRow("SELECT count(*) FROM multixact_test").Scan(&count); err != nil || count == 0 {
return false
}
br.logger.Info("existing primary has data", "rows", fmtInt(int64(count)))
br.dockerRun("network", "create", br.cfg.networkName)
br.dockerRun("network", "connect", br.cfg.networkName, br.cfg.primaryContainer)
for _, c := range []string{br.cfg.standbyContainer, "pg-basebackup-runner", "pg-standby-setup"} {
exec.Command("docker", "rm", "-f", c).Run()
}
exec.Command("docker", "volume", "rm", "-f", br.cfg.standbyVolume).Run()
return true
}
func (br *bugReproducer) dockerCleanup() {
for _, c := range []string{
br.cfg.primaryContainer,
br.cfg.standbyContainer,
"pg-basebackup-runner",
"pg-standby-setup",
} {
exec.Command("docker", "rm", "-f", c).Run()
}
for _, v := range []string{
br.cfg.primaryVolume,
br.cfg.standbyVolume,
} {
exec.Command("docker", "volume", "rm", "-f", v).Run()
}
exec.Command("docker", "network", "rm", br.cfg.networkName).Run()
}
func (br *bugReproducer) teardown() {
br.cancel()
br.logger.Info("done — containers left running for inspection",
"primary", br.cfg.primaryContainer,
"standby", br.cfg.standbyContainer,
)
br.logger.Info("to clean up: go run main.go --cleanup")
}
func (br *bugReproducer) showResults() {
final := atomic.LoadInt64(&br.currentMultiXact)
ops := atomic.LoadInt64(&br.operationsCount)
elapsed := time.Since(br.startTime).Truncate(time.Second)
fmt.Println()
fmt.Println("━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━")
fmt.Printf(" MultiXact ID : %s\n", fmtInt(final))
fmt.Printf(" Rounds : %s\n", fmtInt(ops))
fmt.Printf(" Runtime : %v\n", elapsed)
fmt.Println("━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━")
fmt.Println()
fmt.Printf(" docker logs %s # primary\n", br.cfg.primaryContainer)
fmt.Printf(" docker logs %s # standby (crash?)\n", br.cfg.standbyContainer)
fmt.Println()
}
func isCrash(s string) bool {
crashPatterns := []string{
"could not access status of transaction",
"read too few bytes",
"could not read block",
"invalid resource manager ID",
"incorrect checksum",
"SlruPhysicalReadPage",
"PANIC",
}
for _, p := range crashPatterns {
if strings.Contains(s, p) {
return true
}
}
return false
}
func isAlreadyExists(err error) bool {
return err != nil && strings.Contains(err.Error(), "already exists")
}
func fmtInt(n int64) string {
if n < 0 {
return "-" + fmtInt(-n)
}
s := fmt.Sprintf("%d", n)
if len(s) <= 3 {
return s
}
var result []byte
for i, c := range s {
if i > 0 && (len(s)-i)%3 == 0 {
result = append(result, ',')
}
result = append(result, byte(c))
}
return string(result)
}
Raw
primary.log
The files belonging to this database system will be owned by user "postgres".
This user must also own the server process.
The database cluster will be initialized with locale "en_US.utf8".
The default database encoding has accordingly been set to "UTF8".
The default text search configuration will be set to "english".
Data page checksums are disabled.
fixing permissions on existing directory /var/lib/postgresql/data ... ok
creating subdirectories ... ok
selecting dynamic shared memory implementation ... posix
selecting default "max_connections" ... 100
selecting default "shared_buffers" ... 128MB
selecting default time zone ... Etc/UTC
creating configuration files ... ok
running bootstrap script ... ok
performing post-bootstrap initialization ... ok
syncing data to disk ... ok
Success. You can now start the database server using:
pg_ctl -D /var/lib/postgresql/data -l logfile start
initdb: warning: enabling "trust" authentication for local connections
initdb: hint: You can change this by editing pg_hba.conf or using the option -A, or --auth-local and --auth-host, the next time you run initdb.
waiting for server to start....2026-02-13 20:09:08.031 UTC [48] LOG: starting PostgreSQL 17.5 (Debian 17.5-1.pgdg130+1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 14.2.0-19) 14.2.0, 64-bit
2026-02-13 20:09:08.031 UTC [48] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2026-02-13 20:09:08.033 UTC [51] LOG: database system was shut down at 2026-02-13 20:09:07 UTC
2026-02-13 20:09:08.035 UTC [48] LOG: database system is ready to accept connections
done
server started
/usr/local/bin/docker-entrypoint.sh: ignoring /docker-entrypoint-initdb.d/*
waiting for server to shut down...2026-02-13 20:09:08.154 UTC [48] LOG: received fast shutdown request
.2026-02-13 20:09:08.154 UTC [48] LOG: aborting any active transactions
2026-02-13 20:09:08.155 UTC [48] LOG: background worker "logical replication launcher" (PID 54) exited with exit code 1
2026-02-13 20:09:08.155 UTC [49] LOG: shutting down
2026-02-13 20:09:08.155 UTC [49] LOG: checkpoint starting: shutdown immediate
2026-02-13 20:09:08.159 UTC [49] LOG: checkpoint complete: wrote 3 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.004 s; sync files=2, longest=0.001 s, average=0.001 s; distance=0 kB, estimate=0 kB; lsn=0/14ED7B8, redo lsn=0/14ED7B8
2026-02-13 20:09:08.160 UTC [48] LOG: database system is shut down
done
server stopped
PostgreSQL init process complete; ready for start up.
2026-02-13 20:09:08.273 UTC [1] LOG: starting PostgreSQL 17.5 (Debian 17.5-1.pgdg130+1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 14.2.0-19) 14.2.0, 64-bit
2026-02-13 20:09:08.273 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432
2026-02-13 20:09:08.273 UTC [1] LOG: listening on IPv6 address "::", port 5432
2026-02-13 20:09:08.276 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2026-02-13 20:09:08.278 UTC [62] LOG: database system was shut down at 2026-02-13 20:09:08 UTC
2026-02-13 20:09:08.281 UTC [1] LOG: database system is ready to accept connections
2026-02-13 20:09:13.503 UTC [1] LOG: received fast shutdown request
2026-02-13 20:09:13.505 UTC [1] LOG: aborting any active transactions
2026-02-13 20:09:13.505 UTC [3093] FATAL: terminating connection due to administrator command
2026-02-13 20:09:13.506 UTC [1] LOG: background worker "logical replication launcher" (PID 65) exited with exit code 1
2026-02-13 20:09:13.506 UTC [60] LOG: shutting down
2026-02-13 20:09:13.508 UTC [60] LOG: checkpoint starting: shutdown immediate
2026-02-13 20:09:13.524 UTC [60] LOG: checkpoint complete: wrote 7 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.008 s, total=0.018 s; sync files=6, longest=0.002 s, average=0.002 s; distance=3 kB, estimate=3 kB; lsn=0/14EE650, redo lsn=0/14EE650
2026-02-13 20:09:13.525 UTC [1] LOG: database system is shut down
PostgreSQL Database directory appears to contain a database; Skipping initialization
2026-02-13 20:09:13.689 UTC [1] LOG: starting PostgreSQL 17.5 (Debian 17.5-1.pgdg130+1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 14.2.0-19) 14.2.0, 64-bit
2026-02-13 20:09:13.689 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432
2026-02-13 20:09:13.689 UTC [1] LOG: listening on IPv6 address "::", port 5432
2026-02-13 20:09:13.692 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2026-02-13 20:09:13.696 UTC [29] LOG: database system was shut down at 2026-02-13 20:09:13 UTC
2026-02-13 20:09:13.700 UTC [1] LOG: database system is ready to accept connections
2026-02-13 20:09:22.330 UTC [27] LOG: checkpoints are occurring too frequently (9 seconds apart)
2026-02-13 20:09:22.330 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:09:22.341 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:09:25.033 UTC [27] LOG: checkpoint complete: wrote 90 buffers (0.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.421 s, sync=0.258 s, total=2.703 s; sync files=55, longest=0.133 s, average=0.005 s; distance=543816 kB, estimate=543816 kB; lsn=0/42800478, redo lsn=0/228006F0
2026-02-13 20:09:25.091 UTC [27] LOG: checkpoints are occurring too frequently (3 seconds apart)
2026-02-13 20:09:25.091 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:09:25.101 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:09:27.528 UTC [27] LOG: checkpoint complete: wrote 64 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.258 s, sync=0.147 s, total=2.437 s; sync files=5, longest=0.083 s, average=0.030 s; distance=540670 kB, estimate=543501 kB; lsn=0/622A4820, redo lsn=0/43800230
2026-02-13 20:09:27.679 UTC [27] LOG: checkpoints are occurring too frequently (2 seconds apart)
2026-02-13 20:09:27.679 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:09:27.690 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:09:34.572 UTC [27] LOG: checkpoint complete: wrote 72 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.344 s, sync=0.533 s, total=6.894 s; sync files=9, longest=0.391 s, average=0.060 s; distance=540672 kB, estimate=543218 kB; lsn=0/77183D18, redo lsn=0/64800378
2026-02-13 20:09:34.572 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:09:34.773 UTC [27] LOG: checkpoint complete: wrote 31555 buffers (96.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.098 s, sync=0.099 s, total=0.201 s; sync files=22, longest=0.078 s, average=0.005 s; distance=304654 kB, estimate=519362 kB; lsn=0/77183DE8, redo lsn=0/77183D90
2026-02-13 20:09:35.168 UTC [27] LOG: checkpoint starting: force wait
2026-02-13 20:09:35.184 UTC [27] LOG: checkpoint complete: wrote 0 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.017 s; sync files=0, longest=0.000 s, average=0.000 s; distance=14832 kB, estimate=468909 kB; lsn=0/78000080, redo lsn=0/78000028
2026-02-13 20:09:50.543 UTC [27] LOG: checkpoints are occurring too frequently (15 seconds apart)
2026-02-13 20:09:50.543 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:09:50.543 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:09:57.074 UTC [27] LOG: checkpoint complete: wrote 8398 buffers (25.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.579 s, sync=0.910 s, total=6.532 s; sync files=31, longest=0.569 s, average=0.030 s; distance=540999 kB, estimate=540999 kB; lsn=0/B7A4F700, redo lsn=0/99051D20
2026-02-13 20:09:58.175 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:09:58.175 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:09:58.175 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:10:07.536 UTC [27] LOG: checkpoint complete: wrote 8000 buffers (24.4%); 0 WAL file(s) added, 0 removed, 0 recycled; write=8.489 s, sync=0.800 s, total=9.362 s; sync files=22, longest=0.445 s, average=0.037 s; distance=541517 kB, estimate=541517 kB; lsn=0/D8AD6418, redo lsn=0/BA1253B8
2026-02-13 20:10:08.358 UTC [27] LOG: checkpoints are occurring too frequently (10 seconds apart)
2026-02-13 20:10:08.358 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:10:08.358 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:10:16.009 UTC [27] LOG: checkpoint complete: wrote 6988 buffers (21.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.753 s, sync=1.807 s, total=7.652 s; sync files=22, longest=0.656 s, average=0.083 s; distance=539969 kB, estimate=541362 kB; lsn=0/F77D33F0, redo lsn=0/DB075B50
2026-02-13 20:10:16.009 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:10:17.509 UTC [27] LOG: checkpoint complete: wrote 23228 buffers (70.9%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.736 s, sync=0.312 s, total=1.500 s; sync files=56, longest=0.050 s, average=0.006 s; distance=468272 kB, estimate=534053 kB; lsn=0/FBAF1D08, redo lsn=0/F79C1C70
2026-02-13 20:10:24.299 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:10:24.299 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:10:24.299 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:10:35.006 UTC [27] LOG: checkpoint complete: wrote 5894 buffers (18.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=9.592 s, sync=1.069 s, total=10.708 s; sync files=28, longest=0.678 s, average=0.039 s; distance=531615 kB, estimate=533810 kB; lsn=1/381291B8, redo lsn=1/180E9BC8
2026-02-13 20:10:35.149 UTC [27] LOG: checkpoints are occurring too frequently (11 seconds apart)
2026-02-13 20:10:35.149 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:10:35.149 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:11:00.819 UTC [27] LOG: checkpoint complete: wrote 7294 buffers (22.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=7.144 s, sync=18.474 s, total=25.671 s; sync files=21, longest=14.593 s, average=0.880 s; distance=541102 kB, estimate=541102 kB; lsn=1/8123AAB8, redo lsn=1/39155688
2026-02-13 20:11:00.819 UTC [27] LOG: checkpoints are occurring too frequently (25 seconds apart)
2026-02-13 20:11:00.819 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:11:00.819 UTC [27] LOG: checkpoint starting: immediate force wait wal
2026-02-13 20:11:12.910 UTC [27] LOG: checkpoint complete: wrote 15170 buffers (46.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.583 s, sync=9.476 s, total=12.091 s; sync files=40, longest=5.098 s, average=0.237 s; distance=1180860 kB, estimate=1180860 kB; lsn=1/A0574C20, redo lsn=1/81284760
2026-02-13 20:11:13.404 UTC [27] LOG: checkpoints are occurring too frequently (13 seconds apart)
2026-02-13 20:11:13.404 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:11:13.404 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:11:18.028 UTC [27] LOG: checkpoint complete: wrote 16933 buffers (51.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.337 s, sync=2.191 s, total=4.625 s; sync files=22, longest=1.585 s, average=0.100 s; distance=538334 kB, estimate=1116607 kB; lsn=1/B204BD30, redo lsn=1/A203C268
2026-02-13 20:11:18.029 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:11:19.335 UTC [27] LOG: checkpoint complete: wrote 21994 buffers (67.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.650 s, sync=0.585 s, total=1.307 s; sync files=17, longest=0.463 s, average=0.035 s; distance=265087 kB, estimate=1031455 kB; lsn=1/B64110C8, redo lsn=1/B231C1D8
2026-02-13 20:11:20.128 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:11:20.858 UTC [27] LOG: checkpoint complete: wrote 17761 buffers (54.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.398 s, sync=0.310 s, total=0.731 s; sync files=15, longest=0.241 s, average=0.021 s; distance=161244 kB, estimate=944434 kB; lsn=1/BC394080, redo lsn=1/BC093258
2026-02-13 20:12:11.333 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:12:41.369 UTC [27] LOG: checkpoint complete: wrote 17031 buffers (52.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=29.677 s, sync=0.339 s, total=30.036 s; sync files=15, longest=0.281 s, average=0.023 s; distance=540318 kB, estimate=904022 kB; lsn=1/FB0B8A70, redo lsn=1/DD03AB98
2026-02-13 20:12:44.016 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:13:11.174 UTC [27] LOG: checkpoint complete: wrote 11785 buffers (36.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=26.882 s, sync=0.251 s, total=27.159 s; sync files=6, longest=0.128 s, average=0.042 s; distance=541038 kB, estimate=867724 kB; lsn=2/1C08F9E0, redo lsn=1/FE0963E0
2026-02-13 20:13:14.083 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:13:20.808 UTC [27] LOG: checkpoint complete: wrote 5244 buffers (16.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.952 s, sync=0.759 s, total=6.726 s; sync files=14, longest=0.377 s, average=0.055 s; distance=540261 kB, estimate=834978 kB; lsn=2/3DAF23B0, redo lsn=2/1F02F820
2026-02-13 20:13:21.101 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:13:21.101 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:13:21.101 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:13:27.924 UTC [27] LOG: checkpoint complete: wrote 7340 buffers (22.4%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.560 s, sync=1.223 s, total=6.823 s; sync files=27, longest=0.744 s, average=0.046 s; distance=540839 kB, estimate=805564 kB; lsn=2/5F327EF0, redo lsn=2/400596C8
2026-02-13 20:13:28.233 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:13:28.233 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:13:28.233 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:13:34.827 UTC [27] LOG: checkpoint complete: wrote 7256 buffers (22.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.368 s, sync=1.195 s, total=6.594 s; sync files=21, longest=0.606 s, average=0.057 s; distance=540474 kB, estimate=779055 kB; lsn=2/804CF450, redo lsn=2/61028078
2026-02-13 20:13:35.113 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:13:35.113 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:13:35.113 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:13:43.133 UTC [27] LOG: checkpoint complete: wrote 7408 buffers (22.6%); 1 WAL file(s) added, 0 removed, 0 recycled; write=6.507 s, sync=1.453 s, total=8.021 s; sync files=20, longest=0.986 s, average=0.073 s; distance=540941 kB, estimate=755243 kB; lsn=2/A0CCDC30, redo lsn=2/8206B4C0
2026-02-13 20:13:43.570 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:13:43.570 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:13:43.570 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:13:47.241 UTC [27] LOG: checkpoint complete: wrote 7638 buffers (23.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=2.607 s, sync=1.031 s, total=3.671 s; sync files=24, longest=0.609 s, average=0.043 s; distance=540366 kB, estimate=733756 kB; lsn=2/B3822A68, redo lsn=2/A301EF68
2026-02-13 20:13:47.241 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:13:48.357 UTC [27] LOG: checkpoint complete: wrote 21540 buffers (65.7%); 1 WAL file(s) added, 0 removed, 0 recycled; write=0.499 s, sync=0.110 s, total=1.117 s; sync files=26, longest=0.023 s, average=0.005 s; distance=270354 kB, estimate=687416 kB; lsn=2/B6E5DD98, redo lsn=2/B3823B30
2026-02-13 20:13:53.764 UTC [27] LOG: checkpoints are occurring too frequently (6 seconds apart)
2026-02-13 20:13:53.764 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:13:53.764 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:14:00.837 UTC [27] LOG: checkpoint complete: wrote 6626 buffers (20.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.523 s, sync=1.519 s, total=7.074 s; sync files=25, longest=0.760 s, average=0.061 s; distance=532960 kB, estimate=671970 kB; lsn=2/F32C3C58, redo lsn=2/D409BD10
2026-02-13 20:14:01.113 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:14:01.113 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:14:01.113 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:14:08.744 UTC [27] LOG: checkpoint complete: wrote 5940 buffers (18.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.441 s, sync=1.137 s, total=7.632 s; sync files=20, longest=0.626 s, average=0.057 s; distance=540052 kB, estimate=658778 kB; lsn=3/13B15B70, redo lsn=2/F5000D48
2026-02-13 20:14:09.476 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:14:09.476 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:14:09.476 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:14:17.444 UTC [27] LOG: checkpoint complete: wrote 6007 buffers (18.3%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.763 s, sync=1.157 s, total=7.969 s; sync files=20, longest=0.680 s, average=0.058 s; distance=541137 kB, estimate=647014 kB; lsn=3/33FDFBA0, redo lsn=3/16075370
2026-02-13 20:14:17.445 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:14:18.181 UTC [27] LOG: checkpoint complete: wrote 24719 buffers (75.4%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.604 s, sync=0.105 s, total=0.737 s; sync files=18, longest=0.038 s, average=0.006 s; distance=492905 kB, estimate=631603 kB; lsn=3/3711F780, redo lsn=3/341CFA60
2026-02-13 20:14:24.455 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:14:24.455 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:14:24.455 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:14:31.901 UTC [27] LOG: checkpoint complete: wrote 5724 buffers (17.5%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.798 s, sync=1.610 s, total=7.446 s; sync files=24, longest=0.888 s, average=0.068 s; distance=539496 kB, estimate=622392 kB; lsn=3/74172A80, redo lsn=3/550A9D28
2026-02-13 20:14:32.372 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:14:32.372 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:14:32.372 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:14:40.556 UTC [27] LOG: checkpoint complete: wrote 5877 buffers (17.9%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.092 s, sync=2.054 s, total=8.184 s; sync files=20, longest=1.051 s, average=0.103 s; distance=540427 kB, estimate=614196 kB; lsn=3/95836E08, redo lsn=3/7606CB70
2026-02-13 20:14:40.790 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:14:40.790 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:14:40.790 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:14:47.420 UTC [27] LOG: checkpoint complete: wrote 6253 buffers (19.1%); 1 WAL file(s) added, 0 removed, 0 recycled; write=5.401 s, sync=1.180 s, total=6.630 s; sync files=20, longest=0.595 s, average=0.059 s; distance=540531 kB, estimate=606829 kB; lsn=3/B3F81810, redo lsn=3/97049A10
2026-02-13 20:14:47.420 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:14:48.371 UTC [27] LOG: checkpoint complete: wrote 22863 buffers (69.8%); 1 WAL file(s) added, 0 removed, 0 recycled; write=0.728 s, sync=0.184 s, total=0.952 s; sync files=17, longest=0.079 s, average=0.011 s; distance=476399 kB, estimate=593786 kB; lsn=3/B8CADAB0, redo lsn=3/B4185618
2026-02-13 20:14:53.014 UTC [27] LOG: checkpoints are occurring too frequently (6 seconds apart)
2026-02-13 20:14:53.014 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:14:53.014 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:15:02.323 UTC [27] LOG: checkpoint complete: wrote 6157 buffers (18.8%); 0 WAL file(s) added, 0 removed, 0 recycled; write=4.656 s, sync=4.620 s, total=9.310 s; sync files=26, longest=3.728 s, average=0.178 s; distance=539223 kB, estimate=588330 kB; lsn=3/FF076970, redo lsn=3/D501B398
2026-02-13 20:15:02.323 UTC [27] LOG: checkpoints are occurring too frequently (9 seconds apart)
2026-02-13 20:15:02.323 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:15:02.323 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:15:12.742 UTC [27] LOG: checkpoint complete: wrote 6194 buffers (18.9%); 0 WAL file(s) added, 17 removed, 22 recycled; write=5.947 s, sync=4.294 s, total=10.419 s; sync files=23, longest=3.219 s, average=0.187 s; distance=688706 kB, estimate=688706 kB; lsn=4/2876EA78, redo lsn=3/FF0ABE70
2026-02-13 20:15:12.742 UTC [27] LOG: checkpoints are occurring too frequently (10 seconds apart)
2026-02-13 20:15:12.742 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:15:12.742 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:15:20.454 UTC [27] LOG: checkpoint complete: wrote 5913 buffers (18.0%); 0 WAL file(s) added, 5 removed, 29 recycled; write=3.451 s, sync=3.724 s, total=7.712 s; sync files=26, longest=3.038 s, average=0.144 s; distance=686457 kB, estimate=688481 kB; lsn=4/4A080CA8, redo lsn=4/28F0A410
2026-02-13 20:15:20.454 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:15:20.454 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:15:20.454 UTC [27] LOG: checkpoint starting: immediate force wait wal
2026-02-13 20:15:21.918 UTC [27] LOG: checkpoint complete: wrote 19348 buffers (59.0%); 0 WAL file(s) added, 0 removed, 6 recycled; write=0.884 s, sync=0.538 s, total=1.465 s; sync files=24, longest=0.377 s, average=0.023 s; distance=558932 kB, estimate=675526 kB; lsn=4/50B41870, redo lsn=4/4B0DF488
2026-02-13 20:15:26.143 UTC [27] LOG: checkpoints are occurring too frequently (6 seconds apart)
2026-02-13 20:15:26.143 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:15:26.143 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:15:39.155 UTC [27] LOG: checkpoint complete: wrote 5123 buffers (15.6%); 0 WAL file(s) added, 71 removed, 10 recycled; write=4.436 s, sync=8.426 s, total=13.013 s; sync files=27, longest=7.775 s, average=0.313 s; distance=540007 kB, estimate=661974 kB; lsn=4/A1E8B240, redo lsn=4/6C039220
2026-02-13 20:15:39.156 UTC [27] LOG: checkpoints are occurring too frequently (13 seconds apart)
2026-02-13 20:15:39.156 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:15:39.156 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:15:47.793 UTC [27] LOG: checkpoint complete: wrote 7407 buffers (22.6%); 0 WAL file(s) added, 22 removed, 21 recycled; write=4.685 s, sync=3.830 s, total=8.638 s; sync files=34, longest=1.874 s, average=0.113 s; distance=896266 kB, estimate=896266 kB; lsn=4/CCD3F180, redo lsn=4/A2B7BD70
2026-02-13 20:15:47.794 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:15:47.794 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:15:47.794 UTC [27] LOG: checkpoint starting: immediate force wait wal
2026-02-13 20:15:49.381 UTC [27] LOG: checkpoint complete: wrote 21741 buffers (66.3%); 0 WAL file(s) added, 0 removed, 9 recycled; write=0.858 s, sync=0.658 s, total=1.588 s; sync files=18, longest=0.329 s, average=0.037 s; distance=700860 kB, estimate=876726 kB; lsn=4/D5303C00, redo lsn=4/CD7EB0D0
2026-02-13 20:15:53.090 UTC [27] LOG: checkpoints are occurring too frequently (6 seconds apart)
2026-02-13 20:15:53.090 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:15:53.090 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:06.615 UTC [27] LOG: checkpoint complete: wrote 6695 buffers (20.4%); 0 WAL file(s) added, 80 removed, 4 recycled; write=4.151 s, sync=9.151 s, total=13.525 s; sync files=27, longest=6.704 s, average=0.339 s; distance=533058 kB, estimate=842359 kB; lsn=5/29910C28, redo lsn=4/EE07BCC0
2026-02-13 20:16:06.615 UTC [27] LOG: checkpoints are occurring too frequently (13 seconds apart)
2026-02-13 20:16:06.615 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:06.615 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:14.275 UTC [27] LOG: checkpoint complete: wrote 7327 buffers (22.4%); 0 WAL file(s) added, 18 removed, 23 recycled; write=4.496 s, sync=3.011 s, total=7.661 s; sync files=35, longest=1.735 s, average=0.086 s; distance=991166 kB, estimate=991166 kB; lsn=5/52320670, redo lsn=5/2A86B5C0
2026-02-13 20:16:14.276 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:16:14.276 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:14.276 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:19.216 UTC [27] LOG: checkpoint complete: wrote 6933 buffers (21.2%); 0 WAL file(s) added, 0 removed, 27 recycled; write=1.962 s, sync=2.791 s, total=4.941 s; sync files=19, longest=2.165 s, average=0.147 s; distance=662555 kB, estimate=958305 kB; lsn=5/6DE12A18, redo lsn=5/52F72308
2026-02-13 20:16:19.216 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:16:20.847 UTC [27] LOG: checkpoint complete: wrote 20183 buffers (61.6%); 0 WAL file(s) added, 0 removed, 7 recycled; write=0.918 s, sync=0.581 s, total=1.631 s; sync files=23, longest=0.371 s, average=0.026 s; distance=454803 kB, estimate=907954 kB; lsn=5/74B29B40, redo lsn=5/6EB97090
2026-02-13 20:16:25.428 UTC [27] LOG: checkpoints are occurring too frequently (6 seconds apart)
2026-02-13 20:16:25.428 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:25.428 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:32.840 UTC [27] LOG: checkpoint complete: wrote 6617 buffers (20.2%); 0 WAL file(s) added, 30 removed, 30 recycled; write=4.793 s, sync=2.383 s, total=7.413 s; sync files=32, longest=1.279 s, average=0.075 s; distance=528882 kB, estimate=870047 kB; lsn=5/B0AB3DC0, redo lsn=5/8F0139D8
2026-02-13 20:16:32.841 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:16:32.841 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:32.841 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:38.880 UTC [27] LOG: checkpoint complete: wrote 6574 buffers (20.1%); 0 WAL file(s) added, 0 removed, 32 recycled; write=4.377 s, sync=1.426 s, total=6.040 s; sync files=25, longest=0.804 s, average=0.058 s; distance=571557 kB, estimate=840198 kB; lsn=5/D0B10208, redo lsn=5/B1E3D0F8
2026-02-13 20:16:39.033 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:16:39.033 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:39.033 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:45.006 UTC [27] LOG: checkpoint complete: wrote 6289 buffers (19.2%); 0 WAL file(s) added, 0 removed, 32 recycled; write=4.556 s, sync=1.286 s, total=5.974 s; sync files=23, longest=0.713 s, average=0.056 s; distance=526246 kB, estimate=808803 kB; lsn=5/F0ABDE68, redo lsn=5/D20269C0
2026-02-13 20:16:45.349 UTC [27] LOG: checkpoints are occurring too frequently (6 seconds apart)
2026-02-13 20:16:45.349 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:45.349 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:47.002 UTC [27] LOG: checkpoint complete: wrote 12104 buffers (36.9%); 0 WAL file(s) added, 0 removed, 12 recycled; write=1.001 s, sync=0.566 s, total=1.654 s; sync files=15, longest=0.302 s, average=0.038 s; distance=541194 kB, estimate=782042 kB; lsn=5/FC0DFDB8, redo lsn=5/F30A9520
2026-02-13 20:16:47.003 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:16:47.590 UTC [27] LOG: checkpoint complete: wrote 19168 buffers (58.5%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.484 s, sync=0.069 s, total=0.588 s; sync files=14, longest=0.014 s, average=0.005 s; distance=157406 kB, estimate=719579 kB; lsn=5/FF644180, redo lsn=5/FCA60FA0
2026-02-13 20:16:52.628 UTC [27] LOG: checkpoints are occurring too frequently (5 seconds apart)
2026-02-13 20:16:52.628 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:52.628 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:16:58.990 UTC [27] LOG: checkpoint complete: wrote 5636 buffers (17.2%); 0 WAL file(s) added, 12 removed, 31 recycled; write=4.369 s, sync=1.760 s, total=6.362 s; sync files=25, longest=0.859 s, average=0.071 s; distance=530302 kB, estimate=700651 kB; lsn=6/3D606230, redo lsn=6/1D040888
2026-02-13 20:16:58.991 UTC [27] LOG: checkpoints are occurring too frequently (6 seconds apart)
2026-02-13 20:16:58.991 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:16:58.991 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:17:05.668 UTC [27] LOG: checkpoint complete: wrote 5252 buffers (16.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.170 s, sync=0.486 s, total=6.678 s; sync files=23, longest=0.230 s, average=0.022 s; distance=540467 kB, estimate=684632 kB; lsn=6/5CA45F70, redo lsn=6/3E00D668
2026-02-13 20:17:06.097 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:17:06.097 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:17:06.097 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:17:12.635 UTC [27] LOG: checkpoint complete: wrote 5422 buffers (16.5%); 0 WAL file(s) added, 0 removed, 0 recycled; write=4.790 s, sync=1.713 s, total=6.538 s; sync files=18, longest=0.842 s, average=0.096 s; distance=541288 kB, estimate=670298 kB; lsn=6/7E0D3FA0, redo lsn=6/5F0A77A8
2026-02-13 20:17:13.018 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:17:13.018 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:17:13.018 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:17:17.555 UTC [27] LOG: checkpoint complete: wrote 6076 buffers (18.5%); 1 WAL file(s) added, 0 removed, 0 recycled; write=3.260 s, sync=1.230 s, total=4.538 s; sync files=17, longest=0.772 s, average=0.073 s; distance=540327 kB, estimate=657301 kB; lsn=6/95E96768, redo lsn=6/80051608
2026-02-13 20:17:17.555 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:17:18.389 UTC [27] LOG: checkpoint complete: wrote 21722 buffers (66.3%); 1 WAL file(s) added, 0 removed, 0 recycled; write=0.639 s, sync=0.147 s, total=0.834 s; sync files=19, longest=0.071 s, average=0.008 s; distance=360331 kB, estimate=627604 kB; lsn=6/99DCFA00, redo lsn=6/96034260
2026-02-13 20:17:22.849 UTC [27] LOG: checkpoints are occurring too frequently (5 seconds apart)
2026-02-13 20:17:22.849 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:17:22.849 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:17:29.363 UTC [27] LOG: checkpoint complete: wrote 5298 buffers (16.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=4.546 s, sync=1.929 s, total=6.515 s; sync files=26, longest=1.154 s, average=0.075 s; distance=540946 kB, estimate=618938 kB; lsn=6/D89689E8, redo lsn=6/B7078C10
2026-02-13 20:17:29.363 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:17:29.363 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:17:29.363 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:17:39.661 UTC [27] LOG: checkpoint complete: wrote 5451 buffers (16.6%); 0 WAL file(s) added, 0 removed, 0 recycled; write=7.501 s, sync=2.771 s, total=10.298 s; sync files=25, longest=1.314 s, average=0.111 s; distance=550745 kB, estimate=612119 kB; lsn=6/FC823948, redo lsn=6/D8A4F178
2026-02-13 20:17:39.661 UTC [27] LOG: checkpoints are occurring too frequently (10 seconds apart)
2026-02-13 20:17:39.661 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:17:39.661 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:17:49.286 UTC [27] LOG: checkpoint complete: wrote 5193 buffers (15.8%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.542 s, sync=3.051 s, total=9.626 s; sync files=25, longest=2.831 s, average=0.122 s; distance=588858 kB, estimate=609793 kB; lsn=7/1FA5C8E8, redo lsn=6/FC95DC88
2026-02-13 20:17:49.286 UTC [27] LOG: checkpoints are occurring too frequently (10 seconds apart)
2026-02-13 20:17:49.286 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:17:49.286 UTC [27] LOG: checkpoint starting: immediate force wait wal
2026-02-13 20:17:52.258 UTC [27] LOG: checkpoint complete: wrote 11585 buffers (35.4%); 0 WAL file(s) added, 0 removed, 0 recycled; write=1.470 s, sync=1.478 s, total=2.973 s; sync files=17, longest=1.045 s, average=0.087 s; distance=575306 kB, estimate=606344 kB; lsn=7/2C11FC48, redo lsn=7/1FB30638
2026-02-13 20:17:56.218 UTC [27] LOG: checkpoints are occurring too frequently (7 seconds apart)
2026-02-13 20:17:56.218 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:17:56.218 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:18:09.834 UTC [27] LOG: checkpoint complete: wrote 5417 buffers (16.5%); 0 WAL file(s) added, 0 removed, 0 recycled; write=7.907 s, sync=5.648 s, total=13.616 s; sync files=30, longest=4.649 s, average=0.189 s; distance=529752 kB, estimate=598685 kB; lsn=7/6C92E958, redo lsn=7/400869B8
2026-02-13 20:18:09.834 UTC [27] LOG: checkpoints are occurring too frequently (13 seconds apart)
2026-02-13 20:18:09.834 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:18:09.834 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:18:17.852 UTC [27] LOG: checkpoint complete: wrote 5573 buffers (17.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=4.578 s, sync=3.419 s, total=8.018 s; sync files=26, longest=2.395 s, average=0.132 s; distance=729763 kB, estimate=729763 kB; lsn=7/93741548, redo lsn=7/6C92F640
2026-02-13 20:18:17.852 UTC [27] LOG: checkpoints are occurring too frequently (8 seconds apart)
2026-02-13 20:18:17.852 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:18:17.852 UTC [27] LOG: checkpoint starting: immediate force wait wal
2026-02-13 20:18:20.115 UTC [27] LOG: checkpoint complete: wrote 18331 buffers (55.9%); 1 WAL file(s) added, 0 removed, 0 recycled; write=1.095 s, sync=1.122 s, total=2.263 s; sync files=22, longest=0.966 s, average=0.051 s; distance=638273 kB, estimate=720614 kB; lsn=7/9FCFF548, redo lsn=7/9387FBF8
2026-02-13 20:18:20.753 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:18:22.344 UTC [27] LOG: checkpoint complete: wrote 23998 buffers (73.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.794 s, sync=0.795 s, total=1.592 s; sync files=16, longest=0.511 s, average=0.050 s; distance=337236 kB, estimate=682276 kB; lsn=7/A95101F8, redo lsn=7/A81D4F28
2026-02-13 20:18:22.349 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:18:22.379 UTC [27] LOG: checkpoint complete: wrote 485 buffers (1.5%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.016 s, sync=0.011 s, total=0.031 s; sync files=3, longest=0.010 s, average=0.004 s; distance=19887 kB, estimate=616037 kB; lsn=7/A95FE778, redo lsn=7/A9540CA8
2026-02-13 20:18:32.388 UTC [27] LOG: checkpoints are occurring too frequently (10 seconds apart)
2026-02-13 20:18:32.388 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:18:32.388 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:19:03.626 UTC [27] LOG: checkpoint complete: wrote 7037 buffers (21.5%); 0 WAL file(s) added, 120 removed, 0 recycled; write=5.934 s, sync=25.068 s, total=31.238 s; sync files=23, longest=14.257 s, average=1.090 s; distance=535397 kB, estimate=607973 kB; lsn=8/16707C40, redo lsn=7/CA01A368
2026-02-13 20:19:03.626 UTC [27] LOG: checkpoint starting: immediate force wait wal
2026-02-13 20:19:09.286 UTC [27] LOG: checkpoint complete: wrote 20209 buffers (61.7%); 0 WAL file(s) added, 0 removed, 20 recycled; write=2.166 s, sync=2.919 s, total=5.660 s; sync files=36, longest=1.273 s, average=0.082 s; distance=1255394 kB, estimate=1255394 kB; lsn=8/22661DC0, redo lsn=8/16A12EF0
2026-02-13 20:19:15.012 UTC [27] LOG: checkpoints are occurring too frequently (12 seconds apart)
2026-02-13 20:19:15.012 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:19:15.012 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:19:30.427 UTC [27] LOG: checkpoint complete: wrote 5609 buffers (17.1%); 0 WAL file(s) added, 88 removed, 25 recycled; write=9.711 s, sync=5.475 s, total=15.415 s; sync files=31, longest=3.175 s, average=0.177 s; distance=531035 kB, estimate=1182958 kB; lsn=8/5D3772F0, redo lsn=8/370A9DF8
2026-02-13 20:19:30.427 UTC [27] LOG: checkpoints are occurring too frequently (15 seconds apart)
2026-02-13 20:19:30.427 UTC [27] HINT: Consider increasing the configuration parameter "max_wal_size".
2026-02-13 20:19:30.427 UTC [27] LOG: checkpoint starting: immediate force wait wal
2026-02-13 20:19:31.063 UTC [3193] LOG: unexpected EOF on standby connection
2026-02-13 20:19:31.063 UTC [3193] STATEMENT: START_REPLICATION SLOT "standby_slot" 2/2A000000 TIMELINE 1
2026-02-13 20:19:32.893 UTC [27] LOG: checkpoint complete: wrote 19484 buffers (59.5%); 0 WAL file(s) added, 0 removed, 4 recycled; write=1.796 s, sync=0.657 s, total=2.466 s; sync files=20, longest=0.607 s, average=0.033 s; distance=628444 kB, estimate=1127507 kB; lsn=8/605E6A40, redo lsn=8/5D660F68
2026-02-13 20:19:32.893 UTC [27] LOG: checkpoint starting: immediate force wait
2026-02-13 20:19:33.294 UTC [27] LOG: checkpoint complete: wrote 5854 buffers (17.9%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.194 s, sync=0.200 s, total=0.402 s; sync files=12, longest=0.190 s, average=0.017 s; distance=48662 kB, estimate=1019623 kB; lsn=8/605E6B10, redo lsn=8/605E6AB8
2026-02-13 20:20:59.394 UTC [27] LOG: checkpoint starting: wal
2026-02-13 20:21:41.250 UTC [27] LOG: checkpoint complete: wrote 15254 buffers (46.6%); 1 WAL file(s) added, 0 removed, 0 recycled; write=41.656 s, sync=0.178 s, total=41.856 s; sync files=18, longest=0.082 s, average=0.010 s; distance=535166 kB, estimate=971177 kB; lsn=8/9EFFC110, redo lsn=8/810863E0
2026-02-13 20:21:44.040 UTC [27] LOG: checkpoint starting: wal
Raw
script.log
❯ go run main.go --cleanup
17:09:04 INFO cleaning up existing containers and volumes
17:09:05 INFO cleanup done
17:09:05 INFO starting PostgreSQL MultiXact bug reproduction (17.5→17.8 upgrade) pre_upgrade_target=500,000 post_upgrade_target=5,000,000
17:09:05 INFO phase 1: setting up 17.5 primary
17:09:06 INFO primary started image=postgres:17.5
17:09:06 INFO waiting for primary to be ready
17:09:08 INFO primary ready
17:09:08 INFO installing pg_activity in primary container
17:09:13 INFO restarting primary for replication config
17:09:18 INFO waiting for primary to be ready
17:09:18 INFO primary ready
17:09:18 INFO seeding multixact_test (100K rows ~400MB)...
17:09:19 INFO seeding child_multixact (2M rows ~1.5GB)...
17:09:32 INFO creating indexes and analyzing...
17:09:34 INFO schema ready
17:09:34 INFO running checkpoint after seeding
17:09:34 INFO taking pg_basebackup image=postgres:17.5
17:09:36 INFO configuring standby
17:09:38 INFO starting standby image=postgres:17.5
17:09:44 INFO replication status streaming_standbys=1
17:09:44 INFO phase 2: generating multixacts with 17.5 standby target=500,000
17:10:17 INFO progress multixact="190,410 / 5,000,000 (3.8%)" rounds=40,285 elapsed=1m12s replay_lag=00:00:00.008953
17:11:13 INFO progress multixact="438,672 / 5,000,000 (8.8%)" rounds=103,476 elapsed=2m8s replay_lag=00:00:00.007894
17:11:19 INFO progress multixact="515,017 / 5,000,000 (10.3%)" rounds=107,801 elapsed=2m14s replay_lag=00:00:00.043019
17:11:20 INFO progress multixact="519,291 / 5,000,000 (10.4%)" rounds=108,001 elapsed=2m16s replay_lag=00:00:00.002788
17:11:20 INFO phase 3: vacuuming to truncate old multixacts before stopping standby
17:13:15 INFO stopping 17.5 standby for upgrade
17:13:16 INFO generating multixacts while standby is down (building WAL backlog)
17:13:48 INFO progress multixact="707,715 / 5,000,000 (14.2%)" rounds=147,823 elapsed=4m43s replay_lag=""
17:14:18 INFO progress multixact="872,504 / 5,000,000 (17.5%)" rounds=182,156 elapsed=5m13s replay_lag=""
17:14:48 INFO progress multixact="1,038,239 / 5,000,000 (20.8%)" rounds=216,922 elapsed=5m43s replay_lag=""
17:15:21 INFO progress multixact="1,266,742 / 5,000,000 (25.3%)" rounds=265,322 elapsed=6m17s replay_lag=""
17:15:49 INFO progress multixact="1,481,792 / 5,000,000 (29.6%)" rounds=309,741 elapsed=6m44s replay_lag=""
17:16:20 INFO progress multixact="1,726,070 / 5,000,000 (34.5%)" rounds=360,418 elapsed=7m16s replay_lag=""
17:16:47 INFO progress multixact="1,926,671 / 5,000,000 (38.5%)" rounds=401,318 elapsed=7m42s replay_lag=""
17:17:18 INFO progress multixact="2,151,561 / 5,000,000 (43.0%)" rounds=448,353 elapsed=8m13s replay_lag=""
17:17:52 INFO progress multixact="2,341,210 / 5,000,000 (46.8%)" rounds=489,770 elapsed=8m47s replay_lag=""
17:18:20 INFO progress multixact="2,521,497 / 5,000,000 (50.4%)" rounds=527,518 elapsed=9m15s replay_lag=""
17:18:22 INFO progress multixact="2,536,844 / 5,000,000 (50.7%)" rounds=527,718 elapsed=9m17s replay_lag=""
17:18:22 INFO forcing checkpoint before standby upgrade
17:18:22 INFO phase 4: restarting standby as 17.8 (the upgrade)
17:18:22 INFO starting standby image=postgres:17.8
17:18:27 INFO replication status streaming_standbys=0
17:18:27 INFO phase 5: generating multixacts with 17.8 standby target=5,000,000
17:19:09 INFO progress multixact="2,706,502 / 5,000,000 (54.1%)" rounds=565,570 elapsed=10m4s replay_lag=00:00:40.307892
17:19:30 ERRO BUG REPRODUCED! Standby crashed during WAL replay
log=
│ 2026-02-13 20:19:25.896 UTC [29] CONTEXT: WAL redo at 3/17779F48 for MultiXact/CREATE_ID: 835583 offset 6516271 nmembers 4: 1018581 (sh) 1018991 (sh) 1020806 (sh) 1020813 (keysh)
│ 2026-02-13 20:19:25.896 UTC [29] DEBUG: skipping initialization of offsets page 408 because it was already initialized on multixid creation
│ 2026-02-13 20:19:25.896 UTC [29] CONTEXT: WAL redo at 3/17779FC8 for MultiXact/ZERO_OFF_PAGE: 408
│ 2026-02-13 20:19:25.965 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:25.965 UTC [29] CONTEXT: WAL redo at 3/19657130 for MultiXact/CREATE_ID: 837631 offset 6531904 nmembers 2: 1023251 (sh) 1023315 (keysh)
│ 2026-02-13 20:19:25.965 UTC [29] DEBUG: skipping initialization of offsets page 409 because it was already initialized on multixid creation
│ 2026-02-13 20:19:25.965 UTC [29] CONTEXT: WAL redo at 3/196571A0 for MultiXact/ZERO_OFF_PAGE: 409
│ 2026-02-13 20:19:26.065 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:26.065 UTC [29] CONTEXT: WAL redo at 3/1B071080 for MultiXact/CREATE_ID: 839679 offset 6546945 nmembers 14: 1025556 (sh) 1025593 (keysh) 1025624 (sh) 1025647 (sh) 1025655 (keysh) 1025691 (keysh) 1025694 (sh) 1025699 (sh) 1025733 (sh) 1025738 (keysh) 1025754 (keysh) 1025755 (sh) 1025767 (sh) 1025775 (keysh)
│ 2026-02-13 20:19:26.065 UTC [29] DEBUG: skipping initialization of offsets page 410 because it was already initialized on multixid creation
│ 2026-02-13 20:19:26.065 UTC [29] CONTEXT: WAL redo at 3/1B071150 for MultiXact/ZERO_OFF_PAGE: 410
│ 2026-02-13 20:19:26.548 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:26.548 UTC [29] CONTEXT: WAL redo at 3/1C83A2C0 for MultiXact/CREATE_ID: 841727 offset 6563088 nmembers 13: 1023717 (keysh) 1023737 (sh) 1025818 (sh) 1025822 (keysh) 1025828 (keysh) 1027801 (sh) 1027956 (sh) 1028016 (sh) 1028034 (sh) 1028086 (keysh) 1028139 (sh) 1028182 (keysh) 1028202 (sh)
│ 2026-02-13 20:19:26.548 UTC [29] DEBUG: skipping initialization of offsets page 411 because it was already initialized on multixid creation
│ 2026-02-13 20:19:26.548 UTC [29] CONTEXT: WAL redo at 3/1C83A388 for MultiXact/ZERO_OFF_PAGE: 411
│ 2026-02-13 20:19:26.768 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:26.768 UTC [29] CONTEXT: WAL redo at 3/1E1031B8 for MultiXact/CREATE_ID: 843775 offset 6579709 nmembers 9: 1028600 (sh) 1028662 (keysh) 1028684 (sh) 1028732 (keysh) 1029338 (sh) 1029361 (keysh) 1029379 (sh) 1029404 (keysh) 1030649 (sh)
│ 2026-02-13 20:19:26.768 UTC [29] DEBUG: skipping initialization of offsets page 412 because it was already initialized on multixid creation
│ 2026-02-13 20:19:26.768 UTC [29] CONTEXT: WAL redo at 3/1E104E10 for MultiXact/ZERO_OFF_PAGE: 412
│ 2026-02-13 20:19:27.281 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:27.281 UTC [29] CONTEXT: WAL redo at 3/1FE96D28 for MultiXact/CREATE_ID: 845823 offset 6598059 nmembers 13: 1030375 (keysh) 1030431 (sh) 1030499 (keysh) 1030554 (sh) 1032823 (sh) 1033057 (sh) 1033059 (keysh) 1033061 (sh) 1033063 (keysh) 1033065 (sh) 1033124 (keysh) 1033133 (sh) 1033136 (keysh)
│ 2026-02-13 20:19:27.281 UTC [29] DEBUG: skipping initialization of offsets page 413 because it was already initialized on multixid creation
│ 2026-02-13 20:19:27.281 UTC [29] CONTEXT: WAL redo at 3/1FE96DF0 for MultiXact/ZERO_OFF_PAGE: 413
│ 2026-02-13 20:19:29.116 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:29.116 UTC [29] CONTEXT: WAL redo at 3/218865B8 for MultiXact/CREATE_ID: 847871 offset 6615424 nmembers 6: 1033388 (sh) 1033424 (sh) 1035610 (sh) 1035613 (keysh) 1035626 (sh) 1035632 (keysh)
│ 2026-02-13 20:19:29.116 UTC [29] DEBUG: skipping initialization of offsets page 414 because it was already initialized on multixid creation
│ 2026-02-13 20:19:29.116 UTC [29] CONTEXT: WAL redo at 3/2188E380 for MultiXact/ZERO_OFF_PAGE: 414
│ 2026-02-13 20:19:29.158 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:29.158 UTC [29] CONTEXT: WAL redo at 3/22B488F8 for MultiXact/CREATE_ID: 849919 offset 6632036 nmembers 10: 1035438 (sh) 1035461 (sh) 1035500 (keysh) 1036481 (keysh) 1036509 (sh) 1038000 (sh) 1038009 (keysh) 1038021 (sh) 1038032 (keysh) 1038043 (sh)
│ 2026-02-13 20:19:29.158 UTC [29] DEBUG: skipping initialization of offsets page 415 because it was already initialized on multixid creation
│ 2026-02-13 20:19:29.158 UTC [29] CONTEXT: WAL redo at 3/22B489E0 for MultiXact/ZERO_OFF_PAGE: 415
│ 2026-02-13 20:19:29.790 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:29.790 UTC [29] CONTEXT: WAL redo at 3/25622088 for MultiXact/CREATE_ID: 851967 offset 6648420 nmembers 6: 1040192 (keysh) 1040234 (sh) 1040696 (sh) 1040738 (keysh) 1040747 (sh) 1040788 (sh)
│ 2026-02-13 20:19:29.790 UTC [29] DEBUG: skipping initialization of offsets page 416 because it was already initialized on multixid creation
│ 2026-02-13 20:19:29.790 UTC [29] CONTEXT: WAL redo at 3/25624178 for MultiXact/ZERO_OFF_PAGE: 416
│ 2026-02-13 20:19:30.267 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:30.267 UTC [29] CONTEXT: WAL redo at 3/273BEF70 for MultiXact/CREATE_ID: 854015 offset 6663196 nmembers 7: 1040853 (sh) 1040907 (keysh) 1043024 (keysh) 1043055 (sh) 1043080 (keysh) 1043090 (sh) 1043376 (sh)
│ 2026-02-13 20:19:30.267 UTC [29] DEBUG: skipping initialization of offsets page 417 because it was already initialized on multixid creation
│ 2026-02-13 20:19:30.267 UTC [29] CONTEXT: WAL redo at 3/273BF008 for MultiXact/ZERO_OFF_PAGE: 417
│ 2026-02-13 20:19:30.485 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
│ 2026-02-13 20:19:30.485 UTC [29] CONTEXT: WAL redo at 3/28C148D8 for MultiXact/CREATE_ID: 856063 offset 6680130 nmembers 9: 1043713 (sh) 1045522 (sh) 1045537 (keysh) 1045555 (sh) 1045666 (keysh) 1045715 (sh) 1045750 (keysh) 1045794 (sh) 1045876 (sh)
│ 2026-02-13 20:19:30.485 UTC [29] DEBUG: skipping initialization of offsets page 418 because it was already initialized on multixid creation
│ 2026-02-13 20:19:30.485 UTC [29] CONTEXT: WAL redo at 3/28C149B8 for MultiXact/ZERO_OFF_PAGE: 418
│ 2026-02-13 20:19:30.602 UTC [29] DEBUG: replaying multixact truncation: offsets [1, 490986), offsets segments [0, 7), members [1, 3864017), members segments [0, 49)
│ 2026-02-13 20:19:30.602 UTC [29] CONTEXT: WAL redo at 3/29D6D548 for MultiXact/TRUNCATE_ID: offsets [1, 490986), members [1, 3864017)
│ 2026-02-13 20:19:30.602 UTC [29] DEBUG: MultiXactId wrap limit is 2147974633, limited by database with OID 5
│ 2026-02-13 20:19:30.602 UTC [29] CONTEXT: WAL redo at 3/29D6D548 for MultiXact/TRUNCATE_ID: offsets [1, 490986), members [1, 3864017)
│ 2026-02-13 20:19:30.783 UTC [29] FATAL: could not access status of transaction 858112
│ 2026-02-13 20:19:30.783 UTC [29] DETAIL: Could not read from file "pg_multixact/offsets/000D" at offset 24576: read too few bytes.
│ 2026-02-13 20:19:30.783 UTC [29] CONTEXT: WAL redo at 3/2A3AB408 for MultiXact/CREATE_ID: 858111 offset 6695072 nmembers 5: 1048228 (sh) 1048271 (keysh) 1048316 (sh) 1048344 (keysh) 1048370 (sh)
17:19:32 INFO progress multixact="2,809,199 / 5,000,000 (56.2%)" rounds=585,001 elapsed=10m28s replay_lag=""
17:19:33 INFO progress multixact="2,812,033 / 5,000,000 (56.2%)" rounds=585,001 elapsed=10m28s replay_lag=""
━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
MultiXact ID : 2,812,033
Rounds : 585,001
Runtime : 10m28s
━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━
docker logs pg-primary-multixact # primary
docker logs pg-standby-multixact # standby (crash?)
17:19:33 INFO done — containers left running for inspection primary=pg-primary-multixact standby=pg-standby-multixact
17:19:33 INFO to clean up: go run main.go --cleanup
Raw
standby.log
PostgreSQL Database directory appears to contain a database; Skipping initialization
2026-02-13 20:18:22.713 UTC [1] DEBUG: using armv8 crc32 hardware = 1
2026-02-13 20:18:22.713 UTC [1] DEBUG: registering background worker "logical replication launcher"
2026-02-13 20:18:22.713 UTC [1] DEBUG: mmap(348127232) with MAP_HUGETLB failed, huge pages disabled: Invalid argument
2026-02-13 20:18:22.727 UTC [1] LOG: starting PostgreSQL 17.8 (Debian 17.8-1.pgdg13+1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 14.2.0-19) 14.2.0, 64-bit
2026-02-13 20:18:22.727 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432
2026-02-13 20:18:22.727 UTC [1] LOG: listening on IPv6 address "::", port 5432
2026-02-13 20:18:22.732 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
2026-02-13 20:18:22.738 UTC [29] LOG: database system was shut down in recovery at 2026-02-13 20:13:16 UTC
2026-02-13 20:18:22.740 UTC [29] DEBUG: checkpoint record is at 2/1C08F9E0
2026-02-13 20:18:22.740 UTC [29] LOG: entering standby mode
2026-02-13 20:18:22.740 UTC [29] DEBUG: redo record is at 1/FE0963E0; shutdown false
2026-02-13 20:18:22.740 UTC [29] DEBUG: next transaction ID: 629272; next OID: 24577
2026-02-13 20:18:22.740 UTC [29] DEBUG: next MultiXactId: 519291; next MultiXactOffset: 4091527
2026-02-13 20:18:22.740 UTC [29] DEBUG: oldest unfrozen transaction ID: 731, in database 1
2026-02-13 20:18:22.740 UTC [29] DEBUG: oldest MultiXactId: 1, in database 5
2026-02-13 20:18:22.740 UTC [29] DEBUG: commit timestamp Xid oldest/newest: 0/0
2026-02-13 20:18:22.740 UTC [29] DEBUG: transaction ID wrap limit is 2147484378, limited by database with OID 1
2026-02-13 20:18:22.740 UTC [29] DEBUG: MultiXactId wrap limit is 2147483648, limited by database with OID 5
2026-02-13 20:18:22.740 UTC [29] DEBUG: starting up replication slots
2026-02-13 20:18:22.740 UTC [29] DEBUG: xmin required by slots: data 0, catalog 0
2026-02-13 20:18:22.744 UTC [29] DEBUG: resetting unlogged relations: cleanup 1 init 0
2026-02-13 20:18:22.744 UTC [29] DEBUG: initializing for hot standby
2026-02-13 20:18:22.744 UTC [29] LOG: redo starts at 1/FE0963E0
2026-02-13 20:18:22.954 UTC [29] DEBUG: recovery snapshots are now enabled
2026-02-13 20:18:22.954 UTC [29] CONTEXT: WAL redo at 1/FFDCF108 for Standby/RUNNING_XACTS: nextXid 629272 latestCompletedXid 629271 oldestRunningXid 629272
2026-02-13 20:18:28.969 UTC [29] LOG: consistent recovery state reached at 2/2AB03950
2026-02-13 20:18:28.969 UTC [29] LOG: invalid record length at 2/2AB03950: expected at least 24, got 0
2026-02-13 20:18:28.969 UTC [1] LOG: database system is ready to accept read-only connections
2026-02-13 20:18:28.983 UTC [30] LOG: started streaming WAL from primary at 2/2A000000 on timeline 1
2026-02-13 20:18:29.268 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:29.268 UTC [29] CONTEXT: WAL redo at 2/2B7C4978 for MultiXact/CREATE_ID: 520191 offset 4096039 nmembers 6: 630156 (sh) 630181 (sh) 630271 (keysh) 630327 (sh) 630421 (keysh) 630485 (sh)
2026-02-13 20:18:29.268 UTC [29] DEBUG: skipping initialization of offsets page 254 because it was already initialized on multixid creation
2026-02-13 20:18:29.268 UTC [29] CONTEXT: WAL redo at 2/2B7C4A08 for MultiXact/ZERO_OFF_PAGE: 254
2026-02-13 20:18:30.030 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:30.030 UTC [29] CONTEXT: WAL redo at 2/2E7BA620 for MultiXact/CREATE_ID: 522239 offset 4107204 nmembers 4: 632194 (sh) 632225 (keysh) 633572 (sh) 633601 (keysh)
2026-02-13 20:18:30.033 UTC [29] DEBUG: skipping initialization of offsets page 255 because it was already initialized on multixid creation
2026-02-13 20:18:30.033 UTC [29] CONTEXT: WAL redo at 2/2E7BE4B0 for MultiXact/ZERO_OFF_PAGE: 255
2026-02-13 20:18:30.188 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:30.188 UTC [29] CONTEXT: WAL redo at 2/2F35AD80 for MultiXact/CREATE_ID: 524287 offset 4122707 nmembers 5: 633940 (sh) 633955 (sh) 633968 (keysh) 633986 (sh) 635943 (sh)
2026-02-13 20:18:30.188 UTC [29] DEBUG: skipping initialization of offsets page 256 because it was already initialized on multixid creation
2026-02-13 20:18:30.188 UTC [29] CONTEXT: WAL redo at 2/2F35AE40 for MultiXact/ZERO_OFF_PAGE: 256
2026-02-13 20:18:30.441 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:30.441 UTC [29] CONTEXT: WAL redo at 2/30088D28 for MultiXact/CREATE_ID: 526335 offset 4137762 nmembers 8: 636901 (keysh) 636943 (sh) 636973 (keysh) 637002 (sh) 638296 (sh) 638308 (keysh) 638316 (sh) 638321 (keysh)
2026-02-13 20:18:30.441 UTC [29] DEBUG: skipping initialization of offsets page 257 because it was already initialized on multixid creation
2026-02-13 20:18:30.441 UTC [29] CONTEXT: WAL redo at 2/30088DC8 for MultiXact/ZERO_OFF_PAGE: 257
2026-02-13 20:18:30.875 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:30.875 UTC [29] CONTEXT: WAL redo at 2/316AA348 for MultiXact/CREATE_ID: 528383 offset 4152709 nmembers 13: 634079 (sh) 639181 (sh) 639227 (keysh) 639484 (sh) 640657 (keysh) 640712 (sh) 640750 (sh) 640770 (keysh) 640803 (keysh) 640805 (sh) 640851 (sh) 640894 (keysh) 640909 (sh)
2026-02-13 20:18:30.880 UTC [29] DEBUG: skipping initialization of offsets page 258 because it was already initialized on multixid creation
2026-02-13 20:18:30.880 UTC [29] CONTEXT: WAL redo at 2/316B6D40 for MultiXact/ZERO_OFF_PAGE: 258
2026-02-13 20:18:31.200 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:31.200 UTC [29] CONTEXT: WAL redo at 2/32E92C38 for MultiXact/CREATE_ID: 530431 offset 4168006 nmembers 6: 642226 (sh) 642278 (keysh) 642493 (sh) 642559 (keysh) 643510 (sh) 643526 (keysh)
2026-02-13 20:18:31.200 UTC [29] DEBUG: skipping initialization of offsets page 259 because it was already initialized on multixid creation
2026-02-13 20:18:31.200 UTC [29] CONTEXT: WAL redo at 2/32E92D90 for MultiXact/ZERO_OFF_PAGE: 259
2026-02-13 20:18:31.710 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:31.710 UTC [29] CONTEXT: WAL redo at 2/34638568 for MultiXact/CREATE_ID: 532479 offset 4183409 nmembers 9: 644543 (keysh) 644577 (sh) 644600 (keysh) 644658 (sh) 646100 (sh) 646117 (keysh) 646145 (sh) 646165 (keysh) 646187 (sh)
2026-02-13 20:18:31.710 UTC [29] DEBUG: skipping initialization of offsets page 260 because it was already initialized on multixid creation
2026-02-13 20:18:31.710 UTC [29] CONTEXT: WAL redo at 2/34638638 for MultiXact/ZERO_OFF_PAGE: 260
2026-02-13 20:18:32.175 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:32.175 UTC [29] CONTEXT: WAL redo at 2/35AF4520 for MultiXact/CREATE_ID: 534527 offset 4199517 nmembers 6: 629364 (sh) 647995 (sh) 648709 (sh) 648725 (keysh) 648735 (sh) 648753 (keysh)
2026-02-13 20:18:32.175 UTC [29] DEBUG: skipping initialization of offsets page 261 because it was already initialized on multixid creation
2026-02-13 20:18:32.175 UTC [29] CONTEXT: WAL redo at 2/35AF4600 for MultiXact/ZERO_OFF_PAGE: 261
2026-02-13 20:18:32.817 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:32.817 UTC [29] CONTEXT: WAL redo at 2/375AB628 for MultiXact/CREATE_ID: 536575 offset 4215022 nmembers 12: 650096 (sh) 650158 (keysh) 650213 (sh) 650268 (keysh) 650321 (sh) 650359 (sh) 650374 (sh) 650420 (keysh) 650435 (keysh) 650751 (keysh) 650768 (sh) 651447 (sh)
2026-02-13 20:18:32.817 UTC [29] DEBUG: skipping initialization of offsets page 262 because it was already initialized on multixid creation
2026-02-13 20:18:32.817 UTC [29] CONTEXT: WAL redo at 2/375AB710 for MultiXact/ZERO_OFF_PAGE: 262
2026-02-13 20:18:33.140 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:33.140 UTC [29] CONTEXT: WAL redo at 2/38564490 for MultiXact/CREATE_ID: 538623 offset 4231027 nmembers 5: 650283 (keysh) 652359 (sh) 652380 (keysh) 652536 (sh) 653944 (sh)
2026-02-13 20:18:33.140 UTC [29] DEBUG: skipping initialization of offsets page 263 because it was already initialized on multixid creation
2026-02-13 20:18:33.140 UTC [29] CONTEXT: WAL redo at 2/38564590 for MultiXact/ZERO_OFF_PAGE: 263
2026-02-13 20:18:33.444 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:33.444 UTC [29] CONTEXT: WAL redo at 2/39430E10 for MultiXact/CREATE_ID: 540671 offset 4246157 nmembers 3: 656355 (sh) 656409 (sh) 656411 (keysh)
2026-02-13 20:18:33.444 UTC [29] DEBUG: skipping initialization of offsets page 264 because it was already initialized on multixid creation
2026-02-13 20:18:33.444 UTC [29] CONTEXT: WAL redo at 2/39430EB0 for MultiXact/ZERO_OFF_PAGE: 264
2026-02-13 20:18:33.880 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:33.880 UTC [29] CONTEXT: WAL redo at 2/3A6C91E8 for MultiXact/CREATE_ID: 542719 offset 4261887 nmembers 9: 657622 (sh) 657659 (keysh) 657700 (sh) 658147 (sh) 658666 (sh) 658690 (keysh) 658769 (sh) 658841 (keysh) 658933 (sh)
2026-02-13 20:18:33.880 UTC [29] DEBUG: skipping initialization of offsets page 265 because it was already initialized on multixid creation
2026-02-13 20:18:33.880 UTC [29] CONTEXT: WAL redo at 2/3A6C9290 for MultiXact/ZERO_OFF_PAGE: 265
2026-02-13 20:18:34.221 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:34.221 UTC [29] CONTEXT: WAL redo at 2/3BB95948 for MultiXact/CREATE_ID: 544767 offset 4277534 nmembers 7: 655322 (sh) 659932 (sh) 659951 (keysh) 659985 (sh) 660491 (sh) 661485 (sh) 661513 (keysh)
2026-02-13 20:18:34.221 UTC [29] DEBUG: skipping initialization of offsets page 266 because it was already initialized on multixid creation
2026-02-13 20:18:34.221 UTC [29] CONTEXT: WAL redo at 2/3BB959E0 for MultiXact/ZERO_OFF_PAGE: 266
2026-02-13 20:18:34.476 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:34.476 UTC [29] CONTEXT: WAL redo at 2/3CA6BE40 for MultiXact/CREATE_ID: 546815 offset 4293048 nmembers 8: 663778 (sh) 663802 (keysh) 663829 (sh) 663854 (sh) 663888 (keysh) 663932 (sh) 663957 (keysh) 663977 (sh)
2026-02-13 20:18:34.476 UTC [29] DEBUG: skipping initialization of offsets page 267 because it was already initialized on multixid creation
2026-02-13 20:18:34.476 UTC [29] CONTEXT: WAL redo at 2/3CA6BEE0 for MultiXact/ZERO_OFF_PAGE: 267
2026-02-13 20:18:34.795 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:34.795 UTC [29] CONTEXT: WAL redo at 2/3DA628B0 for MultiXact/CREATE_ID: 548863 offset 4309659 nmembers 9: 666269 (sh) 666281 (keysh) 666301 (sh) 666314 (keysh) 666321 (sh) 666427 (sh) 666432 (keysh) 666446 (sh) 666456 (keysh)
2026-02-13 20:18:34.795 UTC [29] DEBUG: skipping initialization of offsets page 268 because it was already initialized on multixid creation
2026-02-13 20:18:34.795 UTC [29] CONTEXT: WAL redo at 2/3DA62958 for MultiXact/ZERO_OFF_PAGE: 268
2026-02-13 20:18:34.898 UTC [27] LOG: restartpoint starting: wal
2026-02-13 20:18:34.898 UTC [27] DEBUG: performing replication slot checkpoint
2026-02-13 20:18:35.307 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:35.307 UTC [29] CONTEXT: WAL redo at 2/3FAE9E58 for MultiXact/CREATE_ID: 550911 offset 4323060 nmembers 11: 666874 (sh) 666925 (sh) 666942 (keysh) 669147 (sh) 669214 (keysh) 669242 (sh) 669276 (keysh) 669331 (keysh) 669337 (sh) 669382 (sh) 669406 (sh)
2026-02-13 20:18:35.307 UTC [29] DEBUG: skipping initialization of offsets page 269 because it was already initialized on multixid creation
2026-02-13 20:18:35.307 UTC [29] CONTEXT: WAL redo at 2/3FAE9F10 for MultiXact/ZERO_OFF_PAGE: 269
2026-02-13 20:18:35.635 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:35.635 UTC [29] CONTEXT: WAL redo at 2/41470D10 for MultiXact/CREATE_ID: 552959 offset 4340008 nmembers 4: 670453 (sh) 670503 (keysh) 670547 (sh) 671906 (sh)
2026-02-13 20:18:35.635 UTC [29] DEBUG: skipping initialization of offsets page 270 because it was already initialized on multixid creation
2026-02-13 20:18:35.635 UTC [29] CONTEXT: WAL redo at 2/41470D90 for MultiXact/ZERO_OFF_PAGE: 270
2026-02-13 20:18:35.807 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:35.807 UTC [29] CONTEXT: WAL redo at 2/426B37A8 for MultiXact/CREATE_ID: 555007 offset 4356065 nmembers 6: 671975 (sh) 672063 (keysh) 672123 (sh) 674036 (sh) 674038 (sh) 674266 (sh)
2026-02-13 20:18:35.807 UTC [29] DEBUG: skipping initialization of offsets page 271 because it was already initialized on multixid creation
2026-02-13 20:18:35.807 UTC [29] CONTEXT: WAL redo at 2/426B38B0 for MultiXact/ZERO_OFF_PAGE: 271
2026-02-13 20:18:36.077 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:36.077 UTC [29] CONTEXT: WAL redo at 2/447C9BB0 for MultiXact/CREATE_ID: 557055 offset 4370208 nmembers 9: 676189 (sh) 676252 (keysh) 676309 (sh) 676771 (sh) 676782 (keysh) 676802 (sh) 676821 (sh) 676826 (keysh) 676832 (sh)
2026-02-13 20:18:36.077 UTC [29] DEBUG: skipping initialization of offsets page 272 because it was already initialized on multixid creation
2026-02-13 20:18:36.077 UTC [29] CONTEXT: WAL redo at 2/447C9C58 for MultiXact/ZERO_OFF_PAGE: 272
2026-02-13 20:18:36.434 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:36.434 UTC [29] CONTEXT: WAL redo at 2/465DFEA8 for MultiXact/CREATE_ID: 559103 offset 4384860 nmembers 8: 679261 (keysh) 679278 (sh) 679332 (sh) 679351 (keysh) 679356 (sh) 679365 (keysh) 679373 (sh) 679401 (sh)
2026-02-13 20:18:36.434 UTC [29] DEBUG: skipping initialization of offsets page 273 because it was already initialized on multixid creation
2026-02-13 20:18:36.434 UTC [29] CONTEXT: WAL redo at 2/465DFF70 for MultiXact/ZERO_OFF_PAGE: 273
2026-02-13 20:18:36.876 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:36.876 UTC [29] CONTEXT: WAL redo at 2/47BF9CA8 for MultiXact/CREATE_ID: 561151 offset 4400040 nmembers 12: 680579 (keysh) 680597 (sh) 680674 (keysh) 680729 (sh) 681100 (sh) 681706 (sh) 681737 (keysh) 681763 (sh) 681780 (sh) 681811 (keysh) 681845 (sh) 681888 (keysh)
2026-02-13 20:18:36.876 UTC [29] DEBUG: skipping initialization of offsets page 274 because it was already initialized on multixid creation
2026-02-13 20:18:36.876 UTC [29] CONTEXT: WAL redo at 2/47BF9DB8 for MultiXact/ZERO_OFF_PAGE: 274
2026-02-13 20:18:37.593 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:37.593 UTC [29] CONTEXT: WAL redo at 2/49F1D4E8 for MultiXact/CREATE_ID: 563199 offset 4415332 nmembers 4: 684250 (sh) 684325 (keysh) 684372 (sh) 684531 (sh)
2026-02-13 20:18:37.593 UTC [29] DEBUG: skipping initialization of offsets page 275 because it was already initialized on multixid creation
2026-02-13 20:18:37.593 UTC [29] CONTEXT: WAL redo at 2/49F1F0F8 for MultiXact/ZERO_OFF_PAGE: 275
2026-02-13 20:18:38.155 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:38.155 UTC [29] CONTEXT: WAL redo at 2/4B8BBB98 for MultiXact/CREATE_ID: 565247 offset 4430980 nmembers 4: 686945 (sh) 686965 (keysh) 687025 (sh) 687055 (keysh)
2026-02-13 20:18:38.156 UTC [29] DEBUG: skipping initialization of offsets page 276 because it was already initialized on multixid creation
2026-02-13 20:18:38.156 UTC [29] CONTEXT: WAL redo at 2/4B8BBC18 for MultiXact/ZERO_OFF_PAGE: 276
2026-02-13 20:18:38.536 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:38.536 UTC [29] CONTEXT: WAL redo at 2/4CD33DE8 for MultiXact/CREATE_ID: 567295 offset 4446154 nmembers 12: 685833 (sh) 688047 (keysh) 688095 (sh) 689402 (sh) 689422 (keysh) 689425 (sh) 689444 (keysh) 689456 (sh) 689480 (sh) 689483 (keysh) 689506 (keysh) 689511 (sh)
2026-02-13 20:18:38.536 UTC [29] DEBUG: skipping initialization of offsets page 277 because it was already initialized on multixid creation
2026-02-13 20:18:38.536 UTC [29] CONTEXT: WAL redo at 2/4CD33ED0 for MultiXact/ZERO_OFF_PAGE: 277
2026-02-13 20:18:38.915 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:38.915 UTC [29] CONTEXT: WAL redo at 2/4DEF2CE0 for MultiXact/CREATE_ID: 569343 offset 4461947 nmembers 3: 690467 (sh) 690560 (keysh) 691936 (sh)
2026-02-13 20:18:38.915 UTC [29] DEBUG: skipping initialization of offsets page 278 because it was already initialized on multixid creation
2026-02-13 20:18:38.915 UTC [29] CONTEXT: WAL redo at 2/4DEF4E00 for MultiXact/ZERO_OFF_PAGE: 278
2026-02-13 20:18:39.175 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:39.175 UTC [29] CONTEXT: WAL redo at 2/4EFDBCC8 for MultiXact/CREATE_ID: 571391 offset 4478047 nmembers 11: 693444 (sh) 693786 (keysh) 693827 (keysh) 693859 (sh) 693876 (sh) 693955 (keysh) 694003 (sh) 694320 (sh) 694339 (keysh) 694359 (sh) 694385 (keysh)
2026-02-13 20:18:39.176 UTC [29] DEBUG: skipping initialization of offsets page 279 because it was already initialized on multixid creation
2026-02-13 20:18:39.176 UTC [29] CONTEXT: WAL redo at 2/4EFDBE08 for MultiXact/ZERO_OFF_PAGE: 279
2026-02-13 20:18:39.334 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:39.334 UTC [29] CONTEXT: WAL redo at 2/4FEE7198 for MultiXact/CREATE_ID: 573439 offset 4494528 nmembers 12: 696132 (keysh) 696157 (sh) 696376 (sh) 696415 (keysh) 696532 (sh) 696535 (sh) 696584 (keysh) 696644 (keysh) 696660 (sh) 696708 (sh) 696730 (keysh) 696775 (sh)
2026-02-13 20:18:39.334 UTC [29] DEBUG: skipping initialization of offsets page 280 because it was already initialized on multixid creation
2026-02-13 20:18:39.334 UTC [29] CONTEXT: WAL redo at 2/4FEE9218 for MultiXact/ZERO_OFF_PAGE: 280
2026-02-13 20:18:39.932 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:39.932 UTC [29] CONTEXT: WAL redo at 2/512F1228 for MultiXact/CREATE_ID: 575487 offset 4509316 nmembers 7: 696877 (sh) 699012 (sh) 699137 (sh) 699173 (keysh) 699234 (sh) 699301 (keysh) 699324 (sh)
2026-02-13 20:18:39.932 UTC [29] DEBUG: skipping initialization of offsets page 281 because it was already initialized on multixid creation
2026-02-13 20:18:39.932 UTC [29] CONTEXT: WAL redo at 2/512F12C0 for MultiXact/ZERO_OFF_PAGE: 281
2026-02-13 20:18:40.768 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:40.768 UTC [29] CONTEXT: WAL redo at 2/51F92E70 for MultiXact/CREATE_ID: 577535 offset 4523821 nmembers 7: 697151 (sh) 697174 (keysh) 699798 (sh) 701554 (sh) 701636 (sh) 701648 (keysh) 701707 (keysh)
2026-02-13 20:18:40.768 UTC [29] DEBUG: skipping initialization of offsets page 282 because it was already initialized on multixid creation
2026-02-13 20:18:40.768 UTC [29] CONTEXT: WAL redo at 2/51F96AB0 for MultiXact/ZERO_OFF_PAGE: 282
2026-02-13 20:18:41.182 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:41.182 UTC [29] CONTEXT: WAL redo at 2/537BD488 for MultiXact/CREATE_ID: 579583 offset 4538857 nmembers 4: 701604 (sh) 701738 (keysh) 702776 (sh) 704288 (sh)
2026-02-13 20:18:41.183 UTC [29] DEBUG: skipping initialization of offsets page 283 because it was already initialized on multixid creation
2026-02-13 20:18:41.183 UTC [29] CONTEXT: WAL redo at 2/537BD508 for MultiXact/ZERO_OFF_PAGE: 283
2026-02-13 20:18:41.570 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:41.570 UTC [29] CONTEXT: WAL redo at 2/54D8B528 for MultiXact/CREATE_ID: 581631 offset 4554730 nmembers 5: 702208 (sh) 706125 (keysh) 706168 (sh) 706828 (keysh) 706832 (sh)
2026-02-13 20:18:41.570 UTC [29] DEBUG: skipping initialization of offsets page 284 because it was already initialized on multixid creation
2026-02-13 20:18:41.570 UTC [29] CONTEXT: WAL redo at 2/54D8B5B0 for MultiXact/ZERO_OFF_PAGE: 284
2026-02-13 20:18:41.895 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:41.895 UTC [29] CONTEXT: WAL redo at 2/56079608 for MultiXact/CREATE_ID: 583679 offset 4572414 nmembers 12: 706905 (sh) 706927 (keysh) 706957 (sh) 709227 (sh) 709228 (keysh) 709239 (sh) 709242 (keysh) 709251 (keysh) 709256 (sh) 709269 (sh) 709324 (sh) 709352 (keysh)
2026-02-13 20:18:41.895 UTC [29] DEBUG: skipping initialization of offsets page 285 because it was already initialized on multixid creation
2026-02-13 20:18:41.895 UTC [29] CONTEXT: WAL redo at 2/560796C8 for MultiXact/ZERO_OFF_PAGE: 285
2026-02-13 20:18:42.732 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:42.732 UTC [29] CONTEXT: WAL redo at 2/57A6FAE0 for MultiXact/CREATE_ID: 585727 offset 4587877 nmembers 4: 709557 (keysh) 709597 (sh) 711994 (sh) 711998 (keysh)
2026-02-13 20:18:42.732 UTC [29] DEBUG: skipping initialization of offsets page 286 because it was already initialized on multixid creation
2026-02-13 20:18:42.732 UTC [29] CONTEXT: WAL redo at 2/57A6FB88 for MultiXact/ZERO_OFF_PAGE: 286
2026-02-13 20:18:43.025 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:43.025 UTC [29] CONTEXT: WAL redo at 2/58C13E20 for MultiXact/CREATE_ID: 587775 offset 4604483 nmembers 5: 714294 (sh) 714322 (keysh) 714360 (sh) 714405 (keysh) 714455 (sh)
2026-02-13 20:18:43.025 UTC [29] DEBUG: skipping initialization of offsets page 287 because it was already initialized on multixid creation
2026-02-13 20:18:43.025 UTC [29] CONTEXT: WAL redo at 2/58C13EF8 for MultiXact/ZERO_OFF_PAGE: 287
2026-02-13 20:18:43.341 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:43.341 UTC [29] CONTEXT: WAL redo at 2/5A15C908 for MultiXact/CREATE_ID: 589823 offset 4619040 nmembers 7: 715966 (keysh) 716057 (sh) 716439 (sh) 717033 (sh) 717039 (keysh) 717042 (sh) 717049 (keysh)
2026-02-13 20:18:43.341 UTC [29] DEBUG: skipping initialization of offsets page 288 because it was already initialized on multixid creation
2026-02-13 20:18:43.341 UTC [29] CONTEXT: WAL redo at 2/5A15C9F0 for MultiXact/ZERO_OFF_PAGE: 288
2026-02-13 20:18:43.766 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:43.766 UTC [29] CONTEXT: WAL redo at 2/5ACA6A98 for MultiXact/CREATE_ID: 591871 offset 4634479 nmembers 10: 713004 (sh) 717448 (keysh) 717492 (sh) 717531 (keysh) 717572 (sh) 719385 (sh) 719389 (keysh) 719394 (sh) 719408 (keysh) 719432 (sh)
2026-02-13 20:18:43.766 UTC [29] DEBUG: skipping initialization of offsets page 289 because it was already initialized on multixid creation
2026-02-13 20:18:43.766 UTC [29] CONTEXT: WAL redo at 2/5ACA6B48 for MultiXact/ZERO_OFF_PAGE: 289
2026-02-13 20:18:44.081 UTC [27] DEBUG: checkpoint sync: number=1 file=base/5/2619 time=3.616 ms
2026-02-13 20:18:44.084 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:44.084 UTC [29] CONTEXT: WAL redo at 2/5C030180 for MultiXact/CREATE_ID: 593919 offset 4649452 nmembers 9: 720250 (sh) 720291 (keysh) 720564 (sh) 720617 (keysh) 720652 (sh) 721969 (sh) 721986 (keysh) 721998 (sh) 722008 (keysh)
2026-02-13 20:18:44.086 UTC [29] DEBUG: skipping initialization of offsets page 290 because it was already initialized on multixid creation
2026-02-13 20:18:44.086 UTC [29] CONTEXT: WAL redo at 2/5C030228 for MultiXact/ZERO_OFF_PAGE: 290
2026-02-13 20:18:44.088 UTC [27] DEBUG: checkpoint sync: number=2 file=pg_multixact/offsets/0008 time=6.754 ms
2026-02-13 20:18:44.090 UTC [27] DEBUG: checkpoint sync: number=3 file=pg_multixact/offsets/0009 time=1.615 ms
2026-02-13 20:18:44.090 UTC [27] DEBUG: checkpoint sync: number=4 file=base/5/16397 time=0.003 ms
2026-02-13 20:18:44.092 UTC [27] DEBUG: checkpoint sync: number=5 file=pg_multixact/members/0056 time=1.662 ms
2026-02-13 20:18:44.109 UTC [27] DEBUG: checkpoint sync: number=6 file=base/5/16407 time=17.228 ms
2026-02-13 20:18:44.115 UTC [27] DEBUG: checkpoint sync: number=7 file=global/1262 time=6.035 ms
2026-02-13 20:18:44.116 UTC [27] DEBUG: checkpoint sync: number=8 file=pg_multixact/members/0051 time=0.627 ms
2026-02-13 20:18:44.116 UTC [27] DEBUG: checkpoint sync: number=9 file=base/5/1259 time=0.002 ms
2026-02-13 20:18:44.116 UTC [27] DEBUG: checkpoint sync: number=10 file=base/5/16386_vm time=0.007 ms
2026-02-13 20:18:44.117 UTC [27] DEBUG: checkpoint sync: number=11 file=pg_multixact/members/0058 time=0.840 ms
2026-02-13 20:18:44.117 UTC [27] DEBUG: checkpoint sync: number=12 file=pg_multixact/members/0050 time=0.002 ms
2026-02-13 20:18:44.119 UTC [27] DEBUG: checkpoint sync: number=13 file=pg_xact/0000 time=2.674 ms
2026-02-13 20:18:44.120 UTC [27] DEBUG: checkpoint sync: number=14 file=pg_multixact/members/0055 time=0.709 ms
2026-02-13 20:18:44.330 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:44.330 UTC [29] CONTEXT: WAL redo at 2/5D070580 for MultiXact/CREATE_ID: 595967 offset 4664684 nmembers 15: 722469 (keysh) 722509 (sh) 722546 (keysh) 722583 (sh) 724182 (sh) 724396 (sh) 724420 (keysh) 724437 (sh) 724449 (keysh) 724455 (sh) 724467 (sh) 724470 (keysh) 724481 (sh) 724488 (keysh) 724493 (sh)
2026-02-13 20:18:44.330 UTC [29] DEBUG: skipping initialization of offsets page 291 because it was already initialized on multixid creation
2026-02-13 20:18:44.330 UTC [29] CONTEXT: WAL redo at 2/5D070690 for MultiXact/ZERO_OFF_PAGE: 291
2026-02-13 20:18:44.837 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:44.837 UTC [29] CONTEXT: WAL redo at 2/5E696470 for MultiXact/CREATE_ID: 598015 offset 4679122 nmembers 5: 725269 (sh) 726929 (keysh) 726944 (sh) 727126 (sh) 727171 (keysh)
2026-02-13 20:18:44.838 UTC [29] DEBUG: skipping initialization of offsets page 292 because it was already initialized on multixid creation
2026-02-13 20:18:44.838 UTC [29] CONTEXT: WAL redo at 2/5E6964F8 for MultiXact/ZERO_OFF_PAGE: 292
2026-02-13 20:18:45.881 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:45.881 UTC [29] CONTEXT: WAL redo at 2/5F625D48 for MultiXact/CREATE_ID: 600063 offset 4695388 nmembers 5: 729310 (sh) 729421 (sh) 729487 (keysh) 729574 (sh) 729648 (keysh)
2026-02-13 20:18:45.881 UTC [29] DEBUG: skipping initialization of offsets page 293 because it was already initialized on multixid creation
2026-02-13 20:18:45.881 UTC [29] CONTEXT: WAL redo at 2/5F627B40 for MultiXact/ZERO_OFF_PAGE: 293
2026-02-13 20:18:47.655 UTC [27] DEBUG: checkpoint sync: number=15 file=base/5/16398 time=3534.640 ms
2026-02-13 20:18:47.656 UTC [27] DEBUG: checkpoint sync: number=16 file=pg_multixact/members/004E time=1.382 ms
2026-02-13 20:18:47.671 UTC [27] DEBUG: checkpoint sync: number=17 file=base/5/16386 time=14.312 ms
2026-02-13 20:18:47.675 UTC [27] DEBUG: checkpoint sync: number=18 file=base/5/16398_vm time=4.449 ms
2026-02-13 20:18:47.693 UTC [27] DEBUG: checkpoint sync: number=19 file=base/5/16414 time=17.427 ms
2026-02-13 20:18:47.699 UTC [27] DEBUG: checkpoint sync: number=20 file=base/5/16395 time=4.386 ms
2026-02-13 20:18:47.703 UTC [27] DEBUG: checkpoint sync: number=21 file=pg_multixact/members/0057 time=3.809 ms
2026-02-13 20:18:47.708 UTC [27] DEBUG: checkpoint sync: number=22 file=base/5/2619_fsm time=5.282 ms
2026-02-13 20:18:47.715 UTC [27] DEBUG: checkpoint sync: number=23 file=pg_multixact/offsets/0007 time=6.941 ms
2026-02-13 20:18:47.715 UTC [27] DEBUG: checkpoint sync: number=24 file=base/5/2696 time=0.003 ms
2026-02-13 20:18:47.716 UTC [27] DEBUG: checkpoint sync: number=25 file=pg_multixact/members/0052 time=1.052 ms
2026-02-13 20:18:47.717 UTC [27] DEBUG: checkpoint sync: number=26 file=pg_multixact/members/0054 time=1.225 ms
2026-02-13 20:18:47.718 UTC [27] DEBUG: checkpoint sync: number=27 file=pg_multixact/members/0053 time=0.506 ms
2026-02-13 20:18:47.719 UTC [27] DEBUG: checkpoint sync: number=28 file=base/5/16398_fsm time=1.104 ms
2026-02-13 20:18:48.386 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:48.386 UTC [29] CONTEXT: WAL redo at 2/608E5588 for MultiXact/CREATE_ID: 602111 offset 4709048 nmembers 4: 730300 (sh) 730350 (keysh) 730390 (sh) 732226 (sh)
2026-02-13 20:18:48.386 UTC [29] DEBUG: skipping initialization of offsets page 294 because it was already initialized on multixid creation
2026-02-13 20:18:48.386 UTC [29] CONTEXT: WAL redo at 2/608EE5C8 for MultiXact/ZERO_OFF_PAGE: 294
2026-02-13 20:18:48.971 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:48.971 UTC [29] CONTEXT: WAL redo at 2/628520A8 for MultiXact/CREATE_ID: 604159 offset 4725651 nmembers 6: 732325 (sh) 734683 (sh) 734753 (keysh) 734788 (keysh) 734793 (sh) 734857 (sh)
2026-02-13 20:18:48.971 UTC [29] DEBUG: skipping initialization of offsets page 295 because it was already initialized on multixid creation
2026-02-13 20:18:48.971 UTC [29] CONTEXT: WAL redo at 2/62852138 for MultiXact/ZERO_OFF_PAGE: 295
2026-02-13 20:18:49.139 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:49.139 UTC [29] CONTEXT: WAL redo at 2/641F6F90 for MultiXact/CREATE_ID: 606207 offset 4741903 nmembers 13: 729572 (sh) 729583 (sh) 729667 (keysh) 729689 (keysh) 729748 (sh) 736329 (sh) 736406 (keysh) 736525 (sh) 737185 (sh) 737226 (sh) 737245 (keysh) 737268 (sh) 737325 (keysh)
2026-02-13 20:18:49.139 UTC [29] DEBUG: skipping initialization of offsets page 296 because it was already initialized on multixid creation
2026-02-13 20:18:49.139 UTC [29] CONTEXT: WAL redo at 2/641F7058 for MultiXact/ZERO_OFF_PAGE: 296
2026-02-13 20:18:49.294 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:49.294 UTC [29] CONTEXT: WAL redo at 2/6594EB38 for MultiXact/CREATE_ID: 608255 offset 4759778 nmembers 21: 737536 (sh) 737584 (keysh) 737598 (sh) 739479 (sh) 739480 (sh) 739497 (sh) 739520 (sh) 739532 (keysh) 739538 (keysh) 739560 (keysh) 739578 (sh) 739580 (keysh) 739601 (sh) 739643 (sh) 739652 (keysh) 739693 (keysh) 739711 (sh) 739721 (sh) 739730 (keysh) 739743 (keysh) 739758 (sh)
2026-02-13 20:18:49.295 UTC [29] DEBUG: skipping initialization of offsets page 297 because it was already initialized on multixid creation
2026-02-13 20:18:49.295 UTC [29] CONTEXT: WAL redo at 2/6594EE20 for MultiXact/ZERO_OFF_PAGE: 297
2026-02-13 20:18:49.510 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:49.510 UTC [29] CONTEXT: WAL redo at 2/674F12A8 for MultiXact/CREATE_ID: 610303 offset 4777716 nmembers 24: 739430 (keysh) 739432 (sh) 741925 (sh) 741940 (sh) 741980 (keysh) 742013 (sh) 742036 (keysh) 742054 (sh) 742070 (sh) 742096 (sh) 742101 (sh) 742107 (keysh) 742113 (keysh) 742128 (keysh) 742135 (sh) 742138 (sh) 742154 (sh) 742162 (keysh) 742164 (keysh) 742179 (keysh) 742202 (sh) 742211 (sh) 742223 (sh) 742246 (sh)
2026-02-13 20:18:49.510 UTC [29] DEBUG: skipping initialization of offsets page 298 because it was already initialized on multixid creation
2026-02-13 20:18:49.510 UTC [29] CONTEXT: WAL redo at 2/674F13F0 for MultiXact/ZERO_OFF_PAGE: 298
2026-02-13 20:18:49.813 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:49.813 UTC [29] CONTEXT: WAL redo at 2/698708F0 for MultiXact/CREATE_ID: 612351 offset 4795073 nmembers 6: 744389 (sh) 744406 (keysh) 744475 (sh) 744800 (sh) 744857 (keysh) 744893 (sh)
2026-02-13 20:18:49.813 UTC [29] DEBUG: skipping initialization of offsets page 299 because it was already initialized on multixid creation
2026-02-13 20:18:49.813 UTC [29] CONTEXT: WAL redo at 2/69870980 for MultiXact/ZERO_OFF_PAGE: 299
2026-02-13 20:18:50.562 UTC [27] DEBUG: checkpoint sync: number=29 file=base/5/16398.1 time=2842.846 ms
2026-02-13 20:18:50.563 UTC [27] DEBUG: checkpoint sync: number=30 file=pg_multixact/members/004F time=0.009 ms
2026-02-13 20:18:50.563 UTC [27] DEBUG: checkpoint sync: number=31 file=base/5/16386_fsm time=0.002 ms
2026-02-13 20:18:50.563 UTC [27] DEBUG: checkpoint sync: number=32 file=base/5/2619_vm time=0.001 ms
2026-02-13 20:18:50.566 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:50.566 UTC [29] CONTEXT: WAL redo at 2/6B030A18 for MultiXact/CREATE_ID: 614399 offset 4812096 nmembers 7: 745952 (sh) 746928 (sh) 747196 (sh) 747267 (keysh) 747305 (sh) 747336 (keysh) 747383 (sh)
2026-02-13 20:18:50.566 UTC [27] LOG: restartpoint complete: wrote 8351 buffers (25.5%); 0 WAL file(s) added, 0 removed, 0 recycled; write=9.171 s, sync=6.485 s, total=15.669 s; sync files=32, longest=3.535 s, average=0.203 s; distance=540261 kB, estimate=540261 kB; lsn=2/3DAF23B0, redo lsn=2/1F02F820
2026-02-13 20:18:50.566 UTC [27] LOG: recovery restart point at 2/1F02F820
2026-02-13 20:18:50.566 UTC [27] DETAIL: Last completed transaction was at log time 2026-02-13 20:13:29.39751+00.
2026-02-13 20:18:50.566 UTC [27] LOG: restartpoint starting: wal
2026-02-13 20:18:50.566 UTC [27] DEBUG: performing replication slot checkpoint
2026-02-13 20:18:50.566 UTC [29] DEBUG: skipping initialization of offsets page 300 because it was already initialized on multixid creation
2026-02-13 20:18:50.566 UTC [29] CONTEXT: WAL redo at 2/6B082598 for MultiXact/ZERO_OFF_PAGE: 300
2026-02-13 20:18:50.794 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:50.794 UTC [29] CONTEXT: WAL redo at 2/6D0B0380 for MultiXact/CREATE_ID: 616447 offset 4828572 nmembers 4: 749724 (sh) 749851 (keysh) 749854 (sh) 750021 (sh)
2026-02-13 20:18:50.794 UTC [29] DEBUG: skipping initialization of offsets page 301 because it was already initialized on multixid creation
2026-02-13 20:18:50.794 UTC [29] CONTEXT: WAL redo at 2/6D0B7D88 for MultiXact/ZERO_OFF_PAGE: 301
2026-02-13 20:18:51.168 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:51.168 UTC [29] CONTEXT: WAL redo at 2/6E3A4B58 for MultiXact/CREATE_ID: 618495 offset 4844442 nmembers 6: 751773 (sh) 752246 (sh) 752318 (keysh) 752405 (sh) 752431 (sh) 752478 (keysh)
2026-02-13 20:18:51.168 UTC [29] DEBUG: skipping initialization of offsets page 302 because it was already initialized on multixid creation
2026-02-13 20:18:51.168 UTC [29] CONTEXT: WAL redo at 2/6E3A7CA0 for MultiXact/ZERO_OFF_PAGE: 302
2026-02-13 20:18:51.458 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:51.458 UTC [29] CONTEXT: WAL redo at 2/6FCD0C20 for MultiXact/CREATE_ID: 620543 offset 4860336 nmembers 4: 754536 (sh) 754565 (keysh) 754590 (sh) 755036 (sh)
2026-02-13 20:18:51.458 UTC [29] DEBUG: skipping initialization of offsets page 303 because it was already initialized on multixid creation
2026-02-13 20:18:51.458 UTC [29] CONTEXT: WAL redo at 2/6FCD0CF0 for MultiXact/ZERO_OFF_PAGE: 303
2026-02-13 20:18:51.782 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:51.782 UTC [29] CONTEXT: WAL redo at 2/71449D28 for MultiXact/CREATE_ID: 622591 offset 4877104 nmembers 5: 757248 (sh) 757289 (keysh) 757381 (sh) 757478 (keysh) 757544 (sh)
2026-02-13 20:18:51.782 UTC [29] DEBUG: skipping initialization of offsets page 304 because it was already initialized on multixid creation
2026-02-13 20:18:51.782 UTC [29] CONTEXT: WAL redo at 2/71449DB0 for MultiXact/ZERO_OFF_PAGE: 304
2026-02-13 20:18:52.031 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:52.031 UTC [29] CONTEXT: WAL redo at 2/729E2168 for MultiXact/CREATE_ID: 624639 offset 4893508 nmembers 4: 759663 (keysh) 759672 (sh) 760005 (sh) 760022 (keysh)
2026-02-13 20:18:52.031 UTC [29] DEBUG: skipping initialization of offsets page 305 because it was already initialized on multixid creation
2026-02-13 20:18:52.031 UTC [29] CONTEXT: WAL redo at 2/729FC328 for MultiXact/ZERO_OFF_PAGE: 305
2026-02-13 20:18:52.960 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:52.960 UTC [29] CONTEXT: WAL redo at 2/749C0418 for MultiXact/CREATE_ID: 626687 offset 4908953 nmembers 3: 760149 (sh) 762667 (sh) 762684 (keysh)
2026-02-13 20:18:52.960 UTC [29] DEBUG: skipping initialization of offsets page 306 because it was already initialized on multixid creation
2026-02-13 20:18:52.960 UTC [29] CONTEXT: WAL redo at 2/749C0490 for MultiXact/ZERO_OFF_PAGE: 306
2026-02-13 20:18:53.287 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:53.287 UTC [29] CONTEXT: WAL redo at 2/76A86650 for MultiXact/CREATE_ID: 628735 offset 4924242 nmembers 9: 764788 (keysh) 764836 (sh) 764883 (keysh) 764933 (sh) 765276 (sh) 765301 (keysh) 765317 (sh) 765406 (sh) 765427 (keysh)
2026-02-13 20:18:53.287 UTC [29] DEBUG: skipping initialization of offsets page 307 because it was already initialized on multixid creation
2026-02-13 20:18:53.287 UTC [29] CONTEXT: WAL redo at 2/76A86730 for MultiXact/ZERO_OFF_PAGE: 307
2026-02-13 20:18:53.452 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:53.452 UTC [29] CONTEXT: WAL redo at 2/77D94310 for MultiXact/CREATE_ID: 630783 offset 4939035 nmembers 8: 766916 (sh) 766986 (keysh) 767031 (sh) 767762 (sh) 767788 (keysh) 767807 (sh) 767874 (keysh) 767916 (sh)
2026-02-13 20:18:53.452 UTC [29] DEBUG: skipping initialization of offsets page 308 because it was already initialized on multixid creation
2026-02-13 20:18:53.452 UTC [29] CONTEXT: WAL redo at 2/77D943B0 for MultiXact/ZERO_OFF_PAGE: 308
2026-02-13 20:18:53.677 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:53.677 UTC [29] CONTEXT: WAL redo at 2/78EBEB58 for MultiXact/CREATE_ID: 632831 offset 4955014 nmembers 9: 770089 (sh) 770158 (keysh) 770186 (sh) 770219 (sh) 770253 (keysh) 770265 (keysh) 770313 (sh) 770339 (sh) 770376 (keysh)
2026-02-13 20:18:53.677 UTC [29] DEBUG: skipping initialization of offsets page 309 because it was already initialized on multixid creation
2026-02-13 20:18:53.677 UTC [29] CONTEXT: WAL redo at 2/78EBEC00 for MultiXact/ZERO_OFF_PAGE: 309
2026-02-13 20:18:53.993 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:53.993 UTC [29] CONTEXT: WAL redo at 2/7A7A5C60 for MultiXact/CREATE_ID: 634879 offset 4971480 nmembers 8: 770606 (sh) 770621 (sh) 770663 (keysh) 770673 (keysh) 770730 (sh) 770745 (sh) 772865 (keysh) 772961 (keysh)
2026-02-13 20:18:53.993 UTC [29] DEBUG: skipping initialization of offsets page 310 because it was already initialized on multixid creation
2026-02-13 20:18:53.993 UTC [29] CONTEXT: WAL redo at 2/7A7B0708 for MultiXact/ZERO_OFF_PAGE: 310
2026-02-13 20:18:54.197 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:54.197 UTC [29] CONTEXT: WAL redo at 2/7C0C70C0 for MultiXact/CREATE_ID: 636927 offset 4987244 nmembers 5: 773851 (keysh) 774919 (keysh) 774934 (sh) 775335 (sh) 775584 (sh)
2026-02-13 20:18:54.197 UTC [29] DEBUG: skipping initialization of offsets page 311 because it was already initialized on multixid creation
2026-02-13 20:18:54.197 UTC [29] CONTEXT: WAL redo at 2/7C0D2F40 for MultiXact/ZERO_OFF_PAGE: 311
2026-02-13 20:18:54.422 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:54.422 UTC [29] CONTEXT: WAL redo at 2/7D3CFBA0 for MultiXact/CREATE_ID: 638975 offset 5003821 nmembers 9: 775329 (sh) 776682 (keysh) 776717 (sh) 776757 (keysh) 777258 (sh) 777516 (sh) 777545 (keysh) 778066 (sh) 778070 (keysh)
2026-02-13 20:18:54.422 UTC [29] DEBUG: skipping initialization of offsets page 312 because it was already initialized on multixid creation
2026-02-13 20:18:54.422 UTC [29] CONTEXT: WAL redo at 2/7D3CFC48 for MultiXact/ZERO_OFF_PAGE: 312
2026-02-13 20:18:54.881 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:54.881 UTC [29] CONTEXT: WAL redo at 2/7F7D1E20 for MultiXact/CREATE_ID: 641023 offset 5018974 nmembers 4: 779116 (sh) 779161 (keysh) 779966 (sh) 780968 (sh)
2026-02-13 20:18:54.881 UTC [29] DEBUG: skipping initialization of offsets page 313 because it was already initialized on multixid creation
2026-02-13 20:18:54.881 UTC [29] CONTEXT: WAL redo at 2/7F7D3C48 for MultiXact/ZERO_OFF_PAGE: 313
2026-02-13 20:18:55.258 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:55.258 UTC [29] CONTEXT: WAL redo at 2/80DA4498 for MultiXact/CREATE_ID: 643071 offset 5035460 nmembers 5: 782516 (sh) 783442 (sh) 783458 (keysh) 783486 (sh) 783526 (keysh)
2026-02-13 20:18:55.258 UTC [29] DEBUG: skipping initialization of offsets page 314 because it was already initialized on multixid creation
2026-02-13 20:18:55.258 UTC [29] CONTEXT: WAL redo at 2/80DA4548 for MultiXact/ZERO_OFF_PAGE: 314
2026-02-13 20:18:55.533 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:55.533 UTC [29] CONTEXT: WAL redo at 2/826D1820 for MultiXact/CREATE_ID: 645119 offset 5050767 nmembers 5: 784340 (sh) 786079 (sh) 786087 (keysh) 786102 (sh) 786115 (keysh)
2026-02-13 20:18:55.533 UTC [29] DEBUG: skipping initialization of offsets page 315 because it was already initialized on multixid creation
2026-02-13 20:18:55.533 UTC [29] CONTEXT: WAL redo at 2/826D18A8 for MultiXact/ZERO_OFF_PAGE: 315
2026-02-13 20:18:56.089 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:56.089 UTC [29] CONTEXT: WAL redo at 2/84AD8790 for MultiXact/CREATE_ID: 647167 offset 5067701 nmembers 8: 786901 (keysh) 786969 (sh) 787120 (keysh) 787161 (sh) 788497 (sh) 788544 (keysh) 788606 (sh) 788653 (keysh)
2026-02-13 20:18:56.090 UTC [29] DEBUG: skipping initialization of offsets page 316 because it was already initialized on multixid creation
2026-02-13 20:18:56.090 UTC [29] CONTEXT: WAL redo at 2/84AD8830 for MultiXact/ZERO_OFF_PAGE: 316
2026-02-13 20:18:56.545 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:56.545 UTC [29] CONTEXT: WAL redo at 2/86B1E9D8 for MultiXact/CREATE_ID: 649215 offset 5083243 nmembers 5: 789614 (sh) 791125 (sh) 791152 (keysh) 791174 (sh) 791201 (keysh)
2026-02-13 20:18:56.545 UTC [29] DEBUG: skipping initialization of offsets page 317 because it was already initialized on multixid creation
2026-02-13 20:18:56.545 UTC [29] CONTEXT: WAL redo at 2/86B1EA60 for MultiXact/ZERO_OFF_PAGE: 317
2026-02-13 20:18:57.058 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:57.058 UTC [29] CONTEXT: WAL redo at 2/88804250 for MultiXact/CREATE_ID: 651263 offset 5100084 nmembers 4: 792154 (sh) 793019 (sh) 793681 (sh) 793695 (keysh)
2026-02-13 20:18:57.058 UTC [29] DEBUG: skipping initialization of offsets page 318 because it was already initialized on multixid creation
2026-02-13 20:18:57.058 UTC [29] CONTEXT: WAL redo at 2/888042D0 for MultiXact/ZERO_OFF_PAGE: 318
2026-02-13 20:18:57.109 UTC [27] DEBUG: checkpoint sync: number=1 file=pg_multixact/offsets/0008 time=3.955 ms
2026-02-13 20:18:57.113 UTC [27] DEBUG: checkpoint sync: number=2 file=pg_multixact/offsets/0009 time=3.657 ms
2026-02-13 20:18:57.113 UTC [27] DEBUG: checkpoint sync: number=3 file=base/5/16397 time=0.003 ms
2026-02-13 20:18:57.244 UTC [27] DEBUG: checkpoint sync: number=4 file=base/5/16407 time=131.308 ms
2026-02-13 20:18:57.245 UTC [27] DEBUG: checkpoint sync: number=5 file=pg_multixact/members/005E time=0.974 ms
2026-02-13 20:18:57.245 UTC [27] DEBUG: checkpoint sync: number=6 file=base/5/16386_vm time=0.003 ms
2026-02-13 20:18:57.246 UTC [27] DEBUG: checkpoint sync: number=7 file=pg_multixact/members/0058 time=1.367 ms
2026-02-13 20:18:57.247 UTC [27] DEBUG: checkpoint sync: number=8 file=pg_multixact/members/0061 time=0.656 ms
2026-02-13 20:18:57.249 UTC [27] DEBUG: checkpoint sync: number=9 file=pg_multixact/members/005B time=1.661 ms
2026-02-13 20:18:57.264 UTC [27] DEBUG: checkpoint sync: number=10 file=pg_xact/0000 time=15.062 ms
2026-02-13 20:18:57.272 UTC [27] DEBUG: checkpoint sync: number=11 file=pg_multixact/members/005C time=7.792 ms
2026-02-13 20:18:57.520 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:57.520 UTC [29] CONTEXT: WAL redo at 2/8A5B0B08 for MultiXact/CREATE_ID: 653311 offset 5116446 nmembers 2: 796202 (sh) 796227 (keysh)
2026-02-13 20:18:57.520 UTC [29] DEBUG: skipping initialization of offsets page 319 because it was already initialized on multixid creation
2026-02-13 20:18:57.520 UTC [29] CONTEXT: WAL redo at 2/8A5B0B78 for MultiXact/ZERO_OFF_PAGE: 319
2026-02-13 20:18:58.162 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:58.162 UTC [29] CONTEXT: WAL redo at 2/8C0E4E58 for MultiXact/CREATE_ID: 655359 offset 5132608 nmembers 3: 798669 (sh) 798687 (keysh) 798713 (sh)
2026-02-13 20:18:58.162 UTC [29] DEBUG: skipping initialization of offsets page 320 because it was already initialized on multixid creation
2026-02-13 20:18:58.162 UTC [29] CONTEXT: WAL redo at 2/8C0E4ED0 for MultiXact/ZERO_OFF_PAGE: 320
2026-02-13 20:18:58.473 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:58.473 UTC [29] CONTEXT: WAL redo at 2/8DCC3D60 for MultiXact/CREATE_ID: 657407 offset 5148238 nmembers 6: 800962 (sh) 801007 (keysh) 801133 (sh) 801139 (sh) 801242 (keysh) 801255 (keysh)
2026-02-13 20:18:58.473 UTC [29] DEBUG: skipping initialization of offsets page 321 because it was already initialized on multixid creation
2026-02-13 20:18:58.473 UTC [29] CONTEXT: WAL redo at 2/8DCC3DF0 for MultiXact/ZERO_OFF_PAGE: 321
2026-02-13 20:18:58.798 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:58.798 UTC [29] CONTEXT: WAL redo at 2/8F9BBD10 for MultiXact/CREATE_ID: 659455 offset 5164044 nmembers 10: 803195 (sh) 803225 (keysh) 803255 (sh) 803558 (sh) 803587 (keysh) 803608 (sh) 803643 (keysh) 803724 (sh) 803784 (sh) 803799 (keysh)
2026-02-13 20:18:58.798 UTC [29] DEBUG: skipping initialization of offsets page 322 because it was already initialized on multixid creation
2026-02-13 20:18:58.798 UTC [29] CONTEXT: WAL redo at 2/8F9BDCD0 for MultiXact/ZERO_OFF_PAGE: 322
2026-02-13 20:18:59.064 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:59.064 UTC [29] CONTEXT: WAL redo at 2/90E820E8 for MultiXact/CREATE_ID: 661503 offset 5180895 nmembers 12: 804787 (sh) 804836 (keysh) 806051 (keysh) 806118 (sh) 806131 (sh) 806171 (keysh) 806179 (keysh) 806187 (sh) 806219 (keysh) 806220 (sh) 806221 (sh) 806254 (keysh)
2026-02-13 20:18:59.065 UTC [29] DEBUG: skipping initialization of offsets page 323 because it was already initialized on multixid creation
2026-02-13 20:18:59.065 UTC [29] CONTEXT: WAL redo at 2/90E89758 for MultiXact/ZERO_OFF_PAGE: 323
2026-02-13 20:18:59.542 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:18:59.542 UTC [29] CONTEXT: WAL redo at 2/926CEE10 for MultiXact/CREATE_ID: 663551 offset 5196875 nmembers 9: 808566 (sh) 808588 (sh) 808619 (keysh) 808628 (keysh) 808668 (sh) 808672 (sh) 808739 (keysh) 808765 (keysh) 808782 (sh)
2026-02-13 20:18:59.542 UTC [29] DEBUG: skipping initialization of offsets page 324 because it was already initialized on multixid creation
2026-02-13 20:18:59.542 UTC [29] CONTEXT: WAL redo at 2/926D68E8 for MultiXact/ZERO_OFF_PAGE: 324
2026-02-13 20:19:00.218 UTC [27] DEBUG: checkpoint sync: number=12 file=base/5/16398 time=2946.328 ms
2026-02-13 20:19:00.236 UTC [27] DEBUG: checkpoint sync: number=13 file=base/5/16386 time=17.895 ms
2026-02-13 20:19:00.246 UTC [27] DEBUG: checkpoint sync: number=14 file=pg_multixact/members/005D time=9.661 ms
2026-02-13 20:19:00.249 UTC [27] DEBUG: checkpoint sync: number=15 file=base/5/16398_vm time=3.291 ms
2026-02-13 20:19:00.268 UTC [27] DEBUG: checkpoint sync: number=16 file=base/5/16414 time=18.852 ms
2026-02-13 20:19:00.271 UTC [27] DEBUG: checkpoint sync: number=17 file=base/5/16395 time=3.486 ms
2026-02-13 20:19:00.275 UTC [27] DEBUG: checkpoint sync: number=18 file=pg_multixact/members/0059 time=3.501 ms
2026-02-13 20:19:00.277 UTC [27] DEBUG: checkpoint sync: number=19 file=pg_multixact/members/005A time=2.279 ms
2026-02-13 20:19:00.280 UTC [27] DEBUG: checkpoint sync: number=20 file=pg_multixact/members/005F time=2.872 ms
2026-02-13 20:19:00.283 UTC [27] DEBUG: checkpoint sync: number=21 file=pg_multixact/members/0060 time=2.279 ms
2026-02-13 20:19:00.284 UTC [27] DEBUG: checkpoint sync: number=22 file=base/5/16398_fsm time=1.222 ms
2026-02-13 20:19:00.312 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:00.312 UTC [29] CONTEXT: WAL redo at 2/93DA67B0 for MultiXact/CREATE_ID: 665599 offset 5213120 nmembers 4: 810922 (keysh) 810960 (sh) 811246 (sh) 811287 (keysh)
2026-02-13 20:19:00.312 UTC [29] DEBUG: skipping initialization of offsets page 325 because it was already initialized on multixid creation
2026-02-13 20:19:00.312 UTC [29] CONTEXT: WAL redo at 2/93DA6858 for MultiXact/ZERO_OFF_PAGE: 325
2026-02-13 20:19:00.539 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:00.539 UTC [29] CONTEXT: WAL redo at 2/95B74088 for MultiXact/CREATE_ID: 667647 offset 5229395 nmembers 9: 813632 (sh) 813642 (keysh) 813669 (sh) 813688 (keysh) 813870 (sh) 813879 (keysh) 813886 (sh) 813901 (keysh) 813905 (sh)
2026-02-13 20:19:00.539 UTC [29] DEBUG: skipping initialization of offsets page 326 because it was already initialized on multixid creation
2026-02-13 20:19:00.539 UTC [29] CONTEXT: WAL redo at 2/95B74180 for MultiXact/ZERO_OFF_PAGE: 326
2026-02-13 20:19:01.133 UTC [27] DEBUG: checkpoint sync: number=23 file=base/5/16398.1 time=849.323 ms
2026-02-13 20:19:01.133 UTC [27] DEBUG: checkpoint sync: number=24 file=base/5/16386_fsm time=0.003 ms
2026-02-13 20:19:01.136 UTC [27] LOG: restartpoint complete: wrote 8419 buffers (25.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.535 s, sync=4.029 s, total=10.570 s; sync files=24, longest=2.947 s, average=0.168 s; distance=540839 kB, estimate=540839 kB; lsn=2/5F327EF0, redo lsn=2/400596C8
2026-02-13 20:19:01.136 UTC [27] LOG: recovery restart point at 2/400596C8
2026-02-13 20:19:01.136 UTC [27] DETAIL: Last completed transaction was at log time 2026-02-13 20:13:39.272492+00.
2026-02-13 20:19:01.137 UTC [27] LOG: restartpoint starting: wal
2026-02-13 20:19:01.137 UTC [27] DEBUG: performing replication slot checkpoint
2026-02-13 20:19:01.431 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:01.431 UTC [29] CONTEXT: WAL redo at 2/987C44D8 for MultiXact/CREATE_ID: 669695 offset 5245546 nmembers 3: 816624 (sh) 816797 (sh) 816820 (keysh)
2026-02-13 20:19:01.431 UTC [29] DEBUG: skipping initialization of offsets page 327 because it was already initialized on multixid creation
2026-02-13 20:19:01.431 UTC [29] CONTEXT: WAL redo at 2/987C4550 for MultiXact/ZERO_OFF_PAGE: 327
2026-02-13 20:19:01.798 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:01.798 UTC [29] CONTEXT: WAL redo at 2/9A0CB500 for MultiXact/CREATE_ID: 671743 offset 5260500 nmembers 13: 818473 (keysh) 818487 (sh) 818506 (keysh) 818539 (sh) 818812 (sh) 818894 (keysh) 818962 (sh) 819044 (keysh) 819085 (sh) 819141 (sh) 819173 (keysh) 819190 (sh) 819412 (sh)
2026-02-13 20:19:01.798 UTC [29] DEBUG: skipping initialization of offsets page 328 because it was already initialized on multixid creation
2026-02-13 20:19:01.798 UTC [29] CONTEXT: WAL redo at 2/9A0CB618 for MultiXact/ZERO_OFF_PAGE: 328
2026-02-13 20:19:02.049 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:02.049 UTC [29] CONTEXT: WAL redo at 2/9B9B8FC8 for MultiXact/CREATE_ID: 673791 offset 5275543 nmembers 5: 821991 (sh) 821995 (keysh) 821999 (sh) 822001 (keysh) 822003 (sh)
2026-02-13 20:19:02.049 UTC [29] DEBUG: skipping initialization of offsets page 329 because it was already initialized on multixid creation
2026-02-13 20:19:02.049 UTC [29] CONTEXT: WAL redo at 2/9B9B90C8 for MultiXact/ZERO_OFF_PAGE: 329
2026-02-13 20:19:02.419 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:02.419 UTC [29] CONTEXT: WAL redo at 2/9C91EE68 for MultiXact/CREATE_ID: 675839 offset 5291765 nmembers 5: 824158 (sh) 824223 (keysh) 824297 (sh) 824363 (keysh) 824439 (sh)
2026-02-13 20:19:02.419 UTC [29] DEBUG: skipping initialization of offsets page 330 because it was already initialized on multixid creation
2026-02-13 20:19:02.419 UTC [29] CONTEXT: WAL redo at 2/9C91EEF0 for MultiXact/ZERO_OFF_PAGE: 330
2026-02-13 20:19:02.539 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:02.539 UTC [29] CONTEXT: WAL redo at 2/9DDFCAD8 for MultiXact/CREATE_ID: 677887 offset 5307667 nmembers 8: 825398 (sh) 826655 (sh) 826685 (keysh) 826828 (sh) 826862 (keysh) 826888 (sh) 826920 (keysh) 826942 (sh)
2026-02-13 20:19:02.540 UTC [29] DEBUG: skipping initialization of offsets page 331 because it was already initialized on multixid creation
2026-02-13 20:19:02.540 UTC [29] CONTEXT: WAL redo at 2/9DDFCD30 for MultiXact/ZERO_OFF_PAGE: 331
2026-02-13 20:19:02.853 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:02.853 UTC [29] CONTEXT: WAL redo at 2/9F1D2C98 for MultiXact/CREATE_ID: 679935 offset 5323059 nmembers 4: 829067 (sh) 829073 (keysh) 829163 (sh) 829449 (sh)
2026-02-13 20:19:02.853 UTC [29] DEBUG: skipping initialization of offsets page 332 because it was already initialized on multixid creation
2026-02-13 20:19:02.853 UTC [29] CONTEXT: WAL redo at 2/9F1D2D18 for MultiXact/ZERO_OFF_PAGE: 332
2026-02-13 20:19:02.992 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:02.992 UTC [29] CONTEXT: WAL redo at 2/A0255578 for MultiXact/CREATE_ID: 681983 offset 5337859 nmembers 8: 831722 (sh) 831769 (keysh) 831794 (sh) 831814 (keysh) 831829 (sh) 831842 (sh) 831859 (keysh) 831880 (sh)
2026-02-13 20:19:02.992 UTC [29] DEBUG: skipping initialization of offsets page 333 because it was already initialized on multixid creation
2026-02-13 20:19:02.992 UTC [29] CONTEXT: WAL redo at 2/A0260650 for MultiXact/ZERO_OFF_PAGE: 333
2026-02-13 20:19:03.215 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:03.215 UTC [29] CONTEXT: WAL redo at 2/A13431D8 for MultiXact/CREATE_ID: 684031 offset 5353197 nmembers 8: 833947 (sh) 834003 (keysh) 834117 (sh) 834215 (sh) 834257 (keysh) 834300 (sh) 834354 (keysh) 834373 (sh)
2026-02-13 20:19:03.215 UTC [29] DEBUG: skipping initialization of offsets page 334 because it was already initialized on multixid creation
2026-02-13 20:19:03.215 UTC [29] CONTEXT: WAL redo at 2/A13432A0 for MultiXact/ZERO_OFF_PAGE: 334
2026-02-13 20:19:03.445 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:03.445 UTC [29] CONTEXT: WAL redo at 2/A268BDF8 for MultiXact/CREATE_ID: 686079 offset 5368141 nmembers 4: 836744 (sh) 836785 (keysh) 836826 (sh) 836876 (sh)
2026-02-13 20:19:03.445 UTC [29] DEBUG: skipping initialization of offsets page 335 because it was already initialized on multixid creation
2026-02-13 20:19:03.445 UTC [29] CONTEXT: WAL redo at 2/A268BEA0 for MultiXact/ZERO_OFF_PAGE: 335
2026-02-13 20:19:03.523 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:03.523 UTC [29] CONTEXT: WAL redo at 2/A3B2C5B0 for MultiXact/CREATE_ID: 688127 offset 5382490 nmembers 10: 838874 (sh) 838936 (sh) 839027 (keysh) 839044 (keysh) 839105 (sh) 839136 (sh) 839196 (keysh) 839253 (keysh) 839287 (sh) 839354 (sh)
2026-02-13 20:19:03.523 UTC [29] DEBUG: skipping initialization of offsets page 336 because it was already initialized on multixid creation
2026-02-13 20:19:03.523 UTC [29] CONTEXT: WAL redo at 2/A3B2C688 for MultiXact/ZERO_OFF_PAGE: 336
2026-02-13 20:19:03.890 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:03.890 UTC [29] CONTEXT: WAL redo at 2/A5B14E08 for MultiXact/CREATE_ID: 690175 offset 5399020 nmembers 5: 841536 (sh) 841621 (keysh) 841725 (sh) 841806 (keysh) 841843 (sh)
2026-02-13 20:19:03.890 UTC [29] DEBUG: skipping initialization of offsets page 337 because it was already initialized on multixid creation
2026-02-13 20:19:03.890 UTC [29] CONTEXT: WAL redo at 2/A5B14EB8 for MultiXact/ZERO_OFF_PAGE: 337
2026-02-13 20:19:04.199 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:04.199 UTC [29] CONTEXT: WAL redo at 2/A7A970C0 for MultiXact/CREATE_ID: 692223 offset 5413922 nmembers 8: 843414 (sh) 843789 (sh) 843791 (keysh) 843794 (sh) 844292 (sh) 844325 (keysh) 844352 (sh) 844369 (keysh)
2026-02-13 20:19:04.200 UTC [29] DEBUG: skipping initialization of offsets page 338 because it was already initialized on multixid creation
2026-02-13 20:19:04.200 UTC [29] CONTEXT: WAL redo at 2/A7A97160 for MultiXact/ZERO_OFF_PAGE: 338
2026-02-13 20:19:04.856 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:04.856 UTC [29] CONTEXT: WAL redo at 2/A99C44E8 for MultiXact/CREATE_ID: 694271 offset 5427414 nmembers 3: 844807 (keysh) 844836 (sh) 846898 (sh)
2026-02-13 20:19:04.856 UTC [29] DEBUG: skipping initialization of offsets page 339 because it was already initialized on multixid creation
2026-02-13 20:19:04.856 UTC [29] CONTEXT: WAL redo at 2/A99C4560 for MultiXact/ZERO_OFF_PAGE: 339
2026-02-13 20:19:04.993 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:04.993 UTC [29] CONTEXT: WAL redo at 2/ABE8F988 for MultiXact/CREATE_ID: 696319 offset 5442403 nmembers 8: 848013 (sh) 848053 (keysh) 848054 (sh) 848091 (keysh) 848100 (sh) 848114 (sh) 849473 (sh) 849501 (keysh)
2026-02-13 20:19:04.993 UTC [29] LOG: expected to see an XLOG_MULTIXACT_ZERO_OFF_PAGE record for page 340 that was implicitly initialized earlier
2026-02-13 20:19:04.993 UTC [29] CONTEXT: WAL redo at 2/ABE90D90 for MultiXact/CREATE_ID: 696317 offset 5442391 nmembers 6: 845204 (sh) 845310 (keysh) 847612 (sh) 847670 (keysh) 849474 (sh) 849497 (keysh)
2026-02-13 20:19:05.727 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:05.727 UTC [29] CONTEXT: WAL redo at 2/AD890680 for MultiXact/CREATE_ID: 698367 offset 5456809 nmembers 10: 850543 (sh) 851875 (keysh) 851880 (sh) 851894 (sh) 851911 (keysh) 851922 (keysh) 851933 (sh) 851941 (sh) 851948 (keysh) 851995 (sh)
2026-02-13 20:19:05.728 UTC [29] DEBUG: skipping initialization of offsets page 341 because it was already initialized on multixid creation
2026-02-13 20:19:05.728 UTC [29] CONTEXT: WAL redo at 2/AD890730 for MultiXact/ZERO_OFF_PAGE: 341
2026-02-13 20:19:05.943 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:05.943 UTC [29] CONTEXT: WAL redo at 2/AEAB5C88 for MultiXact/CREATE_ID: 700415 offset 5473251 nmembers 7: 852508 (sh) 852523 (keysh) 852537 (sh) 852584 (keysh) 852610 (sh) 854344 (sh) 854380 (keysh)
2026-02-13 20:19:05.943 UTC [29] DEBUG: skipping initialization of offsets page 342 because it was already initialized on multixid creation
2026-02-13 20:19:05.943 UTC [29] CONTEXT: WAL redo at 2/AEAB5D48 for MultiXact/ZERO_OFF_PAGE: 342
2026-02-13 20:19:06.073 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:06.073 UTC [29] CONTEXT: WAL redo at 2/AFA1BF58 for MultiXact/CREATE_ID: 702463 offset 5489099 nmembers 4: 852788 (keysh) 854611 (sh) 856695 (sh) 856741 (keysh)
2026-02-13 20:19:06.073 UTC [29] DEBUG: skipping initialization of offsets page 343 because it was already initialized on multixid creation
2026-02-13 20:19:06.073 UTC [29] CONTEXT: WAL redo at 2/AFA1F268 for MultiXact/ZERO_OFF_PAGE: 343
2026-02-13 20:19:06.255 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:06.255 UTC [29] CONTEXT: WAL redo at 2/B12460E8 for MultiXact/CREATE_ID: 704511 offset 5505124 nmembers 10: 858882 (sh) 859089 (sh) 859093 (keysh) 859130 (keysh) 859134 (sh) 859175 (keysh) 859180 (sh) 859205 (sh) 859215 (keysh) 859243 (sh)
2026-02-13 20:19:06.255 UTC [29] DEBUG: skipping initialization of offsets page 344 because it was already initialized on multixid creation
2026-02-13 20:19:06.255 UTC [29] CONTEXT: WAL redo at 2/B1246198 for MultiXact/ZERO_OFF_PAGE: 344
2026-02-13 20:19:07.238 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:07.238 UTC [29] CONTEXT: WAL redo at 2/B299FDE8 for MultiXact/CREATE_ID: 706559 offset 5520418 nmembers 3: 860395 (sh) 861695 (sh) 861710 (keysh)
2026-02-13 20:19:07.239 UTC [29] DEBUG: skipping initialization of offsets page 345 because it was already initialized on multixid creation
2026-02-13 20:19:07.239 UTC [29] CONTEXT: WAL redo at 2/B299FE88 for MultiXact/ZERO_OFF_PAGE: 345
2026-02-13 20:19:07.357 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:07.357 UTC [29] CONTEXT: WAL redo at 2/B4556F78 for MultiXact/CREATE_ID: 708607 offset 5535830 nmembers 3: 864099 (sh) 864166 (keysh) 864220 (sh)
2026-02-13 20:19:07.357 UTC [29] DEBUG: skipping initialization of offsets page 346 because it was already initialized on multixid creation
2026-02-13 20:19:07.357 UTC [29] CONTEXT: WAL redo at 2/B4556FF0 for MultiXact/ZERO_OFF_PAGE: 346
2026-02-13 20:19:07.390 UTC [27] DEBUG: checkpoint sync: number=1 file=pg_multixact/members/0064 time=4.298 ms
2026-02-13 20:19:07.393 UTC [27] DEBUG: checkpoint sync: number=2 file=pg_multixact/offsets/0009 time=2.351 ms
2026-02-13 20:19:07.393 UTC [27] DEBUG: checkpoint sync: number=3 file=base/5/16397 time=0.003 ms
2026-02-13 20:19:07.393 UTC [27] DEBUG: checkpoint sync: number=4 file=base/1/2619_fsm time=0.005 ms
2026-02-13 20:19:07.399 UTC [27] DEBUG: checkpoint sync: number=5 file=base/1/2696 time=5.874 ms
2026-02-13 20:19:07.452 UTC [27] DEBUG: checkpoint sync: number=6 file=base/5/16407 time=52.730 ms
2026-02-13 20:19:07.455 UTC [27] DEBUG: checkpoint sync: number=7 file=pg_multixact/offsets/000A time=2.864 ms
2026-02-13 20:19:07.455 UTC [27] DEBUG: checkpoint sync: number=8 file=global/2396_vm time=0.757 ms
2026-02-13 20:19:07.455 UTC [27] DEBUG: checkpoint sync: number=9 file=base/1/2840_fsm time=0.007 ms
2026-02-13 20:19:07.456 UTC [27] DEBUG: checkpoint sync: number=10 file=base/1/1259 time=0.946 ms
2026-02-13 20:19:07.457 UTC [27] DEBUG: checkpoint sync: number=11 file=base/5/16386_vm time=0.916 ms
2026-02-13 20:19:07.459 UTC [27] DEBUG: checkpoint sync: number=12 file=pg_multixact/members/0061 time=1.206 ms
2026-02-13 20:19:07.467 UTC [27] DEBUG: checkpoint sync: number=13 file=global/1260_vm time=8.936 ms
2026-02-13 20:19:07.467 UTC [27] DEBUG: checkpoint sync: number=14 file=base/1/2841 time=0.006 ms
2026-02-13 20:19:07.472 UTC [27] DEBUG: checkpoint sync: number=15 file=global/1262_vm time=4.366 ms
2026-02-13 20:19:07.477 UTC [27] DEBUG: checkpoint sync: number=16 file=pg_multixact/members/0066 time=4.875 ms
2026-02-13 20:19:07.484 UTC [27] DEBUG: checkpoint sync: number=17 file=pg_multixact/members/0065 time=7.206 ms
2026-02-13 20:19:07.505 UTC [27] DEBUG: checkpoint sync: number=18 file=pg_xact/0000 time=20.582 ms
2026-02-13 20:19:08.709 UTC [27] DEBUG: checkpoint sync: number=19 file=base/5/16398 time=1204.566 ms
2026-02-13 20:19:08.715 UTC [27] DEBUG: checkpoint sync: number=20 file=pg_multixact/members/0069 time=5.344 ms
2026-02-13 20:19:08.728 UTC [27] DEBUG: checkpoint sync: number=21 file=base/5/16386 time=12.940 ms
2026-02-13 20:19:08.730 UTC [27] DEBUG: checkpoint sync: number=22 file=base/5/16398_vm time=1.584 ms
2026-02-13 20:19:08.733 UTC [27] DEBUG: checkpoint sync: number=23 file=pg_multixact/members/0067 time=3.529 ms
2026-02-13 20:19:08.741 UTC [27] DEBUG: checkpoint sync: number=24 file=pg_multixact/members/0063 time=7.352 ms
2026-02-13 20:19:08.752 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:08.752 UTC [29] CONTEXT: WAL redo at 2/B6821368 for MultiXact/CREATE_ID: 710655 offset 5553308 nmembers 5: 866563 (sh) 866641 (keysh) 866685 (sh) 866722 (keysh) 866771 (sh)
2026-02-13 20:19:08.752 UTC [29] DEBUG: skipping initialization of offsets page 347 because it was already initialized on multixid creation
2026-02-13 20:19:08.752 UTC [29] CONTEXT: WAL redo at 2/B68213F0 for MultiXact/ZERO_OFF_PAGE: 347
2026-02-13 20:19:08.756 UTC [27] DEBUG: checkpoint sync: number=25 file=base/5/16414 time=15.513 ms
2026-02-13 20:19:08.758 UTC [27] DEBUG: checkpoint sync: number=26 file=base/5/16395 time=1.862 ms
2026-02-13 20:19:08.763 UTC [27] DEBUG: checkpoint sync: number=27 file=pg_multixact/members/0062 time=4.461 ms
2026-02-13 20:19:08.764 UTC [27] DEBUG: checkpoint sync: number=28 file=base/1/2840 time=1.515 ms
2026-02-13 20:19:08.774 UTC [27] DEBUG: checkpoint sync: number=29 file=base/1/2619 time=9.646 ms
2026-02-13 20:19:08.774 UTC [27] DEBUG: checkpoint sync: number=30 file=global/1260_fsm time=0.005 ms
2026-02-13 20:19:08.786 UTC [27] DEBUG: checkpoint sync: number=31 file=pg_multixact/members/0068 time=11.794 ms
2026-02-13 20:19:08.788 UTC [27] DEBUG: checkpoint sync: number=32 file=base/5/16398_fsm time=2.065 ms
2026-02-13 20:19:08.788 UTC [27] DEBUG: checkpoint sync: number=33 file=global/2396_fsm time=0.007 ms
2026-02-13 20:19:08.969 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:08.969 UTC [29] CONTEXT: WAL redo at 2/B94202C0 for MultiXact/CREATE_ID: 712703 offset 5568037 nmembers 13: 869381 (keysh) 869387 (sh) 869389 (sh) 869397 (sh) 869414 (keysh) 869415 (keysh) 869418 (keysh) 869434 (sh) 869435 (sh) 869441 (sh) 869468 (keysh) 869470 (keysh) 869507 (sh)
2026-02-13 20:19:08.969 UTC [29] DEBUG: skipping initialization of offsets page 348 because it was already initialized on multixid creation
2026-02-13 20:19:08.969 UTC [29] CONTEXT: WAL redo at 2/B9420388 for MultiXact/ZERO_OFF_PAGE: 348
2026-02-13 20:19:09.251 UTC [27] DEBUG: checkpoint sync: number=34 file=base/5/16398.1 time=463.421 ms
2026-02-13 20:19:09.251 UTC [27] DEBUG: checkpoint sync: number=35 file=global/1262_fsm time=0.006 ms
2026-02-13 20:19:09.251 UTC [27] DEBUG: checkpoint sync: number=36 file=base/5/16386_fsm time=0.001 ms
2026-02-13 20:19:09.257 UTC [27] LOG: restartpoint complete: wrote 7537 buffers (23.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=6.083 s, sync=1.866 s, total=8.121 s; sync files=36, longest=1.205 s, average=0.052 s; distance=540474 kB, estimate=540803 kB; lsn=2/804CF450, redo lsn=2/61028078
2026-02-13 20:19:09.257 UTC [27] LOG: recovery restart point at 2/61028078
2026-02-13 20:19:09.257 UTC [27] DETAIL: Last completed transaction was at log time 2026-02-13 20:13:48.61689+00.
2026-02-13 20:19:09.257 UTC [27] LOG: restartpoint starting: wal
2026-02-13 20:19:09.257 UTC [27] DEBUG: performing replication slot checkpoint
2026-02-13 20:19:09.313 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:09.313 UTC [29] CONTEXT: WAL redo at 2/BB796CC8 for MultiXact/CREATE_ID: 714751 offset 5584897 nmembers 9: 867993 (keysh) 868199 (sh) 870273 (keysh) 870379 (sh) 871999 (sh) 872002 (keysh) 872007 (sh) 872048 (keysh) 872076 (sh)
2026-02-13 20:19:09.313 UTC [29] DEBUG: skipping initialization of offsets page 349 because it was already initialized on multixid creation
2026-02-13 20:19:09.313 UTC [29] CONTEXT: WAL redo at 2/BB796D98 for MultiXact/ZERO_OFF_PAGE: 349
2026-02-13 20:19:09.513 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:09.513 UTC [29] CONTEXT: WAL redo at 2/BD2BF4E8 for MultiXact/CREATE_ID: 716799 offset 5602405 nmembers 7: 873249 (sh) 874200 (sh) 874439 (sh) 874474 (keysh) 874509 (sh) 874539 (keysh) 874567 (sh)
2026-02-13 20:19:09.513 UTC [29] DEBUG: skipping initialization of offsets page 350 because it was already initialized on multixid creation
2026-02-13 20:19:09.513 UTC [29] CONTEXT: WAL redo at 2/BD2BF5D0 for MultiXact/ZERO_OFF_PAGE: 350
2026-02-13 20:19:10.008 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:10.008 UTC [29] CONTEXT: WAL redo at 2/BF084558 for MultiXact/CREATE_ID: 718847 offset 5618114 nmembers 8: 875336 (sh) 876923 (keysh) 876925 (sh) 877002 (sh) 877057 (keysh) 877093 (sh) 877122 (keysh) 877158 (sh)
2026-02-13 20:19:10.009 UTC [29] DEBUG: skipping initialization of offsets page 351 because it was already initialized on multixid creation
2026-02-13 20:19:10.009 UTC [29] CONTEXT: WAL redo at 2/BF084648 for MultiXact/ZERO_OFF_PAGE: 351
2026-02-13 20:19:10.165 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:10.165 UTC [29] CONTEXT: WAL redo at 2/C0C5CB10 for MultiXact/CREATE_ID: 720895 offset 5633033 nmembers 13: 879447 (sh) 879455 (sh) 879482 (sh) 879515 (keysh) 879519 (keysh) 879543 (keysh) 879553 (sh) 879599 (sh) 879602 (sh) 879620 (keysh) 879646 (keysh) 879690 (sh) 879710 (sh)
2026-02-13 20:19:10.165 UTC [29] DEBUG: skipping initialization of offsets page 352 because it was already initialized on multixid creation
2026-02-13 20:19:10.165 UTC [29] CONTEXT: WAL redo at 2/C0C5CC40 for MultiXact/ZERO_OFF_PAGE: 352
2026-02-13 20:19:10.321 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:10.321 UTC [29] CONTEXT: WAL redo at 2/C2053650 for MultiXact/CREATE_ID: 722943 offset 5649501 nmembers 18: 877347 (sh) 877452 (keysh) 877496 (sh) 881237 (sh) 881300 (keysh) 881338 (sh) 881426 (sh) 881440 (sh) 881450 (keysh) 881463 (keysh) 881468 (sh) 881479 (sh) 881511 (keysh) 881541 (sh) 881554 (keysh) 881570 (sh) 882133 (sh) 882145 (keysh)
2026-02-13 20:19:10.321 UTC [29] DEBUG: skipping initialization of offsets page 353 because it was already initialized on multixid creation
2026-02-13 20:19:10.321 UTC [29] CONTEXT: WAL redo at 2/C2053740 for MultiXact/ZERO_OFF_PAGE: 353
2026-02-13 20:19:10.628 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:10.628 UTC [29] CONTEXT: WAL redo at 2/C37EFEF8 for MultiXact/CREATE_ID: 724991 offset 5665995 nmembers 3: 884578 (sh) 884601 (keysh) 884623 (sh)
2026-02-13 20:19:10.628 UTC [29] DEBUG: skipping initialization of offsets page 354 because it was already initialized on multixid creation
2026-02-13 20:19:10.628 UTC [29] CONTEXT: WAL redo at 2/C37F1F28 for MultiXact/ZERO_OFF_PAGE: 354
2026-02-13 20:19:10.708 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:10.708 UTC [29] CONTEXT: WAL redo at 2/C4EE99E0 for MultiXact/CREATE_ID: 727039 offset 5682187 nmembers 6: 886777 (keysh) 886825 (sh) 886881 (keysh) 886955 (sh) 887049 (sh) 887087 (keysh)
2026-02-13 20:19:10.708 UTC [29] DEBUG: skipping initialization of offsets page 355 because it was already initialized on multixid creation
2026-02-13 20:19:10.708 UTC [29] CONTEXT: WAL redo at 2/C4EE9A98 for MultiXact/ZERO_OFF_PAGE: 355
2026-02-13 20:19:10.988 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:10.988 UTC [29] CONTEXT: WAL redo at 2/C6780340 for MultiXact/CREATE_ID: 729087 offset 5696268 nmembers 7: 887561 (sh) 887587 (keysh) 887597 (sh) 887645 (keysh) 887689 (sh) 889621 (sh) 889670 (keysh)
2026-02-13 20:19:10.988 UTC [29] DEBUG: skipping initialization of offsets page 356 because it was already initialized on multixid creation
2026-02-13 20:19:10.988 UTC [29] CONTEXT: WAL redo at 2/C67804F0 for MultiXact/ZERO_OFF_PAGE: 356
2026-02-13 20:19:11.143 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:11.143 UTC [29] CONTEXT: WAL redo at 2/C812D0F0 for MultiXact/CREATE_ID: 731135 offset 5711308 nmembers 8: 891855 (keysh) 891902 (sh) 892082 (sh) 892119 (sh) 892150 (keysh) 892194 (sh) 892223 (keysh) 892232 (sh)
2026-02-13 20:19:11.143 UTC [29] DEBUG: skipping initialization of offsets page 357 because it was already initialized on multixid creation
2026-02-13 20:19:11.143 UTC [29] CONTEXT: WAL redo at 2/C812D190 for MultiXact/ZERO_OFF_PAGE: 357
2026-02-13 20:19:11.368 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:11.368 UTC [29] CONTEXT: WAL redo at 2/C97427A0 for MultiXact/CREATE_ID: 733183 offset 5727813 nmembers 5: 894642 (sh) 894666 (keysh) 894692 (sh) 894711 (keysh) 894723 (sh)
2026-02-13 20:19:11.368 UTC [29] DEBUG: skipping initialization of offsets page 358 because it was already initialized on multixid creation
2026-02-13 20:19:11.368 UTC [29] CONTEXT: WAL redo at 2/C9742828 for MultiXact/ZERO_OFF_PAGE: 358
2026-02-13 20:19:11.526 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:11.526 UTC [29] CONTEXT: WAL redo at 2/CAEC48F0 for MultiXact/CREATE_ID: 735231 offset 5743325 nmembers 7: 896959 (sh) 897023 (keysh) 897076 (sh) 897155 (keysh) 897176 (sh) 897240 (sh) 897244 (keysh)
2026-02-13 20:19:11.526 UTC [29] DEBUG: skipping initialization of offsets page 359 because it was already initialized on multixid creation
2026-02-13 20:19:11.526 UTC [29] CONTEXT: WAL redo at 2/CAEC4950 for MultiXact/ZERO_OFF_PAGE: 359
2026-02-13 20:19:11.612 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:11.612 UTC [29] CONTEXT: WAL redo at 2/CBEEF190 for MultiXact/CREATE_ID: 737279 offset 5759815 nmembers 12: 897849 (sh) 897898 (keysh) 898001 (sh) 898069 (keysh) 899342 (sh) 899392 (keysh) 899455 (sh) 899512 (sh) 899525 (keysh) 899579 (keysh) 899664 (sh) 899672 (sh)
2026-02-13 20:19:11.612 UTC [29] DEBUG: skipping initialization of offsets page 360 because it was already initialized on multixid creation
2026-02-13 20:19:11.612 UTC [29] CONTEXT: WAL redo at 2/CBEEF250 for MultiXact/ZERO_OFF_PAGE: 360
2026-02-13 20:19:11.810 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:11.810 UTC [29] CONTEXT: WAL redo at 2/CDA8F0D0 for MultiXact/CREATE_ID: 739327 offset 5775507 nmembers 10: 902161 (sh) 902175 (sh) 902189 (keysh) 902191 (keysh) 902219 (sh) 902225 (sh) 902259 (keysh) 902260 (keysh) 902286 (sh) 902290 (sh)
2026-02-13 20:19:11.810 UTC [29] DEBUG: skipping initialization of offsets page 361 because it was already initialized on multixid creation
2026-02-13 20:19:11.810 UTC [29] CONTEXT: WAL redo at 2/CDA8F180 for MultiXact/ZERO_OFF_PAGE: 361
2026-02-13 20:19:11.988 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:11.988 UTC [29] CONTEXT: WAL redo at 2/CF2C6CE8 for MultiXact/CREATE_ID: 741375 offset 5791222 nmembers 7: 901414 (keysh) 901518 (sh) 904210 (keysh) 904255 (sh) 904844 (sh) 904854 (keysh) 904862 (sh)
2026-02-13 20:19:11.988 UTC [29] DEBUG: skipping initialization of offsets page 362 because it was already initialized on multixid creation
2026-02-13 20:19:11.988 UTC [29] CONTEXT: WAL redo at 2/CF2C6D80 for MultiXact/ZERO_OFF_PAGE: 362
2026-02-13 20:19:12.195 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:12.195 UTC [29] CONTEXT: WAL redo at 2/D065C1A8 for MultiXact/CREATE_ID: 743423 offset 5807467 nmembers 5: 905789 (sh) 905812 (keysh) 905842 (sh) 907329 (sh) 907362 (keysh)
2026-02-13 20:19:12.196 UTC [29] DEBUG: skipping initialization of offsets page 363 because it was already initialized on multixid creation
2026-02-13 20:19:12.196 UTC [29] CONTEXT: WAL redo at 2/D065C370 for MultiXact/ZERO_OFF_PAGE: 363
2026-02-13 20:19:12.319 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:12.319 UTC [29] CONTEXT: WAL redo at 2/D17E5D20 for MultiXact/CREATE_ID: 745471 offset 5823192 nmembers 3: 909754 (sh) 909789 (keysh) 909833 (sh)
2026-02-13 20:19:12.320 UTC [29] DEBUG: skipping initialization of offsets page 364 because it was already initialized on multixid creation
2026-02-13 20:19:12.320 UTC [29] CONTEXT: WAL redo at 2/D17E5D98 for MultiXact/ZERO_OFF_PAGE: 364
2026-02-13 20:19:12.484 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:12.484 UTC [29] CONTEXT: WAL redo at 2/D2EB6168 for MultiXact/CREATE_ID: 747519 offset 5838706 nmembers 3: 910694 (sh) 912359 (sh) 912391 (sh)
2026-02-13 20:19:12.484 UTC [29] DEBUG: skipping initialization of offsets page 365 because it was already initialized on multixid creation
2026-02-13 20:19:12.484 UTC [29] CONTEXT: WAL redo at 2/D2EB61E0 for MultiXact/ZERO_OFF_PAGE: 365
2026-02-13 20:19:12.674 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:12.674 UTC [29] CONTEXT: WAL redo at 2/D4600FF8 for MultiXact/CREATE_ID: 749567 offset 5853554 nmembers 8: 912170 (sh) 912230 (keysh) 912282 (sh) 912327 (keysh) 914872 (sh) 914894 (keysh) 914918 (sh) 914922 (sh)
2026-02-13 20:19:12.674 UTC [29] DEBUG: skipping initialization of offsets page 366 because it was already initialized on multixid creation
2026-02-13 20:19:12.674 UTC [29] CONTEXT: WAL redo at 2/D46010E8 for MultiXact/ZERO_OFF_PAGE: 366
2026-02-13 20:19:12.879 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:12.879 UTC [29] CONTEXT: WAL redo at 2/D654BD90 for MultiXact/CREATE_ID: 751615 offset 5869491 nmembers 3: 917280 (sh) 917338 (keysh) 917420 (sh)
2026-02-13 20:19:12.879 UTC [29] DEBUG: skipping initialization of offsets page 367 because it was already initialized on multixid creation
2026-02-13 20:19:12.879 UTC [29] CONTEXT: WAL redo at 2/D654BE08 for MultiXact/ZERO_OFF_PAGE: 367
2026-02-13 20:19:13.039 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:13.039 UTC [29] CONTEXT: WAL redo at 2/D7D5CEE0 for MultiXact/CREATE_ID: 753663 offset 5885764 nmembers 9: 914419 (sh) 914435 (keysh) 914451 (sh) 919802 (keysh) 919804 (sh) 919827 (sh) 919833 (keysh) 919840 (sh) 919849 (keysh)
2026-02-13 20:19:13.039 UTC [29] DEBUG: skipping initialization of offsets page 368 because it was already initialized on multixid creation
2026-02-13 20:19:13.039 UTC [29] CONTEXT: WAL redo at 2/D7D5CFD8 for MultiXact/ZERO_OFF_PAGE: 368
2026-02-13 20:19:13.051 UTC [27] DEBUG: checkpoint sync: number=1 file=pg_multixact/members/006F time=6.438 ms
2026-02-13 20:19:13.053 UTC [27] DEBUG: checkpoint sync: number=2 file=base/5/16397 time=1.348 ms
2026-02-13 20:19:13.054 UTC [27] DEBUG: checkpoint sync: number=3 file=base/1/2619_fsm time=1.362 ms
2026-02-13 20:19:13.057 UTC [27] DEBUG: checkpoint sync: number=4 file=base/1/2696 time=2.595 ms
2026-02-13 20:19:13.141 UTC [27] DEBUG: checkpoint sync: number=5 file=base/5/16407 time=84.333 ms
2026-02-13 20:19:13.143 UTC [27] DEBUG: checkpoint sync: number=6 file=pg_multixact/offsets/000A time=2.013 ms
2026-02-13 20:19:13.146 UTC [27] DEBUG: checkpoint sync: number=7 file=base/1/2619_vm time=3.032 ms
2026-02-13 20:19:13.147 UTC [27] DEBUG: checkpoint sync: number=8 file=base/1/1259 time=0.949 ms
2026-02-13 20:19:13.149 UTC [27] DEBUG: checkpoint sync: number=9 file=base/1/2840_fsm time=1.934 ms
2026-02-13 20:19:13.152 UTC [27] DEBUG: checkpoint sync: number=10 file=global/1262 time=2.629 ms
2026-02-13 20:19:13.155 UTC [27] DEBUG: checkpoint sync: number=11 file=base/1/2841 time=3.401 ms
2026-02-13 20:19:13.157 UTC [27] DEBUG: checkpoint sync: number=12 file=base/1/2840_vm time=1.751 ms
2026-02-13 20:19:13.161 UTC [27] DEBUG: checkpoint sync: number=13 file=pg_xact/0000 time=3.926 ms
2026-02-13 20:19:13.163 UTC [27] DEBUG: checkpoint sync: number=14 file=pg_multixact/members/006D time=2.153 ms
2026-02-13 20:19:13.684 UTC [27] DEBUG: checkpoint sync: number=15 file=base/5/16398 time=520.676 ms
2026-02-13 20:19:13.688 UTC [27] DEBUG: checkpoint sync: number=16 file=pg_multixact/members/0069 time=3.588 ms
2026-02-13 20:19:13.727 UTC [27] DEBUG: checkpoint sync: number=17 file=base/5/16386 time=39.550 ms
2026-02-13 20:19:13.733 UTC [27] DEBUG: checkpoint sync: number=18 file=pg_multixact/members/006A time=5.915 ms
2026-02-13 20:19:13.736 UTC [27] DEBUG: checkpoint sync: number=19 file=pg_multixact/members/006B time=2.888 ms
2026-02-13 20:19:13.737 UTC [27] DEBUG: checkpoint sync: number=20 file=base/5/16398_vm time=1.090 ms
2026-02-13 20:19:13.738 UTC [27] DEBUG: checkpoint sync: number=21 file=global/2396 time=1.209 ms
2026-02-13 20:19:13.756 UTC [27] DEBUG: checkpoint sync: number=22 file=base/5/16414 time=17.522 ms
2026-02-13 20:19:13.759 UTC [27] DEBUG: checkpoint sync: number=23 file=base/5/16395 time=3.138 ms
2026-02-13 20:19:13.774 UTC [27] DEBUG: checkpoint sync: number=24 file=pg_multixact/members/006E time=15.098 ms
2026-02-13 20:19:13.781 UTC [27] DEBUG: checkpoint sync: number=25 file=base/1/2840 time=6.755 ms
2026-02-13 20:19:13.784 UTC [27] DEBUG: checkpoint sync: number=26 file=base/1/2619 time=2.517 ms
2026-02-13 20:19:13.784 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:13.784 UTC [29] CONTEXT: WAL redo at 2/D9F61C10 for MultiXact/CREATE_ID: 755711 offset 5900770 nmembers 6: 920315 (sh) 920359 (sh) 920371 (keysh) 922329 (sh) 922374 (keysh) 922430 (sh)
2026-02-13 20:19:13.786 UTC [29] DEBUG: skipping initialization of offsets page 369 because it was already initialized on multixid creation
2026-02-13 20:19:13.786 UTC [29] CONTEXT: WAL redo at 2/D9F61CA0 for MultiXact/ZERO_OFF_PAGE: 369
2026-02-13 20:19:13.788 UTC [27] DEBUG: checkpoint sync: number=27 file=pg_multixact/offsets/000B time=4.054 ms
2026-02-13 20:19:13.790 UTC [27] DEBUG: checkpoint sync: number=28 file=base/5/16398_fsm time=2.126 ms
2026-02-13 20:19:14.474 UTC [27] DEBUG: checkpoint sync: number=29 file=base/5/16398.1 time=683.982 ms
2026-02-13 20:19:14.477 UTC [27] DEBUG: checkpoint sync: number=30 file=pg_multixact/members/006C time=3.082 ms
2026-02-13 20:19:14.484 UTC [27] DEBUG: checkpoint sync: number=31 file=pg_multixact/members/0070 time=7.316 ms
2026-02-13 20:19:14.485 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:14.485 UTC [29] CONTEXT: WAL redo at 2/DB9A84C8 for MultiXact/CREATE_ID: 757759 offset 5918591 nmembers 10: 922620 (sh) 922677 (keysh) 924811 (sh) 924829 (sh) 924836 (keysh) 924858 (keysh) 924859 (sh) 924881 (sh) 924882 (keysh) 924904 (sh)
2026-02-13 20:19:14.485 UTC [29] DEBUG: skipping initialization of offsets page 370 because it was already initialized on multixid creation
2026-02-13 20:19:14.485 UTC [29] CONTEXT: WAL redo at 2/DB9A86B8 for MultiXact/ZERO_OFF_PAGE: 370
2026-02-13 20:19:14.516 UTC [27] DEBUG: checkpoint sync: number=32 file=global/1260 time=31.745 ms
2026-02-13 20:19:14.517 UTC [27] DEBUG: checkpoint sync: number=33 file=base/5/16386_fsm time=1.233 ms
2026-02-13 20:19:14.544 UTC [27] LOG: restartpoint complete: wrote 8526 buffers (26.0%); 1 WAL file(s) added, 0 removed, 0 recycled; write=3.780 s, sync=1.473 s, total=5.287 s; sync files=33, longest=0.684 s, average=0.045 s; distance=1351662 kB, estimate=1351662 kB; lsn=2/B6E5DD98, redo lsn=2/B3823B30
2026-02-13 20:19:14.544 UTC [27] LOG: recovery restart point at 2/B3823B30
2026-02-13 20:19:14.544 UTC [27] DETAIL: Last completed transaction was at log time 2026-02-13 20:13:55.371799+00.
2026-02-13 20:19:14.558 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:14.558 UTC [29] CONTEXT: WAL redo at 2/DD477E70 for MultiXact/CREATE_ID: 759807 offset 5934200 nmembers 6: 927103 (sh) 927118 (keysh) 927135 (sh) 927303 (sh) 927365 (keysh) 927383 (sh)
2026-02-13 20:19:14.558 UTC [29] DEBUG: skipping initialization of offsets page 371 because it was already initialized on multixid creation
2026-02-13 20:19:14.558 UTC [29] CONTEXT: WAL redo at 2/DD479A60 for MultiXact/ZERO_OFF_PAGE: 371
2026-02-13 20:19:14.720 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:14.720 UTC [29] CONTEXT: WAL redo at 2/DECEEF38 for MultiXact/CREATE_ID: 761855 offset 5949256 nmembers 10: 928237 (sh) 928290 (keysh) 928330 (sh) 928994 (sh) 929013 (keysh) 929026 (sh) 929804 (sh) 929821 (keysh) 929837 (sh) 929849 (keysh)
2026-02-13 20:19:14.720 UTC [29] DEBUG: skipping initialization of offsets page 372 because it was already initialized on multixid creation
2026-02-13 20:19:14.720 UTC [29] CONTEXT: WAL redo at 2/DECEF010 for MultiXact/ZERO_OFF_PAGE: 372
2026-02-13 20:19:14.966 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:14.966 UTC [29] CONTEXT: WAL redo at 2/E07BD198 for MultiXact/CREATE_ID: 763903 offset 5965584 nmembers 6: 931967 (sh) 931997 (keysh) 932034 (sh) 932093 (keysh) 932318 (sh) 932347 (keysh)
2026-02-13 20:19:14.966 UTC [29] DEBUG: skipping initialization of offsets page 373 because it was already initialized on multixid creation
2026-02-13 20:19:14.966 UTC [29] CONTEXT: WAL redo at 2/E07BD250 for MultiXact/ZERO_OFF_PAGE: 373
2026-02-13 20:19:15.331 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:15.331 UTC [29] CONTEXT: WAL redo at 2/E2873610 for MultiXact/CREATE_ID: 765951 offset 5982254 nmembers 3: 934902 (sh) 934924 (keysh) 934959 (sh)
2026-02-13 20:19:15.331 UTC [29] DEBUG: skipping initialization of offsets page 374 because it was already initialized on multixid creation
2026-02-13 20:19:15.331 UTC [29] CONTEXT: WAL redo at 2/E28736B0 for MultiXact/ZERO_OFF_PAGE: 374
2026-02-13 20:19:15.719 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:15.719 UTC [29] CONTEXT: WAL redo at 2/E4378F60 for MultiXact/CREATE_ID: 767999 offset 5999084 nmembers 2: 937342 (sh) 937494 (keysh)
2026-02-13 20:19:15.719 UTC [29] DEBUG: skipping initialization of offsets page 375 because it was already initialized on multixid creation
2026-02-13 20:19:15.719 UTC [29] CONTEXT: WAL redo at 2/E4378FF8 for MultiXact/ZERO_OFF_PAGE: 375
2026-02-13 20:19:16.032 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:16.032 UTC [29] CONTEXT: WAL redo at 2/E6199D60 for MultiXact/CREATE_ID: 770047 offset 6013700 nmembers 4: 939729 (sh) 939749 (keysh) 939772 (sh) 940086 (sh)
2026-02-13 20:19:16.032 UTC [29] DEBUG: skipping initialization of offsets page 376 because it was already initialized on multixid creation
2026-02-13 20:19:16.032 UTC [29] CONTEXT: WAL redo at 2/E6199DE0 for MultiXact/ZERO_OFF_PAGE: 376
2026-02-13 20:19:16.285 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:16.285 UTC [29] CONTEXT: WAL redo at 2/E7D74C88 for MultiXact/CREATE_ID: 772095 offset 6029103 nmembers 5: 942364 (sh) 942429 (keysh) 942578 (sh) 942621 (keysh) 942658 (sh)
2026-02-13 20:19:16.285 UTC [29] DEBUG: skipping initialization of offsets page 377 because it was already initialized on multixid creation
2026-02-13 20:19:16.285 UTC [29] CONTEXT: WAL redo at 2/E7D74D88 for MultiXact/ZERO_OFF_PAGE: 377
2026-02-13 20:19:16.545 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:16.545 UTC [29] CONTEXT: WAL redo at 2/E97AD200 for MultiXact/CREATE_ID: 774143 offset 6044494 nmembers 9: 945014 (sh) 945065 (sh) 945067 (keysh) 945101 (keysh) 945120 (sh) 945162 (sh) 945178 (keysh) 945221 (keysh) 945227 (sh)
2026-02-13 20:19:16.545 UTC [29] DEBUG: skipping initialization of offsets page 378 because it was already initialized on multixid creation
2026-02-13 20:19:16.545 UTC [29] CONTEXT: WAL redo at 2/E97B2AF8 for MultiXact/ZERO_OFF_PAGE: 378
2026-02-13 20:19:16.859 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:16.859 UTC [29] CONTEXT: WAL redo at 2/EB4A5A88 for MultiXact/CREATE_ID: 776191 offset 6060032 nmembers 7: 947702 (sh) 947723 (keysh) 947739 (sh) 947817 (sh) 947838 (keysh) 947850 (sh) 947861 (keysh)
2026-02-13 20:19:16.859 UTC [29] DEBUG: skipping initialization of offsets page 379 because it was already initialized on multixid creation
2026-02-13 20:19:16.859 UTC [29] CONTEXT: WAL redo at 2/EB4A5B20 for MultiXact/ZERO_OFF_PAGE: 379
2026-02-13 20:19:16.985 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:16.985 UTC [29] CONTEXT: WAL redo at 2/EC772578 for MultiXact/CREATE_ID: 778239 offset 6075727 nmembers 7: 949797 (sh) 949838 (keysh) 950237 (sh) 950250 (keysh) 950272 (sh) 950290 (keysh) 950320 (sh)
2026-02-13 20:19:16.985 UTC [29] DEBUG: skipping initialization of offsets page 380 because it was already initialized on multixid creation
2026-02-13 20:19:16.985 UTC [29] CONTEXT: WAL redo at 2/EC772610 for MultiXact/ZERO_OFF_PAGE: 380
2026-02-13 20:19:17.321 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:17.321 UTC [29] CONTEXT: WAL redo at 2/EE0809F0 for MultiXact/CREATE_ID: 780287 offset 6091083 nmembers 10: 951640 (sh) 951656 (keysh) 951734 (sh) 951766 (sh) 951774 (keysh) 951875 (keysh) 951879 (sh) 951930 (sh) 952890 (sh) 952905 (keysh)
2026-02-13 20:19:17.321 UTC [29] DEBUG: skipping initialization of offsets page 381 because it was already initialized on multixid creation
2026-02-13 20:19:17.321 UTC [29] CONTEXT: WAL redo at 2/EE080AA0 for MultiXact/ZERO_OFF_PAGE: 381
2026-02-13 20:19:17.554 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:17.554 UTC [29] CONTEXT: WAL redo at 2/EF6BB1B8 for MultiXact/CREATE_ID: 782335 offset 6106672 nmembers 4: 955419 (sh) 955422 (keysh) 955424 (sh) 955425 (keysh)
2026-02-13 20:19:17.555 UTC [29] DEBUG: skipping initialization of offsets page 382 because it was already initialized on multixid creation
2026-02-13 20:19:17.555 UTC [29] CONTEXT: WAL redo at 2/EF6BB238 for MultiXact/ZERO_OFF_PAGE: 382
2026-02-13 20:19:17.676 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:17.676 UTC [29] CONTEXT: WAL redo at 2/F0562430 for MultiXact/CREATE_ID: 784383 offset 6122805 nmembers 15: 956660 (keysh) 956734 (sh) 956813 (keysh) 956857 (sh) 957519 (sh) 957567 (sh) 957587 (keysh) 957637 (keysh) 957657 (sh) 957671 (sh) 957736 (keysh) 957738 (keysh) 957777 (sh) 957787 (sh) 957818 (keysh)
2026-02-13 20:19:17.676 UTC [29] DEBUG: skipping initialization of offsets page 383 because it was already initialized on multixid creation
2026-02-13 20:19:17.676 UTC [29] CONTEXT: WAL redo at 2/F0562550 for MultiXact/ZERO_OFF_PAGE: 383
2026-02-13 20:19:17.822 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:17.822 UTC [29] CONTEXT: WAL redo at 2/F1903E80 for MultiXact/CREATE_ID: 786431 offset 6139784 nmembers 5: 960111 (sh) 960116 (keysh) 960119 (sh) 960288 (sh) 960320 (keysh)
2026-02-13 20:19:17.822 UTC [29] DEBUG: skipping initialization of offsets page 384 because it was already initialized on multixid creation
2026-02-13 20:19:17.822 UTC [29] CONTEXT: WAL redo at 2/F1903F30 for MultiXact/ZERO_OFF_PAGE: 384
2026-02-13 20:19:17.908 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:17.908 UTC [29] CONTEXT: WAL redo at 2/F256C528 for MultiXact/CREATE_ID: 788479 offset 6154440 nmembers 3: 960212 (keysh) 960608 (sh) 962696 (sh)
2026-02-13 20:19:17.908 UTC [29] DEBUG: skipping initialization of offsets page 385 because it was already initialized on multixid creation
2026-02-13 20:19:17.908 UTC [29] CONTEXT: WAL redo at 2/F256C5E0 for MultiXact/ZERO_OFF_PAGE: 385
2026-02-13 20:19:18.074 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:18.074 UTC [29] CONTEXT: WAL redo at 2/F3E9F350 for MultiXact/CREATE_ID: 790527 offset 6169058 nmembers 10: 964192 (sh) 964195 (sh) 964267 (keysh) 964281 (keysh) 964358 (sh) 964360 (sh) 965237 (sh) 965272 (keysh) 965303 (sh) 965331 (keysh)
2026-02-13 20:19:18.074 UTC [29] DEBUG: skipping initialization of offsets page 386 because it was already initialized on multixid creation
2026-02-13 20:19:18.074 UTC [29] CONTEXT: WAL redo at 2/F3EA1298 for MultiXact/ZERO_OFF_PAGE: 386
2026-02-13 20:19:18.079 UTC [27] LOG: restartpoint starting: wal
2026-02-13 20:19:18.079 UTC [27] DEBUG: performing replication slot checkpoint
2026-02-13 20:19:18.273 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:18.273 UTC [29] CONTEXT: WAL redo at 2/F53E21F0 for MultiXact/CREATE_ID: 792575 offset 6183701 nmembers 4: 964280 (sh) 964382 (keysh) 967824 (sh) 967853 (keysh)
2026-02-13 20:19:18.273 UTC [29] DEBUG: skipping initialization of offsets page 387 because it was already initialized on multixid creation
2026-02-13 20:19:18.273 UTC [29] CONTEXT: WAL redo at 2/F53E5D68 for MultiXact/ZERO_OFF_PAGE: 387
2026-02-13 20:19:18.568 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:18.568 UTC [29] CONTEXT: WAL redo at 2/F76D9298 for MultiXact/CREATE_ID: 794623 offset 6199896 nmembers 3: 970352 (sh) 970387 (keysh) 970419 (sh)
2026-02-13 20:19:18.568 UTC [29] DEBUG: skipping initialization of offsets page 388 because it was already initialized on multixid creation
2026-02-13 20:19:18.568 UTC [29] CONTEXT: WAL redo at 2/F76D9310 for MultiXact/ZERO_OFF_PAGE: 388
2026-02-13 20:19:18.967 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:18.967 UTC [29] CONTEXT: WAL redo at 2/F97AC710 for MultiXact/CREATE_ID: 796671 offset 6214780 nmembers 8: 972777 (sh) 972817 (keysh) 972842 (sh) 972904 (sh) 972908 (sh) 972934 (keysh) 972936 (keysh) 972949 (sh)
2026-02-13 20:19:18.967 UTC [29] DEBUG: skipping initialization of offsets page 389 because it was already initialized on multixid creation
2026-02-13 20:19:18.967 UTC [29] CONTEXT: WAL redo at 2/F97AD940 for MultiXact/ZERO_OFF_PAGE: 389
2026-02-13 20:19:19.176 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:19.176 UTC [29] CONTEXT: WAL redo at 2/FB6663C8 for MultiXact/CREATE_ID: 798719 offset 6230897 nmembers 10: 974583 (sh) 974634 (keysh) 974714 (sh) 974879 (keysh) 974912 (sh) 975402 (sh) 975429 (keysh) 975445 (sh) 975452 (keysh) 975461 (sh)
2026-02-13 20:19:19.176 UTC [29] DEBUG: skipping initialization of offsets page 390 because it was already initialized on multixid creation
2026-02-13 20:19:19.176 UTC [29] CONTEXT: WAL redo at 2/FB6803A8 for MultiXact/ZERO_OFF_PAGE: 390
2026-02-13 20:19:19.400 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:19.400 UTC [29] CONTEXT: WAL redo at 2/FD3228B0 for MultiXact/CREATE_ID: 800767 offset 6246123 nmembers 3: 977928 (sh) 977941 (keysh) 977954 (sh)
2026-02-13 20:19:19.400 UTC [29] DEBUG: skipping initialization of offsets page 391 because it was already initialized on multixid creation
2026-02-13 20:19:19.400 UTC [29] CONTEXT: WAL redo at 2/FD322928 for MultiXact/ZERO_OFF_PAGE: 391
2026-02-13 20:19:19.587 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:19.587 UTC [29] CONTEXT: WAL redo at 2/FE86E9C0 for MultiXact/CREATE_ID: 802815 offset 6261968 nmembers 4: 980217 (sh) 980248 (keysh) 980291 (sh) 980356 (keysh)
2026-02-13 20:19:19.588 UTC [29] DEBUG: skipping initialization of offsets page 392 because it was already initialized on multixid creation
2026-02-13 20:19:19.588 UTC [29] CONTEXT: WAL redo at 2/FE86EA40 for MultiXact/ZERO_OFF_PAGE: 392
2026-02-13 20:19:20.154 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:20.154 UTC [29] CONTEXT: WAL redo at 3/122F168 for MultiXact/CREATE_ID: 804863 offset 6277624 nmembers 3: 982994 (sh) 983023 (keysh) 983049 (sh)
2026-02-13 20:19:20.154 UTC [29] DEBUG: skipping initialization of offsets page 393 because it was already initialized on multixid creation
2026-02-13 20:19:20.154 UTC [29] CONTEXT: WAL redo at 3/122F1E0 for MultiXact/ZERO_OFF_PAGE: 393
2026-02-13 20:19:20.545 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:20.545 UTC [29] CONTEXT: WAL redo at 3/32E4790 for MultiXact/CREATE_ID: 806911 offset 6292980 nmembers 9: 985323 (sh) 985326 (sh) 985358 (keysh) 985370 (keysh) 985582 (sh) 985597 (sh) 985606 (keysh) 985634 (keysh) 985638 (sh)
2026-02-13 20:19:20.545 UTC [29] DEBUG: skipping initialization of offsets page 394 because it was already initialized on multixid creation
2026-02-13 20:19:20.545 UTC [29] CONTEXT: WAL redo at 3/32E4838 for MultiXact/ZERO_OFF_PAGE: 394
2026-02-13 20:19:20.712 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:20.712 UTC [29] CONTEXT: WAL redo at 3/4C2FC80 for MultiXact/CREATE_ID: 808959 offset 6308795 nmembers 9: 987799 (sh) 987802 (keysh) 987823 (sh) 987878 (sh) 988060 (sh) 988085 (keysh) 988111 (sh) 988117 (keysh) 988147 (sh)
2026-02-13 20:19:20.712 UTC [29] DEBUG: skipping initialization of offsets page 395 because it was already initialized on multixid creation
2026-02-13 20:19:20.712 UTC [29] CONTEXT: WAL redo at 3/4C2FD28 for MultiXact/ZERO_OFF_PAGE: 395
2026-02-13 20:19:21.120 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:21.120 UTC [29] CONTEXT: WAL redo at 3/6CFDA00 for MultiXact/CREATE_ID: 811007 offset 6323524 nmembers 9: 989874 (sh) 989903 (keysh) 989924 (sh) 990687 (sh) 990703 (keysh) 990711 (keysh) 990723 (sh) 990747 (keysh) 990767 (sh)
2026-02-13 20:19:21.120 UTC [29] DEBUG: skipping initialization of offsets page 396 because it was already initialized on multixid creation
2026-02-13 20:19:21.120 UTC [29] CONTEXT: WAL redo at 3/6CFDBC0 for MultiXact/ZERO_OFF_PAGE: 396
2026-02-13 20:19:21.544 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:21.544 UTC [29] CONTEXT: WAL redo at 3/8A49D30 for MultiXact/CREATE_ID: 813055 offset 6339667 nmembers 9: 993007 (sh) 993059 (keysh) 993133 (sh) 993172 (sh) 993210 (keysh) 993241 (keysh) 993294 (sh) 993330 (sh) 993343 (keysh)
2026-02-13 20:19:21.545 UTC [29] DEBUG: skipping initialization of offsets page 397 because it was already initialized on multixid creation
2026-02-13 20:19:21.545 UTC [29] CONTEXT: WAL redo at 3/8A49DD8 for MultiXact/ZERO_OFF_PAGE: 397
2026-02-13 20:19:21.662 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:21.662 UTC [29] CONTEXT: WAL redo at 3/9FC9DE8 for MultiXact/CREATE_ID: 815103 offset 6354583 nmembers 9: 995423 (keysh) 995429 (sh) 995436 (keysh) 995440 (sh) 995798 (sh) 995810 (keysh) 995825 (sh) 995836 (keysh) 995848 (sh)
2026-02-13 20:19:21.663 UTC [29] DEBUG: skipping initialization of offsets page 398 because it was already initialized on multixid creation
2026-02-13 20:19:21.663 UTC [29] CONTEXT: WAL redo at 3/9FC9E90 for MultiXact/ZERO_OFF_PAGE: 398
2026-02-13 20:19:22.132 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:22.132 UTC [29] CONTEXT: WAL redo at 3/B76AB18 for MultiXact/CREATE_ID: 817151 offset 6371258 nmembers 10: 994358 (sh) 996000 (keysh) 997583 (sh) 997632 (keysh) 997650 (sh) 997715 (keysh) 997801 (sh) 998276 (sh) 998293 (keysh) 998330 (sh)
2026-02-13 20:19:22.132 UTC [29] DEBUG: skipping initialization of offsets page 399 because it was already initialized on multixid creation
2026-02-13 20:19:22.132 UTC [29] CONTEXT: WAL redo at 3/B76AC58 for MultiXact/ZERO_OFF_PAGE: 399
2026-02-13 20:19:22.274 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:22.274 UTC [29] CONTEXT: WAL redo at 3/CD6C240 for MultiXact/CREATE_ID: 819199 offset 6386738 nmembers 9: 1000695 (sh) 1000697 (sh) 1000722 (keysh) 1000735 (keysh) 1000745 (sh) 1000752 (sh) 1000779 (keysh) 1000800 (sh) 1000824 (keysh)
2026-02-13 20:19:22.274 UTC [29] DEBUG: skipping initialization of offsets page 400 because it was already initialized on multixid creation
2026-02-13 20:19:22.274 UTC [29] CONTEXT: WAL redo at 3/CD6C338 for MultiXact/ZERO_OFF_PAGE: 400
2026-02-13 20:19:22.547 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:22.547 UTC [29] CONTEXT: WAL redo at 3/E2DAF80 for MultiXact/CREATE_ID: 821247 offset 6404326 nmembers 8: 1002476 (keysh) 1002822 (keysh) 1002829 (sh) 1002994 (sh) 1003203 (sh) 1003240 (keysh) 1003269 (sh) 1003311 (keysh)
2026-02-13 20:19:22.547 UTC [29] DEBUG: skipping initialization of offsets page 401 because it was already initialized on multixid creation
2026-02-13 20:19:22.547 UTC [29] CONTEXT: WAL redo at 3/E2DCD00 for MultiXact/ZERO_OFF_PAGE: 401
2026-02-13 20:19:22.979 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:22.979 UTC [29] CONTEXT: WAL redo at 3/1079E140 for MultiXact/CREATE_ID: 823295 offset 6419851 nmembers 7: 1005452 (sh) 1005967 (sh) 1006004 (keysh) 1006047 (sh) 1006083 (sh) 1006089 (keysh) 1006105 (sh)
2026-02-13 20:19:22.979 UTC [29] DEBUG: skipping initialization of offsets page 402 because it was already initialized on multixid creation
2026-02-13 20:19:22.979 UTC [29] CONTEXT: WAL redo at 3/1079E448 for MultiXact/ZERO_OFF_PAGE: 402
2026-02-13 20:19:23.419 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:23.419 UTC [29] CONTEXT: WAL redo at 3/11BC3880 for MultiXact/CREATE_ID: 825343 offset 6435279 nmembers 4: 1008560 (sh) 1008587 (keysh) 1008598 (sh) 1008610 (keysh)
2026-02-13 20:19:23.419 UTC [29] DEBUG: skipping initialization of offsets page 403 because it was already initialized on multixid creation
2026-02-13 20:19:23.419 UTC [29] CONTEXT: WAL redo at 3/11BC3900 for MultiXact/ZERO_OFF_PAGE: 403
2026-02-13 20:19:23.445 UTC [27] DEBUG: checkpoint sync: number=1 file=pg_multixact/offsets/000C time=6.054 ms
2026-02-13 20:19:23.446 UTC [27] DEBUG: checkpoint sync: number=2 file=pg_multixact/members/006F time=0.919 ms
2026-02-13 20:19:23.446 UTC [27] DEBUG: checkpoint sync: number=3 file=base/5/16397 time=0.496 ms
2026-02-13 20:19:23.448 UTC [27] DEBUG: checkpoint sync: number=4 file=pg_multixact/members/0072 time=1.308 ms
2026-02-13 20:19:23.479 UTC [27] DEBUG: checkpoint sync: number=5 file=base/5/16407 time=31.027 ms
2026-02-13 20:19:23.482 UTC [27] DEBUG: checkpoint sync: number=6 file=pg_multixact/members/0074 time=3.096 ms
2026-02-13 20:19:23.483 UTC [27] DEBUG: checkpoint sync: number=7 file=pg_multixact/members/0075 time=1.394 ms
2026-02-13 20:19:23.484 UTC [27] DEBUG: checkpoint sync: number=8 file=pg_multixact/members/0076 time=1.028 ms
2026-02-13 20:19:23.487 UTC [27] DEBUG: checkpoint sync: number=9 file=pg_xact/0000 time=2.485 ms
2026-02-13 20:19:23.488 UTC [27] DEBUG: checkpoint sync: number=10 file=pg_multixact/members/0077 time=1.299 ms
2026-02-13 20:19:24.012 UTC [27] DEBUG: checkpoint sync: number=11 file=base/5/16398 time=523.126 ms
2026-02-13 20:19:24.052 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:24.052 UTC [29] CONTEXT: WAL redo at 3/12D7F4E0 for MultiXact/CREATE_ID: 827391 offset 6451578 nmembers 14: 1008676 (sh) 1010814 (keysh) 1010846 (sh) 1010882 (keysh) 1010902 (keysh) 1010923 (sh) 1010936 (sh) 1010977 (keysh) 1010982 (sh) 1011005 (keysh) 1011012 (sh) 1011028 (sh) 1011041 (keysh) 1011068 (sh)
2026-02-13 20:19:24.052 UTC [29] DEBUG: skipping initialization of offsets page 404 because it was already initialized on multixid creation
2026-02-13 20:19:24.052 UTC [29] CONTEXT: WAL redo at 3/12D91B70 for MultiXact/ZERO_OFF_PAGE: 404
2026-02-13 20:19:24.054 UTC [27] DEBUG: checkpoint sync: number=12 file=base/5/16386 time=42.768 ms
2026-02-13 20:19:24.057 UTC [27] DEBUG: checkpoint sync: number=13 file=pg_multixact/members/0078 time=2.265 ms
2026-02-13 20:19:24.061 UTC [27] DEBUG: checkpoint sync: number=14 file=base/5/16398_vm time=4.698 ms
2026-02-13 20:19:24.075 UTC [27] DEBUG: checkpoint sync: number=15 file=pg_multixact/members/0079 time=13.456 ms
2026-02-13 20:19:24.470 UTC [27] DEBUG: checkpoint sync: number=16 file=base/5/16414 time=395.301 ms
2026-02-13 20:19:24.639 UTC [27] DEBUG: checkpoint sync: number=17 file=pg_multixact/members/0071 time=169.146 ms
2026-02-13 20:19:24.647 UTC [27] DEBUG: checkpoint sync: number=18 file=base/5/16395 time=7.701 ms
2026-02-13 20:19:24.654 UTC [27] DEBUG: checkpoint sync: number=19 file=pg_multixact/members/007B time=6.675 ms
2026-02-13 20:19:24.656 UTC [27] DEBUG: checkpoint sync: number=20 file=pg_multixact/offsets/000B time=2.497 ms
2026-02-13 20:19:24.667 UTC [27] DEBUG: checkpoint sync: number=21 file=base/5/16398_fsm time=10.227 ms
2026-02-13 20:19:24.680 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:24.680 UTC [29] CONTEXT: WAL redo at 3/14127300 for MultiXact/CREATE_ID: 829439 offset 6467806 nmembers 4: 1013242 (sh) 1013402 (keysh) 1013491 (sh) 1013547 (keysh)
2026-02-13 20:19:24.680 UTC [29] DEBUG: skipping initialization of offsets page 405 because it was already initialized on multixid creation
2026-02-13 20:19:24.680 UTC [29] CONTEXT: WAL redo at 3/141273A8 for MultiXact/ZERO_OFF_PAGE: 405
2026-02-13 20:19:24.765 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:24.765 UTC [29] CONTEXT: WAL redo at 3/150AF798 for MultiXact/CREATE_ID: 831487 offset 6484745 nmembers 14: 1013638 (sh) 1013704 (keysh) 1013751 (sh) 1015490 (sh) 1015553 (sh) 1015605 (keysh) 1015672 (sh) 1015757 (sh) 1015805 (keysh) 1015811 (keysh) 1015873 (sh) 1015877 (sh) 1015933 (keysh) 1015954 (sh)
2026-02-13 20:19:24.765 UTC [29] DEBUG: skipping initialization of offsets page 406 because it was already initialized on multixid creation
2026-02-13 20:19:24.765 UTC [29] CONTEXT: WAL redo at 3/150AF868 for MultiXact/ZERO_OFF_PAGE: 406
2026-02-13 20:19:25.653 UTC [27] DEBUG: checkpoint sync: number=22 file=base/5/16398.1 time=986.303 ms
2026-02-13 20:19:25.655 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:25.655 UTC [29] CONTEXT: WAL redo at 3/15F50A80 for MultiXact/CREATE_ID: 833535 offset 6500009 nmembers 10: 1015968 (keysh) 1015982 (sh) 1017560 (sh) 1017584 (keysh) 1017615 (sh) 1017641 (keysh) 1017832 (sh) 1017853 (keysh) 1017888 (sh) 1018398 (sh)
2026-02-13 20:19:25.655 UTC [29] DEBUG: skipping initialization of offsets page 407 because it was already initialized on multixid creation
2026-02-13 20:19:25.655 UTC [29] CONTEXT: WAL redo at 3/15F52EB8 for MultiXact/ZERO_OFF_PAGE: 407
2026-02-13 20:19:25.863 UTC [27] DEBUG: checkpoint sync: number=23 file=pg_multixact/members/0073 time=209.588 ms
2026-02-13 20:19:25.870 UTC [27] DEBUG: checkpoint sync: number=24 file=pg_multixact/members/0070 time=7.293 ms
2026-02-13 20:19:25.875 UTC [27] DEBUG: checkpoint sync: number=25 file=pg_multixact/members/007A time=4.620 ms
2026-02-13 20:19:25.877 UTC [27] DEBUG: checkpoint sync: number=26 file=base/5/16386_fsm time=2.289 ms
2026-02-13 20:19:25.882 UTC [27] LOG: restartpoint complete: wrote 9819 buffers (30.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=5.352 s, sync=2.439 s, total=7.804 s; sync files=26, longest=0.987 s, average=0.094 s; distance=532960 kB, estimate=1269792 kB; lsn=2/F32C3C58, redo lsn=2/D409BD10
2026-02-13 20:19:25.882 UTC [27] LOG: recovery restart point at 2/D409BD10
2026-02-13 20:19:25.882 UTC [27] DETAIL: Last completed transaction was at log time 2026-02-13 20:14:09.707258+00.
2026-02-13 20:19:25.883 UTC [27] LOG: restartpoint starting: wal
2026-02-13 20:19:25.883 UTC [27] DEBUG: performing replication slot checkpoint
2026-02-13 20:19:25.896 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:25.896 UTC [29] CONTEXT: WAL redo at 3/17779F48 for MultiXact/CREATE_ID: 835583 offset 6516271 nmembers 4: 1018581 (sh) 1018991 (sh) 1020806 (sh) 1020813 (keysh)
2026-02-13 20:19:25.896 UTC [29] DEBUG: skipping initialization of offsets page 408 because it was already initialized on multixid creation
2026-02-13 20:19:25.896 UTC [29] CONTEXT: WAL redo at 3/17779FC8 for MultiXact/ZERO_OFF_PAGE: 408
2026-02-13 20:19:25.965 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:25.965 UTC [29] CONTEXT: WAL redo at 3/19657130 for MultiXact/CREATE_ID: 837631 offset 6531904 nmembers 2: 1023251 (sh) 1023315 (keysh)
2026-02-13 20:19:25.965 UTC [29] DEBUG: skipping initialization of offsets page 409 because it was already initialized on multixid creation
2026-02-13 20:19:25.965 UTC [29] CONTEXT: WAL redo at 3/196571A0 for MultiXact/ZERO_OFF_PAGE: 409
2026-02-13 20:19:26.065 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:26.065 UTC [29] CONTEXT: WAL redo at 3/1B071080 for MultiXact/CREATE_ID: 839679 offset 6546945 nmembers 14: 1025556 (sh) 1025593 (keysh) 1025624 (sh) 1025647 (sh) 1025655 (keysh) 1025691 (keysh) 1025694 (sh) 1025699 (sh) 1025733 (sh) 1025738 (keysh) 1025754 (keysh) 1025755 (sh) 1025767 (sh) 1025775 (keysh)
2026-02-13 20:19:26.065 UTC [29] DEBUG: skipping initialization of offsets page 410 because it was already initialized on multixid creation
2026-02-13 20:19:26.065 UTC [29] CONTEXT: WAL redo at 3/1B071150 for MultiXact/ZERO_OFF_PAGE: 410
2026-02-13 20:19:26.548 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:26.548 UTC [29] CONTEXT: WAL redo at 3/1C83A2C0 for MultiXact/CREATE_ID: 841727 offset 6563088 nmembers 13: 1023717 (keysh) 1023737 (sh) 1025818 (sh) 1025822 (keysh) 1025828 (keysh) 1027801 (sh) 1027956 (sh) 1028016 (sh) 1028034 (sh) 1028086 (keysh) 1028139 (sh) 1028182 (keysh) 1028202 (sh)
2026-02-13 20:19:26.548 UTC [29] DEBUG: skipping initialization of offsets page 411 because it was already initialized on multixid creation
2026-02-13 20:19:26.548 UTC [29] CONTEXT: WAL redo at 3/1C83A388 for MultiXact/ZERO_OFF_PAGE: 411
2026-02-13 20:19:26.768 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:26.768 UTC [29] CONTEXT: WAL redo at 3/1E1031B8 for MultiXact/CREATE_ID: 843775 offset 6579709 nmembers 9: 1028600 (sh) 1028662 (keysh) 1028684 (sh) 1028732 (keysh) 1029338 (sh) 1029361 (keysh) 1029379 (sh) 1029404 (keysh) 1030649 (sh)
2026-02-13 20:19:26.768 UTC [29] DEBUG: skipping initialization of offsets page 412 because it was already initialized on multixid creation
2026-02-13 20:19:26.768 UTC [29] CONTEXT: WAL redo at 3/1E104E10 for MultiXact/ZERO_OFF_PAGE: 412
2026-02-13 20:19:27.281 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:27.281 UTC [29] CONTEXT: WAL redo at 3/1FE96D28 for MultiXact/CREATE_ID: 845823 offset 6598059 nmembers 13: 1030375 (keysh) 1030431 (sh) 1030499 (keysh) 1030554 (sh) 1032823 (sh) 1033057 (sh) 1033059 (keysh) 1033061 (sh) 1033063 (keysh) 1033065 (sh) 1033124 (keysh) 1033133 (sh) 1033136 (keysh)
2026-02-13 20:19:27.281 UTC [29] DEBUG: skipping initialization of offsets page 413 because it was already initialized on multixid creation
2026-02-13 20:19:27.281 UTC [29] CONTEXT: WAL redo at 3/1FE96DF0 for MultiXact/ZERO_OFF_PAGE: 413
2026-02-13 20:19:29.116 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:29.116 UTC [29] CONTEXT: WAL redo at 3/218865B8 for MultiXact/CREATE_ID: 847871 offset 6615424 nmembers 6: 1033388 (sh) 1033424 (sh) 1035610 (sh) 1035613 (keysh) 1035626 (sh) 1035632 (keysh)
2026-02-13 20:19:29.116 UTC [29] DEBUG: skipping initialization of offsets page 414 because it was already initialized on multixid creation
2026-02-13 20:19:29.116 UTC [29] CONTEXT: WAL redo at 3/2188E380 for MultiXact/ZERO_OFF_PAGE: 414
2026-02-13 20:19:29.158 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:29.158 UTC [29] CONTEXT: WAL redo at 3/22B488F8 for MultiXact/CREATE_ID: 849919 offset 6632036 nmembers 10: 1035438 (sh) 1035461 (sh) 1035500 (keysh) 1036481 (keysh) 1036509 (sh) 1038000 (sh) 1038009 (keysh) 1038021 (sh) 1038032 (keysh) 1038043 (sh)
2026-02-13 20:19:29.158 UTC [29] DEBUG: skipping initialization of offsets page 415 because it was already initialized on multixid creation
2026-02-13 20:19:29.158 UTC [29] CONTEXT: WAL redo at 3/22B489E0 for MultiXact/ZERO_OFF_PAGE: 415
2026-02-13 20:19:29.790 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:29.790 UTC [29] CONTEXT: WAL redo at 3/25622088 for MultiXact/CREATE_ID: 851967 offset 6648420 nmembers 6: 1040192 (keysh) 1040234 (sh) 1040696 (sh) 1040738 (keysh) 1040747 (sh) 1040788 (sh)
2026-02-13 20:19:29.790 UTC [29] DEBUG: skipping initialization of offsets page 416 because it was already initialized on multixid creation
2026-02-13 20:19:29.790 UTC [29] CONTEXT: WAL redo at 3/25624178 for MultiXact/ZERO_OFF_PAGE: 416
2026-02-13 20:19:30.267 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:30.267 UTC [29] CONTEXT: WAL redo at 3/273BEF70 for MultiXact/CREATE_ID: 854015 offset 6663196 nmembers 7: 1040853 (sh) 1040907 (keysh) 1043024 (keysh) 1043055 (sh) 1043080 (keysh) 1043090 (sh) 1043376 (sh)
2026-02-13 20:19:30.267 UTC [29] DEBUG: skipping initialization of offsets page 417 because it was already initialized on multixid creation
2026-02-13 20:19:30.267 UTC [29] CONTEXT: WAL redo at 3/273BF008 for MultiXact/ZERO_OFF_PAGE: 417
2026-02-13 20:19:30.485 UTC [29] DEBUG: next offsets page is not initialized, initializing it now
2026-02-13 20:19:30.485 UTC [29] CONTEXT: WAL redo at 3/28C148D8 for MultiXact/CREATE_ID: 856063 offset 6680130 nmembers 9: 1043713 (sh) 1045522 (sh) 1045537 (keysh) 1045555 (sh) 1045666 (keysh) 1045715 (sh) 1045750 (keysh) 1045794 (sh) 1045876 (sh)
2026-02-13 20:19:30.485 UTC [29] DEBUG: skipping initialization of offsets page 418 because it was already initialized on multixid creation
2026-02-13 20:19:30.485 UTC [29] CONTEXT: WAL redo at 3/28C149B8 for MultiXact/ZERO_OFF_PAGE: 418
2026-02-13 20:19:30.602 UTC [29] DEBUG: replaying multixact truncation: offsets [1, 490986), offsets segments [0, 7), members [1, 3864017), members segments [0, 49)
2026-02-13 20:19:30.602 UTC [29] CONTEXT: WAL redo at 3/29D6D548 for MultiXact/TRUNCATE_ID: offsets [1, 490986), members [1, 3864017)
2026-02-13 20:19:30.602 UTC [29] DEBUG: MultiXactId wrap limit is 2147974633, limited by database with OID 5
2026-02-13 20:19:30.602 UTC [29] CONTEXT: WAL redo at 3/29D6D548 for MultiXact/TRUNCATE_ID: offsets [1, 490986), members [1, 3864017)
2026-02-13 20:19:30.783 UTC [29] FATAL: could not access status of transaction 858112
2026-02-13 20:19:30.783 UTC [29] DETAIL: Could not read from file "pg_multixact/offsets/000D" at offset 24576: read too few bytes.
2026-02-13 20:19:30.783 UTC [29] CONTEXT: WAL redo at 3/2A3AB408 for MultiXact/CREATE_ID: 858111 offset 6695072 nmembers 5: 1048228 (sh) 1048271 (keysh) 1048316 (sh) 1048344 (keysh) 1048370 (sh)
2026-02-13 20:19:31.050 UTC [1] LOG: startup process (PID 29) exited with exit code 1
2026-02-13 20:19:31.050 UTC [1] LOG: terminating any other active server processes
2026-02-13 20:19:31.054 UTC [1] LOG: shutting down due to startup process failure
2026-02-13 20:19:31.068 UTC [1] LOG: database system is shut down
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment