assert.log

text/x-log

Download

# Postmaster PID for node "main" is 1822197
[03:19:48.808](2.752s) ok 3198 - ensure checksums are set to on
[03:19:48.843](0.035s) # LSN before: B1/B72958C8
[03:19:48.846](0.003s) # disable_data_checksums fast true
[03:19:48.902](0.056s) ok 3199 - ensure data checksums are transitioned to off
[03:19:48.920](0.018s) # LSN after: B1/B73696F0
[03:19:50.996](2.076s) ok 3200 - ensure data pages can be read back on primary
[03:19:52.997](2.001s) # iteration 718 of 1000
# Running: pg_isready --timeout 180 --host /tmp/QlNkDRW0vA --port 18699
/tmp/QlNkDRW0vA:18699 - accepting connections
[03:19:55.542](2.545s) ok 3201 - ensure checksums are set to off
[03:19:55.554](0.012s) # LSN before: B1/BC2E1B00
[03:19:55.555](0.001s) # enable_data_checksums delay 0 limit 100 fast true
[03:19:55.603](0.048s) ok 3202 - ensure data checksums are transitioned to inprogress-on
[03:20:20.561](24.958s) ok 3203 - ensure data checksums are transitioned to on
[03:20:20.589](0.027s) # LSN after: B2/2FDA8620
[03:20:22.041](1.452s) ok 3204 - ensure data pages can be read back on primary
[03:20:23.042](1.001s) # iteration 719 of 1000
# Running: pg_isready --timeout 180 --host /tmp/QlNkDRW0vA --port 18699
/tmp/QlNkDRW0vA:18699 - accepting connections
[03:20:26.418](3.376s) ok 3205 - ensure checksums are set to on
[03:20:28.435](2.018s) # LSN before: B2/3569F408
[03:20:28.438](0.003s) # disable_data_checksums fast true
ack Broken pipe: write( 13, 'SELECT setting FROM pg_catalog.pg_settings WHERE name = 'data_checksums';' ) at /usr/share/perl5/IPC/Run/IO.pm line 550.
# No postmaster PID for node "main"
[03:21:02.179](33.741s) # Tests were run but no plan was declared and done_testing() was not seen.
[03:21:02.180](0.000s) # Looks like your test exited with 32 just after 3205.



000 s; distance=62367 kB, estimate=71775 kB; lsn=B2/2FCF2CA8, redo lsn=B2/2FCEE3A0, checksums=2 (2)
2025-09-01 03:20:20.524 BST checkpointer[1822201] LOG:  checkpoint starting: fast force wait
2025-09-01 03:20:20.533 BST checkpointer[1822201] LOG:  CreateCheckPoint ControlFile->data_checksum_version = 1 ControlFile->checkPointCopy.data_checksum_version = 1
2025-09-01 03:20:20.533 BST checkpointer[1822201] LOG:  update_controlfile ControlFile->data_checksum_version = 1  ControlFile->checkPointCopy.data_checksum_version = 1
2025-09-01 03:20:20.533 BST checkpointer[1822201] LOG:  checkpoint complete: wrote 31 buffers (24.2%), wrote 1 SLRU buffers; 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.009 s; sync files=0, longest=0.000 s, average=0.000 s; distance=53 kB, estimate=64603 kB; lsn=B2/2FD21908, redo lsn=B2/2FCFBA00, checksums=1 (1)
2025-09-01 03:20:24.703 BST checkpointer[1822201] LOG:  checkpoints are occurring too frequently (4 seconds apart)
2025-09-01 03:20:24.703 BST checkpointer[1822201] HINT:  Consider increasing the configuration parameter "max_wal_size".
2025-09-01 03:20:24.703 BST checkpointer[1822201] LOG:  checkpoint starting: wal
2025-09-01 03:20:26.202 BST checkpointer[1822201] LOG:  CreateCheckPoint ControlFile->data_checksum_version = 1 ControlFile->checkPointCopy.data_checksum_version = 1
2025-09-01 03:20:26.202 BST checkpointer[1822201] LOG:  update_controlfile ControlFile->data_checksum_version = 1  ControlFile->checkPointCopy.data_checksum_version = 1
2025-09-01 03:20:26.202 BST checkpointer[1822201] LOG:  checkpoint complete: wrote 4 buffers (3.1%), wrote 3 SLRU buffers; 0 WAL file(s) added, 0 removed, 4 recycled; write=1.498 s, sync=0.001 s, total=1.499 s; sync files=0, longest=0.000 s, average=0.000 s; distance=52249 kB, estimate=63368 kB; lsn=B2/342F3E38, redo lsn=B2/33002080, checksums=1 (1)
2025-09-01 03:20:28.468 BST client backend[1822725] 006_pgbench_single.pl LOG:  disable_data_checksums fast 1
2025-09-01 03:20:28.468 BST client backend[1822725] 006_pgbench_single.pl STATEMENT:  SELECT pg_disable_data_checksums(true);
2025-09-01 03:20:28.479 BST checkpointer[1822201] LOG:  checkpoint starting: fast force wait
2025-09-01 03:20:28.480 BST checkpointer[1822201] LOG:  CreateCheckPoint ControlFile->data_checksum_version = 3 ControlFile->checkPointCopy.data_checksum_version = 3
2025-09-01 03:20:28.480 BST checkpointer[1822201] LOG:  update_controlfile ControlFile->data_checksum_version = 3  ControlFile->checkPointCopy.data_checksum_version = 3
2025-09-01 03:20:28.481 BST checkpointer[1822201] LOG:  checkpoint complete: wrote 40 buffers (31.2%), wrote 4 SLRU buffers; 0 WAL file(s) added, 0 removed, 2 recycled; write=0.001 s, sync=0.001 s, total=0.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=39883 kB, estimate=61019 kB; lsn=B2/356F6040, redo lsn=B2/356F4E78, checksums=3 (3)
TRAP: failed Assert("(LocalDataChecksumVersion != PG_DATA_CHECKSUM_VERSION) && (LocalDataChecksumVersion == PG_DATA_CHECKSUM_INPROGRESS_ON_VERSION || LocalDataChecksumVersion == PG_DATA_CHECKSUM_INPROGRESS_OFF_VERSION)"), File: "xlog.c", Line: 5002, PID: 1822728
postgres: main: debian postgres [local] authentication(ExceptionalCondition+0x84)[0x55564d0428b4]
postgres: main: debian postgres [local] authentication(AbsorbChecksumsOffBarrier+0x5c)[0x55564c96f764]
postgres: main: debian postgres [local] authentication(ProcessProcSignalBarrier+0x1f0)[0x55564ce0d364]
postgres: main: debian postgres [local] authentication(ProcessInterrupts+0x90c)[0x55564ce4f0c0]
postgres: main: debian postgres [local] authentication(ClientAuthentication+0x50)[0x55564cbd1930]
postgres: main: debian postgres [local] authentication(+0x8eb940)[0x55564d05b940]
postgres: main: debian postgres [local] authentication(InitPostgres+0x3f8)[0x55564d05c8b0]
postgres: main: debian postgres [local] authentication(PostgresMain+0x2b4)[0x55564ce50588]
postgres: main: debian postgres [local] authentication(+0x6d6a74)[0x55564ce46a74]
postgres: main: debian postgres [local] authentication(postmaster_child_launch+0x1d4)[0x55564cd33684]
postgres: main: debian postgres [local] authentication(+0x5ca9c4)[0x55564cd3a9c4]
postgres: main: debian postgres [local] authentication(+0x5c78a4)[0x55564cd378a4]
postgres: main: debian postgres [local] authentication(PostmasterMain+0x153c)[0x55564cd37118]
postgres: main: debian postgres [local] authentication(main+0x3d8)[0x55564cbe452c]
/lib/aarch64-linux-gnu/libc.so.6(+0x27744)[0x7fffa1a47744]
/lib/aarch64-linux-gnu/libc.so.6(__libc_start_main+0x98)[0x7fffa1a47818]
postgres: main: debian postgres [local] authentication(_start+0x30)[0x55564c8562f0]
2025-09-01 03:20:28.513 BST postmaster[1822197] LOG:  client backend (PID 1822728) was terminated by signal 6: Aborted
2025-09-01 03:20:28.513 BST postmaster[1822197] LOG:  terminating any other active server processes
2025-09-01 03:20:28.515 BST postmaster[1822197] LOG:  shutting down because "restart_after_crash" is off
2025-09-01 03:20:28.516 BST postmaster[1822197] LOG:  database system is shut down