Skip to content

Instantly share code, notes, and snippets.

@mdcallag
Created June 3, 2024 20:39
Show Gist options
  • Save mdcallag/bb166167bf144256d55b87ebfe9e99cd to your computer and use it in GitHub Desktop.
Save mdcallag/bb166167bf144256d55b87ebfe9e99cd to your computer and use it in GitHub Desktop.
logfile with full_page_writes=ON
2024-06-02 01:56:18.972 UTC [633083] LOG: starting PostgreSQL 16.3 on x86_64-pc-linux-gnu, compiled by gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, 64-bit
2024-06-02 01:56:18.972 UTC [633083] LOG: listening on IPv4 address "127.0.0.1", port 5432
2024-06-02 01:56:18.991 UTC [633083] LOG: listening on Unix socket "/tmp/.s.PGSQL.5432"
2024-06-02 01:56:19.005 UTC [633086] LOG: database system was shut down at 2024-06-02 01:56:18 UTC
2024-06-02 01:56:19.014 UTC [633083] LOG: database system is ready to accept connections
2024-06-02 01:56:34.480 UTC [633084] LOG: checkpoint starting: immediate force wait
2024-06-02 01:56:34.774 UTC [633084] LOG: checkpoint complete: wrote 1888 buffers (0.1%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.074 s, sync=0.197 s, total=0.294 s; sync files=621, longest=0.009 s, average=0.001 s; distance=13030 kB, estimate=13030 kB; lsn=0/23E3A58, redo lsn=0/23E3A20
2024-06-02 01:56:35.699 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_type"
I/O timings: read: 0.022 ms, write: 0.026 ms
avg read rate: 3.125 MB/s, avg write rate: 14.062 MB/s
buffer usage: 1076 hits, 2 misses, 9 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.699 UTC [633133] LOG: automatic aggressive vacuum of table "template1.pg_catalog.pg_attribute": index scans: 0
pages: 0 removed, 57 remain, 1 scanned (1.75% of total)
tuples: 0 removed, 3108 remain, 0 are dead but not yet removable
removable cutoff: 738, which was 0 XIDs old when operation ended
new relfrozenxid: 738, which is 16 XIDs ahead of previous value
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 100.160 MB/s
buffer usage: 12 hits, 0 misses, 1 dirtied
WAL usage: 1 records, 1 full page images, 3097 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.708 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_attribute"
I/O timings: read: 0.000 ms, write: 0.015 ms
avg read rate: 0.000 MB/s, avg write rate: 4.688 MB/s
buffer usage: 510 hits, 0 misses, 6 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.709 UTC [633133] LOG: automatic aggressive vacuum of table "template1.pg_catalog.pg_class": index scans: 0
pages: 0 removed, 14 remain, 14 scanned (100.00% of total)
tuples: 0 removed, 413 remain, 0 are dead but not yet removable
removable cutoff: 739, which was 0 XIDs old when operation ended
new relfrozenxid: 739, which is 17 XIDs ahead of previous value
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 54 hits, 0 misses, 0 dirtied
WAL usage: 1 records, 0 full page images, 241 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.711 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_class"
I/O timings: read: 0.000 ms, write: 0.014 ms
avg read rate: 0.000 MB/s, avg write rate: 15.625 MB/s
buffer usage: 522 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.712 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_constraint"
I/O timings: read: 0.000 ms, write: 0.022 ms
avg read rate: 0.000 MB/s, avg write rate: 19.531 MB/s
buffer usage: 533 hits, 0 misses, 5 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.712 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_rewrite"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 7.812 MB/s
buffer usage: 126 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.712 UTC [633133] LOG: automatic aggressive vacuum of table "template1.pg_catalog.pg_description": index scans: 0
pages: 0 removed, 45 remain, 1 scanned (2.22% of total)
tuples: 0 removed, 5172 remain, 0 are dead but not yet removable
removable cutoff: 742, which was 0 XIDs old when operation ended
new relfrozenxid: 742, which is 20 XIDs ahead of previous value
frozen: 1 pages from table (2.22% of total) had 1 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 464.109 MB/s
buffer usage: 28 hits, 0 misses, 6 dirtied
WAL usage: 7 records, 6 full page images, 41208 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.717 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_description"
I/O timings: read: 0.000 ms, write: 0.014 ms
avg read rate: 0.000 MB/s, avg write rate: 6.250 MB/s
buffer usage: 105 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.717 UTC [633133] LOG: automatic aggressive vacuum of table "template1.pg_catalog.pg_depend": index scans: 1
pages: 0 removed, 13 remain, 13 scanned (100.00% of total)
tuples: 8 removed, 1723 remain, 0 are dead but not yet removable
removable cutoff: 743, which was 0 XIDs old when operation ended
new relfrozenxid: 743, which is 21 XIDs ahead of previous value
frozen: 3 pages from table (23.08% of total) had 19 tuples frozen
index scan needed: 1 pages from table (7.69% of total) had 8 dead item identifiers removed
index "pg_depend_depender_index": pages: 9 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pg_depend_reference_index": pages: 8 in total, 0 newly deleted, 0 currently deleted, 0 reusable
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 165.053 MB/s
buffer usage: 96 hits, 0 misses, 9 dirtied
WAL usage: 19 records, 9 full page images, 69327 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.719 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_depend"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 7.812 MB/s
buffer usage: 118 hits, 0 misses, 2 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.719 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_ts_config_map"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 7.812 MB/s
buffer usage: 58 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.720 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_init_privs"
I/O timings: read: 0.000 ms, write: 0.013 ms
avg read rate: 0.000 MB/s, avg write rate: 39.062 MB/s
buffer usage: 90 hits, 0 misses, 5 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.721 UTC [633133] LOG: automatic analyze of table "template1.pg_catalog.pg_collation"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 3.906 MB/s
buffer usage: 140 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:35.723 UTC [633133] LOG: automatic analyze of table "template1.information_schema.sql_features"
I/O timings: read: 0.000 ms, write: 0.018 ms
avg read rate: 0.000 MB/s, avg write rate: 11.719 MB/s
buffer usage: 184 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:42.492 UTC [633153] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 3471 remain, 3471 scanned (100.00% of total)
tuples: 0 removed, 354325 remain, 0 are dead but not yet removable
removable cutoff: 4293, which was 44 XIDs old when operation ended
new relfrozenxid: 751, which is 2 XIDs ahead of previous value
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.015 ms
avg read rate: 0.000 MB/s, avg write rate: 0.814 MB/s
buffer usage: 6978 hits, 0 misses, 3 dirtied
WAL usage: 3472 records, 3 full page images, 229538 bytes
system usage: CPU: user: 0.01 s, system: 0.00 s, elapsed: 0.02 s
2024-06-02 01:56:42.645 UTC [633153] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.019 ms
avg read rate: 0.000 MB/s, avg write rate: 0.051 MB/s
buffer usage: 3873 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.14 s, system: 0.00 s, elapsed: 0.15 s
2024-06-02 01:56:47.467 UTC [633155] LOG: automatic aggressive vacuum of table "template1.pg_catalog.pg_statistic": index scans: 1
pages: 0 removed, 25 remain, 25 scanned (100.00% of total)
tuples: 88 removed, 409 remain, 0 are dead but not yet removable
removable cutoff: 11803, which was 0 XIDs old when operation ended
new relfrozenxid: 11803, which is 11081 XIDs ahead of previous value
frozen: 15 pages from table (60.00% of total) had 164 tuples frozen
index scan needed: 13 pages from table (52.00% of total) had 143 dead item identifiers removed
index "pg_statistic_relid_att_inh_index": pages: 5 in total, 0 newly deleted, 0 currently deleted, 0 reusable
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 39.657 MB/s
buffer usage: 93 hits, 0 misses, 2 dirtied
WAL usage: 66 records, 3 full page images, 22091 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 01:56:52.587 UTC [633158] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 18161 remain, 14692 scanned (80.90% of total)
tuples: 0 removed, 1854015 remain, 0 are dead but not yet removable
removable cutoff: 19292, which was 184 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29425 hits, 0 misses, 0 dirtied
WAL usage: 14692 records, 0 full page images, 866957 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:56:52.792 UTC [633158] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.019 ms
avg read rate: 0.000 MB/s, avg write rate: 0.038 MB/s
buffer usage: 18708 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.18 s, system: 0.00 s, elapsed: 0.20 s
2024-06-02 01:57:05.218 UTC [633168] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 36676 remain, 18516 scanned (50.49% of total)
tuples: 0 removed, 3744083 remain, 0 are dead but not yet removable
removable cutoff: 38193, which was 229 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.023 ms
avg read rate: 0.000 MB/s, avg write rate: 0.051 MB/s
buffer usage: 37080 hits, 0 misses, 1 dirtied
WAL usage: 18516 records, 1 full page images, 1100770 bytes
system usage: CPU: user: 0.13 s, system: 0.00 s, elapsed: 0.15 s
2024-06-02 01:57:05.465 UTC [633168] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30361 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.22 s, system: 0.00 s, elapsed: 0.24 s
2024-06-02 01:57:15.287 UTC [633172] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 51495 remain, 14820 scanned (28.78% of total)
tuples: 0 removed, 5256893 remain, 0 are dead but not yet removable
removable cutoff: 53322, which was 184 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29691 hits, 0 misses, 0 dirtied
WAL usage: 14820 records, 0 full page images, 874509 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:57:15.546 UTC [633172] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30353 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.01 s, elapsed: 0.25 s
2024-06-02 01:57:25.388 UTC [633176] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 66333 remain, 14839 scanned (22.37% of total)
tuples: 0 removed, 6771625 remain, 0 are dead but not yet removable
removable cutoff: 68470, which was 185 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.025 ms
avg read rate: 0.000 MB/s, avg write rate: 0.063 MB/s
buffer usage: 29735 hits, 0 misses, 1 dirtied
WAL usage: 14839 records, 1 full page images, 883827 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:57:25.657 UTC [633176] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30352 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.22 s, system: 0.03 s, elapsed: 0.26 s
2024-06-02 01:57:35.391 UTC [633195] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 81022 remain, 14690 scanned (18.13% of total)
tuples: 0 removed, 8271235 remain, 0 are dead but not yet removable
removable cutoff: 83467, which was 186 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29440 hits, 0 misses, 0 dirtied
WAL usage: 14690 records, 0 full page images, 866839 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:57:35.668 UTC [633195] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30352 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.04 s, elapsed: 0.27 s
2024-06-02 01:57:45.393 UTC [633199] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 95705 remain, 14684 scanned (15.34% of total)
tuples: 0 removed, 9769925 remain, 0 are dead but not yet removable
removable cutoff: 98457, which was 186 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29432 hits, 0 misses, 0 dirtied
WAL usage: 14684 records, 0 full page images, 866485 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:57:45.674 UTC [633199] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30352 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.04 s, elapsed: 0.28 s
2024-06-02 01:57:55.396 UTC [633203] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 110391 remain, 14687 scanned (13.30% of total)
tuples: 0 removed, 11269639 remain, 0 are dead but not yet removable
removable cutoff: 113453, which was 185 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.028 ms
avg read rate: 0.000 MB/s, avg write rate: 0.063 MB/s
buffer usage: 29444 hits, 0 misses, 1 dirtied
WAL usage: 14687 records, 1 full page images, 874859 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:57:55.680 UTC [633203] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.023 ms
avg read rate: 0.000 MB/s, avg write rate: 0.027 MB/s
buffer usage: 30363 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.20 s, system: 0.06 s, elapsed: 0.28 s
2024-06-02 01:58:05.397 UTC [633213] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 125102 remain, 14712 scanned (11.76% of total)
tuples: 0 removed, 12770709 remain, 0 are dead but not yet removable
removable cutoff: 128472, which was 186 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29497 hits, 0 misses, 0 dirtied
WAL usage: 14712 records, 0 full page images, 868137 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:58:05.684 UTC [633213] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30357 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.04 s, elapsed: 0.28 s
2024-06-02 01:58:15.402 UTC [633217] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 139812 remain, 14711 scanned (10.52% of total)
tuples: 0 removed, 14273451 remain, 0 are dead but not yet removable
removable cutoff: 143490, which was 186 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.026 ms
avg read rate: 0.000 MB/s, avg write rate: 0.062 MB/s
buffer usage: 29501 hits, 0 misses, 1 dirtied
WAL usage: 14711 records, 1 full page images, 876275 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 01:58:15.700 UTC [633217] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30354 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.22 s, system: 0.05 s, elapsed: 0.29 s
2024-06-02 01:58:25.402 UTC [633221] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 154505 remain, 14694 scanned (9.51% of total)
tuples: 0 removed, 15772906 remain, 0 are dead but not yet removable
removable cutoff: 158490, which was 185 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29470 hits, 0 misses, 0 dirtied
WAL usage: 14694 records, 0 full page images, 867075 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:58:25.699 UTC [633221] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30354 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.07 s, elapsed: 0.29 s
2024-06-02 01:58:35.405 UTC [633240] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 169190 remain, 14686 scanned (8.68% of total)
tuples: 0 removed, 17271802 remain, 0 are dead but not yet removable
removable cutoff: 173481, which was 187 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.025 ms
avg read rate: 0.000 MB/s, avg write rate: 0.062 MB/s
buffer usage: 29460 hits, 0 misses, 1 dirtied
WAL usage: 14685 records, 1 full page images, 874741 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 01:58:45.406 UTC [633247] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 183860 remain, 14672 scanned (7.98% of total)
tuples: 0 removed, 18769395 remain, 0 are dead but not yet removable
removable cutoff: 188458, which was 186 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29436 hits, 0 misses, 0 dirtied
WAL usage: 14672 records, 0 full page images, 865777 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 01:58:45.707 UTC [633247] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30355 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.07 s, elapsed: 0.30 s
2024-06-02 01:58:55.408 UTC [633256] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 198542 remain, 14683 scanned (7.40% of total)
tuples: 0 removed, 20268990 remain, 0 are dead but not yet removable
removable cutoff: 203447, which was 187 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.025 ms
avg read rate: 0.000 MB/s, avg write rate: 0.063 MB/s
buffer usage: 29463 hits, 0 misses, 1 dirtied
WAL usage: 14683 records, 1 full page images, 874623 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 01:59:05.410 UTC [633281] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 213241 remain, 14700 scanned (6.89% of total)
tuples: 0 removed, 21769559 remain, 0 are dead but not yet removable
removable cutoff: 218452, which was 189 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29501 hits, 0 misses, 0 dirtied
WAL usage: 14699 records, 0 full page images, 867370 bytes
system usage: CPU: user: 0.09 s, system: 0.02 s, elapsed: 0.12 s
2024-06-02 01:59:05.723 UTC [633281] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30356 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.22 s, system: 0.07 s, elapsed: 0.31 s
2024-06-02 01:59:15.412 UTC [633285] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 227930 remain, 14691 scanned (6.45% of total)
tuples: 0 removed, 23268984 remain, 0 are dead but not yet removable
removable cutoff: 233450, which was 186 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29487 hits, 0 misses, 0 dirtied
WAL usage: 14691 records, 0 full page images, 866898 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 01:59:25.414 UTC [633298] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 242597 remain, 14668 scanned (6.05% of total)
tuples: 0 removed, 24766297 remain, 0 are dead but not yet removable
removable cutoff: 248423, which was 187 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.026 ms
avg read rate: 0.000 MB/s, avg write rate: 0.063 MB/s
buffer usage: 29446 hits, 0 misses, 1 dirtied
WAL usage: 14668 records, 1 full page images, 873738 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 01:59:25.727 UTC [633298] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30357 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.06 s, elapsed: 0.31 s
2024-06-02 01:59:35.416 UTC [633318] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 257293 remain, 14697 scanned (5.71% of total)
tuples: 0 removed, 26265254 remain, 0 are dead but not yet removable
removable cutoff: 263426, which was 188 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29508 hits, 0 misses, 0 dirtied
WAL usage: 14697 records, 0 full page images, 867252 bytes
system usage: CPU: user: 0.08 s, system: 0.02 s, elapsed: 0.12 s
2024-06-02 01:59:45.419 UTC [633329] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 271975 remain, 14683 scanned (5.40% of total)
tuples: 0 removed, 27764030 remain, 0 are dead but not yet removable
removable cutoff: 278414, which was 187 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.029 ms
avg read rate: 0.000 MB/s, avg write rate: 0.062 MB/s
buffer usage: 29485 hits, 0 misses, 1 dirtied
WAL usage: 14683 records, 1 full page images, 874623 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 01:59:45.743 UTC [633329] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.021 ms
avg read rate: 0.000 MB/s, avg write rate: 0.024 MB/s
buffer usage: 30364 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.26 s, system: 0.04 s, elapsed: 0.32 s
2024-06-02 01:59:55.519 UTC [633343] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 286793 remain, 14819 scanned (5.17% of total)
tuples: 0 removed, 29277866 remain, 0 are dead but not yet removable
removable cutoff: 293542, which was 188 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 29761 hits, 0 misses, 0 dirtied
WAL usage: 14819 records, 0 full page images, 874450 bytes
system usage: CPU: user: 0.10 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 02:00:05.620 UTC [633353] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 301632 remain, 14840 scanned (4.92% of total)
tuples: 0 removed, 30792739 remain, 0 are dead but not yet removable
removable cutoff: 308689, which was 191 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.022 ms
avg read rate: 0.000 MB/s, avg write rate: 0.061 MB/s
buffer usage: 29809 hits, 0 misses, 1 dirtied
WAL usage: 14839 records, 1 full page images, 883827 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 02:00:05.945 UTC [633353] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30361 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.08 s, elapsed: 0.32 s
2024-06-02 02:00:25.942 UTC [633361] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 331299 remain, 29669 scanned (8.96% of total)
tuples: 0 removed, 33821022 remain, 0 are dead but not yet removable
removable cutoff: 338977, which was 374 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.022 ms
avg read rate: 0.000 MB/s, avg write rate: 0.031 MB/s
buffer usage: 59476 hits, 0 misses, 1 dirtied
WAL usage: 29668 records, 1 full page images, 1758738 bytes
system usage: CPU: user: 0.19 s, system: 0.02 s, elapsed: 0.24 s
2024-06-02 02:00:36.134 UTC [633380] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30388 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.24 s, system: 0.08 s, elapsed: 0.34 s
2024-06-02 02:00:46.040 UTC [633384] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 360818 remain, 29521 scanned (8.18% of total)
tuples: 0 removed, 36834306 remain, 0 are dead but not yet removable
removable cutoff: 369113, which was 366 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.027 ms
avg read rate: 0.000 MB/s, avg write rate: 0.032 MB/s
buffer usage: 59189 hits, 0 misses, 1 dirtied
WAL usage: 29521 records, 1 full page images, 1750065 bytes
system usage: CPU: user: 0.19 s, system: 0.02 s, elapsed: 0.24 s
2024-06-02 02:01:06.144 UTC [633398] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 390343 remain, 29526 scanned (7.56% of total)
tuples: 0 removed, 39848421 remain, 0 are dead but not yet removable
removable cutoff: 399253, which was 372 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 59206 hits, 0 misses, 0 dirtied
WAL usage: 29525 records, 0 full page images, 1742104 bytes
system usage: CPU: user: 0.19 s, system: 0.02 s, elapsed: 0.24 s
2024-06-02 02:01:06.472 UTC [633398] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30361 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.07 s, elapsed: 0.32 s
2024-06-02 02:01:26.249 UTC [633406] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 419877 remain, 29536 scanned (7.03% of total)
tuples: 0 removed, 42863864 remain, 0 are dead but not yet removable
removable cutoff: 429406, which was 372 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.026 ms
avg read rate: 0.000 MB/s, avg write rate: 0.031 MB/s
buffer usage: 59236 hits, 0 misses, 1 dirtied
WAL usage: 29536 records, 1 full page images, 1750950 bytes
system usage: CPU: user: 0.19 s, system: 0.02 s, elapsed: 0.24 s
2024-06-02 02:01:36.341 UTC [633425] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30390 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.08 s, elapsed: 0.34 s
2024-06-02 02:01:46.248 UTC [633429] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 449253 remain, 29377 scanned (6.54% of total)
tuples: 0 removed, 45860261 remain, 0 are dead but not yet removable
removable cutoff: 459394, which was 371 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.032 ms
avg read rate: 0.000 MB/s, avg write rate: 0.032 MB/s
buffer usage: 58927 hits, 0 misses, 1 dirtied
WAL usage: 29377 records, 1 full page images, 1741569 bytes
system usage: CPU: user: 0.20 s, system: 0.01 s, elapsed: 0.24 s
2024-06-02 02:02:06.452 UTC [633443] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 478920 remain, 29668 scanned (6.19% of total)
tuples: 0 removed, 48888786 remain, 0 are dead but not yet removable
removable cutoff: 489679, which was 377 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.022 ms
avg read rate: 0.000 MB/s, avg write rate: 0.031 MB/s
buffer usage: 59518 hits, 0 misses, 1 dirtied
WAL usage: 29667 records, 1 full page images, 1758679 bytes
system usage: CPU: user: 0.19 s, system: 0.02 s, elapsed: 0.25 s
2024-06-02 02:02:16.300 UTC [633958] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:02:26.303 UTC [633962] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:02:36.307 UTC [633989] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:02:46.309 UTC [634011] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:02:56.312 UTC [634025] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:03:06.314 UTC [634029] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:03:16.317 UTC [634040] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:03:26.319 UTC [634044] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:03:36.320 UTC [634057] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:03:46.323 UTC [634067] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:03:56.326 UTC [634071] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:04:06.329 UTC [634075] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:04:16.332 UTC [634085] LOG: skipping analyze of "pi1" --- lock not available
2024-06-02 02:04:26.717 UTC [634343] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30468 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.15 s, elapsed: 0.38 s
2024-06-02 02:05:43.878 UTC [634575] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 504373 remain, 40046 scanned (7.94% of total)
tuples: 968390 removed, 50000923 remain, 697 are dead but not yet removable
removable cutoff: 560332, which was 13931 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 14591 pages from table (2.89% of total) had 1488891 dead item identifiers removed
index "pi1_pkey": pages: 141300 in total, 4081 newly deleted, 4081 currently deleted, 4081 reusable
index "pi1_marketsegment": pages: 192248 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 192220 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 263820 in total, 7005 newly deleted, 7005 currently deleted, 7005 reusable
I/O timings: read: 371.348 ms, write: 7.251 ms
avg read rate: 262.060 MB/s, avg write rate: 8.149 MB/s
buffer usage: 741378 hits, 246138 misses, 7654 dirtied
WAL usage: 486976 records, 68 full page images, 42902896 bytes
system usage: CPU: user: 4.73 s, system: 0.94 s, elapsed: 7.33 s
2024-06-02 02:06:17.235 UTC [634631] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.27 s, system: 0.10 s, elapsed: 0.39 s
2024-06-02 02:09:06.380 UTC [634787] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 514566 remain, 49570 scanned (9.63% of total)
tuples: 1644746 removed, 48376748 remain, 1060 are dead but not yet removable
removable cutoff: 661509, which was 16661 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24794 pages from table (4.82% of total) had 2529749 dead item identifiers removed
index "pi1_pkey": pages: 144146 in total, 6935 newly deleted, 6935 currently deleted, 6935 reusable
index "pi1_marketsegment": pages: 192868 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 193122 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 279589 in total, 11748 newly deleted, 11748 currently deleted, 11748 reusable
I/O timings: read: 416.321 ms, write: 5.237 ms
avg read rate: 207.145 MB/s, avg write rate: 10.913 MB/s
buffer usage: 868518 hits, 239153 misses, 12599 dirtied
WAL usage: 540876 records, 1 full page images, 57451288 bytes
system usage: CPU: user: 6.25 s, system: 0.96 s, elapsed: 9.01 s
2024-06-02 02:09:37.857 UTC [634841] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.036 ms
avg read rate: 0.000 MB/s, avg write rate: 0.020 MB/s
buffer usage: 30475 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.25 s, system: 0.12 s, elapsed: 0.39 s
2024-06-02 02:12:29.791 UTC [634982] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518646 remain, 49918 scanned (9.62% of total)
tuples: 1656476 removed, 47681748 remain, 1351 are dead but not yet removable
removable cutoff: 763350, which was 16912 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24961 pages from table (4.81% of total) had 2546209 dead item identifiers removed
index "pi1_pkey": pages: 144189 in total, 6980 newly deleted, 7040 currently deleted, 7040 reusable
index "pi1_marketsegment": pages: 196696 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 197651 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 290597 in total, 12454 newly deleted, 12454 currently deleted, 12454 reusable
I/O timings: read: 410.682 ms, write: 5.315 ms
avg read rate: 194.257 MB/s, avg write rate: 11.387 MB/s
buffer usage: 908810 hits, 227162 misses, 13316 dirtied
WAL usage: 552136 records, 2 full page images, 58266725 bytes
system usage: CPU: user: 6.32 s, system: 0.98 s, elapsed: 9.13 s
2024-06-02 02:13:01.246 UTC [635042] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30476 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.26 s, system: 0.11 s, elapsed: 0.38 s
2024-06-02 02:16:15.709 UTC [635211] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518855 remain, 51288 scanned (9.88% of total)
tuples: 1702056 removed, 47766366 remain, 1472 are dead but not yet removable
removable cutoff: 867983, which was 16612 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25646 pages from table (4.94% of total) had 2615829 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 7173 newly deleted, 7173 currently deleted, 7173 reusable
index "pi1_marketsegment": pages: 203424 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 205115 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 301546 in total, 13178 newly deleted, 13178 currently deleted, 13178 reusable
I/O timings: read: 392.009 ms, write: 5.117 ms
avg read rate: 181.240 MB/s, avg write rate: 11.831 MB/s
buffer usage: 959707 hits, 214273 misses, 13987 dirtied
WAL usage: 571785 records, 2 full page images, 60115850 bytes
system usage: CPU: user: 6.23 s, system: 1.17 s, elapsed: 9.23 s
2024-06-02 02:16:36.867 UTC [635259] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30471 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.25 s, system: 0.11 s, elapsed: 0.39 s
2024-06-02 02:19:38.288 UTC [635416] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49724 scanned (9.57% of total)
tuples: 1648711 removed, 47729849 remain, 1487 are dead but not yet removable
removable cutoff: 969424, which was 16172 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24863 pages from table (4.79% of total) had 2535985 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6953 newly deleted, 7264 currently deleted, 7264 reusable
index "pi1_marketsegment": pages: 209860 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 211812 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 310732 in total, 12457 newly deleted, 12457 currently deleted, 12457 reusable
I/O timings: read: 354.883 ms, write: 5.085 ms
avg read rate: 176.429 MB/s, avg write rate: 11.643 MB/s
buffer usage: 983338 hits, 200986 misses, 13264 dirtied
WAL usage: 578941 records, 1 full page images, 59477267 bytes
system usage: CPU: user: 6.10 s, system: 1.00 s, elapsed: 8.89 s
2024-06-02 02:20:00.082 UTC [635463] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.032 ms
avg read rate: 0.000 MB/s, avg write rate: 0.020 MB/s
buffer usage: 30475 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.24 s, system: 0.13 s, elapsed: 0.39 s
2024-06-02 02:23:03.877 UTC [635621] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 51103 scanned (9.84% of total)
tuples: 1696189 removed, 47671159 remain, 1477 are dead but not yet removable
removable cutoff: 1073683, which was 16495 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25553 pages from table (4.92% of total) had 2606405 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 7147 newly deleted, 7264 currently deleted, 7264 reusable
index "pi1_marketsegment": pages: 216996 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 219359 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 321485 in total, 12479 newly deleted, 12479 currently deleted, 12479 reusable
I/O timings: read: 349.437 ms, write: 5.182 ms
avg read rate: 160.615 MB/s, avg write rate: 11.321 MB/s
buffer usage: 1026912 hits, 188414 misses, 13281 dirtied
WAL usage: 597002 records, 2 full page images, 61215959 bytes
system usage: CPU: user: 6.27 s, system: 1.04 s, elapsed: 9.16 s
2024-06-02 02:23:25.393 UTC [635658] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30471 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.24 s, system: 0.12 s, elapsed: 0.38 s
2024-06-02 02:26:23.933 UTC [635819] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49407 scanned (9.51% of total)
tuples: 1637157 removed, 47781349 remain, 1482 are dead but not yet removable
removable cutoff: 1174472, which was 15825 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24707 pages from table (4.76% of total) had 2519850 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6908 newly deleted, 7262 currently deleted, 7262 reusable
index "pi1_marketsegment": pages: 222573 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 225230 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 331309 in total, 12498 newly deleted, 12498 currently deleted, 12498 reusable
I/O timings: read: 322.073 ms, write: 5.621 ms
avg read rate: 154.405 MB/s, avg write rate: 11.675 MB/s
buffer usage: 1055398 hits, 175825 misses, 13295 dirtied
WAL usage: 604281 records, 3 full page images, 60571557 bytes
system usage: CPU: user: 5.99 s, system: 1.05 s, elapsed: 8.89 s
2024-06-02 02:26:25.956 UTC [633084] LOG: checkpoints are occurring too frequently (1791 seconds apart)
2024-06-02 02:26:25.956 UTC [633084] HINT: Consider increasing the configuration parameter "max_wal_size".
2024-06-02 02:26:25.956 UTC [633084] LOG: checkpoint starting: wal
2024-06-02 02:26:45.727 UTC [635867] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 8.452 MB/s
buffer usage: 30469 hits, 0 misses, 423 dirtied
system usage: CPU: user: 0.22 s, system: 0.14 s, elapsed: 0.39 s
2024-06-02 02:29:40.721 UTC [636017] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49582 scanned (9.54% of total)
tuples: 1643269 removed, 47710459 remain, 2406 are dead but not yet removable
removable cutoff: 1275579, which was 16914 XIDs old when operation ended
frozen: 4390 pages from table (0.85% of total) had 448198 tuples frozen
index scan needed: 24799 pages from table (4.77% of total) had 2528226 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6930 newly deleted, 7258 currently deleted, 7258 reusable
index "pi1_marketsegment": pages: 228097 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 230987 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 341539 in total, 12615 newly deleted, 12615 currently deleted, 12615 reusable
I/O timings: read: 331.396 ms, write: 5.467 ms
avg read rate: 131.844 MB/s, avg write rate: 23.974 MB/s
buffer usage: 1091034 hits, 163235 misses, 29682 dirtied
WAL usage: 624888 records, 30657 full page images, 249657955 bytes
system usage: CPU: user: 6.31 s, system: 1.04 s, elapsed: 9.67 s
2024-06-02 02:30:01.428 UTC [636063] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.26 s, system: 0.10 s, elapsed: 0.37 s
2024-06-02 02:32:43.205 UTC [636210] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 48046 scanned (9.25% of total)
tuples: 1592434 removed, 47765815 remain, 1457 are dead but not yet removable
removable cutoff: 1373594, which was 16072 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24025 pages from table (4.62% of total) had 2449749 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6719 newly deleted, 7261 currently deleted, 7261 reusable
index "pi1_marketsegment": pages: 232592 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 235501 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 350620 in total, 11880 newly deleted, 11880 currently deleted, 11880 reusable
I/O timings: read: 291.520 ms, write: 5.360 ms
avg read rate: 128.810 MB/s, avg write rate: 10.788 MB/s
buffer usage: 1109122 hits, 150588 misses, 12612 dirtied
WAL usage: 619177 records, 18607 full page images, 195421244 bytes
system usage: CPU: user: 6.15 s, system: 0.95 s, elapsed: 9.13 s
2024-06-02 02:33:04.542 UTC [636248] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.22 s, system: 0.12 s, elapsed: 0.36 s
2024-06-02 02:36:06.973 UTC [636415] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 50192 scanned (9.66% of total)
tuples: 1664457 removed, 47747805 remain, 1383 are dead but not yet removable
removable cutoff: 1475993, which was 16276 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25098 pages from table (4.83% of total) had 2559924 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 7019 newly deleted, 7261 currently deleted, 7261 reusable
index "pi1_marketsegment": pages: 237671 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 240759 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 361605 in total, 12602 newly deleted, 12602 currently deleted, 12602 reusable
I/O timings: read: 281.738 ms, write: 5.452 ms
avg read rate: 116.647 MB/s, avg write rate: 11.218 MB/s
buffer usage: 1157045 hits, 138676 misses, 13336 dirtied
WAL usage: 636909 records, 19625 full page images, 204831160 bytes
system usage: CPU: user: 6.23 s, system: 0.99 s, elapsed: 9.28 s
2024-06-02 02:36:28.152 UTC [636452] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.28 s, system: 0.06 s, elapsed: 0.36 s
2024-06-02 02:39:27.841 UTC [636610] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 50205 scanned (9.66% of total)
tuples: 1665662 removed, 47768522 remain, 1472 are dead but not yet removable
removable cutoff: 1578418, which was 16732 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25106 pages from table (4.83% of total) had 2560611 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 7021 newly deleted, 7260 currently deleted, 7260 reusable
index "pi1_marketsegment": pages: 242089 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 245362 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 371899 in total, 12630 newly deleted, 12630 currently deleted, 12630 reusable
I/O timings: read: 266.069 ms, write: 5.498 ms
avg read rate: 103.321 MB/s, avg write rate: 16.335 MB/s
buffer usage: 1189292 hits, 126065 misses, 19931 dirtied
WAL usage: 646004 records, 19194 full page images, 201920719 bytes
system usage: CPU: user: 6.51 s, system: 0.90 s, elapsed: 9.53 s
2024-06-02 02:39:48.772 UTC [636656] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.25 s, system: 0.08 s, elapsed: 0.36 s
2024-06-02 02:42:40.537 UTC [636839] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49244 scanned (9.48% of total)
tuples: 1633047 removed, 47786907 remain, 1504 are dead but not yet removable
removable cutoff: 1678879, which was 16871 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24625 pages from table (4.74% of total) had 2511468 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6886 newly deleted, 7259 currently deleted, 7259 reusable
index "pi1_marketsegment": pages: 245768 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 249225 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 381724 in total, 12593 newly deleted, 12593 currently deleted, 12593 reusable
I/O timings: read: 247.118 ms, write: 5.734 ms
avg read rate: 93.215 MB/s, avg write rate: 25.962 MB/s
buffer usage: 1215561 hits, 113427 misses, 31591 dirtied
WAL usage: 651062 records, 12634 full page images, 153899755 bytes
system usage: CPU: user: 6.24 s, system: 0.99 s, elapsed: 9.50 s
2024-06-02 02:43:01.391 UTC [636886] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.24 s, system: 0.09 s, elapsed: 0.35 s
2024-06-02 02:46:10.939 UTC [637056] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 50193 scanned (9.66% of total)
tuples: 1665071 removed, 47768574 remain, 1605 are dead but not yet removable
removable cutoff: 1781282, which was 16862 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25099 pages from table (4.83% of total) had 2559899 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 7019 newly deleted, 7259 currently deleted, 7259 reusable
index "pi1_marketsegment": pages: 249547 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 253113 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 391957 in total, 12614 newly deleted, 12614 currently deleted, 12614 reusable
I/O timings: read: 231.234 ms, write: 5.774 ms
avg read rate: 82.137 MB/s, avg write rate: 26.708 MB/s
buffer usage: 1249629 hits, 100825 misses, 32785 dirtied
WAL usage: 661100 records, 12647 full page images, 154814823 bytes
system usage: CPU: user: 6.36 s, system: 1.01 s, elapsed: 9.59 s
2024-06-02 02:46:31.812 UTC [637094] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.25 s, system: 0.09 s, elapsed: 0.36 s
2024-06-02 02:49:37.255 UTC [637251] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49589 scanned (9.55% of total)
tuples: 1644556 removed, 47661825 remain, 1490 are dead but not yet removable
removable cutoff: 1882449, which was 16826 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24797 pages from table (4.77% of total) had 2529215 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6936 newly deleted, 7259 currently deleted, 7259 reusable
index "pi1_marketsegment": pages: 252871 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 256393 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 401837 in total, 12622 newly deleted, 12622 currently deleted, 12622 reusable
I/O timings: read: 214.044 ms, write: 5.886 ms
avg read rate: 71.249 MB/s, avg write rate: 24.100 MB/s
buffer usage: 1276707 hits, 88203 misses, 29835 dirtied
WAL usage: 666238 records, 12649 full page images, 155211625 bytes
system usage: CPU: user: 6.50 s, system: 0.91 s, elapsed: 9.67 s
2024-06-02 02:50:00.549 UTC [637305] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.26 s, system: 0.08 s, elapsed: 0.36 s
2024-06-02 02:53:02.422 UTC [637459] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49832 scanned (9.59% of total)
tuples: 1654256 removed, 47774646 remain, 1511 are dead but not yet removable
removable cutoff: 1984110, which was 16257 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24919 pages from table (4.80% of total) had 2541579 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6968 newly deleted, 7258 currently deleted, 7258 reusable
index "pi1_marketsegment": pages: 256067 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 259491 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 411770 in total, 12623 newly deleted, 12623 currently deleted, 12623 reusable
I/O timings: read: 193.444 ms, write: 5.721 ms
avg read rate: 63.406 MB/s, avg write rate: 11.293 MB/s
buffer usage: 1306379 hits, 75589 misses, 13463 dirtied
WAL usage: 673121 records, 12660 full page images, 155863815 bytes
system usage: CPU: user: 6.29 s, system: 1.00 s, elapsed: 9.31 s
2024-06-02 02:53:23.671 UTC [637497] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.27 s, system: 0.07 s, elapsed: 0.36 s
2024-06-02 02:53:32.171 UTC [633084] LOG: checkpoint complete: wrote 1280776 buffers (42.5%); 0 WAL file(s) added, 0 removed, 1347 recycled; write=1619.132 s, sync=0.138 s, total=1626.216 s; sync files=345, longest=0.007 s, average=0.001 s; distance=22066023 kB, estimate=22066023 kB; lsn=9/2A302568, redo lsn=5/450BD988
2024-06-02 02:56:15.186 UTC [637659] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 48239 scanned (9.29% of total)
tuples: 1599755 removed, 47897744 remain, 1428 are dead but not yet removable
removable cutoff: 2082523, which was 15727 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24122 pages from table (4.64% of total) had 2460283 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6746 newly deleted, 7257 currently deleted, 7257 reusable
index "pi1_marketsegment": pages: 258553 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 262011 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 420975 in total, 11888 newly deleted, 11888 currently deleted, 11888 reusable
I/O timings: read: 175.009 ms, write: 6.018 ms
avg read rate: 55.602 MB/s, avg write rate: 11.168 MB/s
buffer usage: 1320287 hits, 63066 misses, 12667 dirtied
WAL usage: 672030 records, 11939 full page images, 148116629 bytes
system usage: CPU: user: 6.22 s, system: 0.93 s, elapsed: 8.86 s
2024-06-02 02:56:25.270 UTC [633084] LOG: checkpoint starting: time
2024-06-02 02:58:39.794 UTC [638411] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.153 MB/s
buffer usage: 30474 hits, 0 misses, 7 dirtied
system usage: CPU: user: 0.24 s, system: 0.09 s, elapsed: 0.35 s
2024-06-02 03:23:30.591 UTC [633084] LOG: checkpoint complete: wrote 1023733 buffers (34.0%); 0 WAL file(s) added, 0 removed, 1075 recycled; write=1619.759 s, sync=0.031 s, total=1625.321 s; sync files=21, longest=0.007 s, average=0.002 s; distance=17624662 kB, estimate=21621887 kB; lsn=A/6B70A958, redo lsn=9/78C531A8
2024-06-02 03:25:30.648 UTC [639940] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49349 scanned (9.50% of total)
tuples: 2098212 removed, 47832333 remain, 368 are dead but not yet removable
removable cutoff: 2925444, which was 49913 XIDs old when operation ended
frozen: 4550 pages from table (0.88% of total) had 464474 tuples frozen
index scan needed: 24676 pages from table (4.75% of total) had 2517795 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6905 newly deleted, 7272 currently deleted, 7272 reusable
index "pi1_marketsegment": pages: 261184 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 264279 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 429143 in total, 12609 newly deleted, 12609 currently deleted, 12609 reusable
I/O timings: read: 171.314 ms, write: 6.780 ms
avg read rate: 41.931 MB/s, avg write rate: 60.900 MB/s
buffer usage: 1356219 hits, 51263 misses, 74454 dirtied
WAL usage: 691041 records, 26619 full page images, 216871430 bytes
system usage: CPU: user: 6.30 s, system: 0.91 s, elapsed: 9.55 s
2024-06-02 03:26:25.689 UTC [633084] LOG: checkpoint starting: time
2024-06-02 03:28:09.476 UTC [640228] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.026 ms
avg read rate: 0.000 MB/s, avg write rate: 6.675 MB/s
buffer usage: 30477 hits, 0 misses, 311 dirtied
system usage: CPU: user: 0.24 s, system: 0.09 s, elapsed: 0.36 s
2024-06-02 03:53:26.549 UTC [633084] LOG: checkpoint complete: wrote 618915 buffers (20.5%); 0 WAL file(s) added, 0 removed, 277 recycled; write=1619.360 s, sync=0.031 s, total=1620.860 s; sync files=22, longest=0.007 s, average=0.002 s; distance=4535881 kB, estimate=19913287 kB; lsn=B/7DFB5778, redo lsn=A/8D9E5818
2024-06-02 03:56:25.633 UTC [633084] LOG: checkpoint starting: time
2024-06-02 03:57:00.330 UTC [641860] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49030 scanned (9.44% of total)
tuples: 2185829 removed, 47744266 remain, 407 are dead but not yet removable
removable cutoff: 3926251, which was 56304 XIDs old when operation ended
frozen: 12707 pages from table (2.45% of total) had 1297134 tuples frozen
index scan needed: 24515 pages from table (4.72% of total) had 2502006 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6860 newly deleted, 7271 currently deleted, 7271 reusable
index "pi1_marketsegment": pages: 263631 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 266649 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 439034 in total, 12610 newly deleted, 12610 currently deleted, 12610 reusable
I/O timings: read: 150.151 ms, write: 6.509 ms
avg read rate: 28.195 MB/s, avg write rate: 76.166 MB/s
buffer usage: 1382353 hits, 38759 misses, 104705 dirtied
WAL usage: 711420 records, 476718 full page images, 2558933902 bytes
system usage: CPU: user: 7.16 s, system: 0.92 s, elapsed: 10.73 s
2024-06-02 03:59:03.255 UTC [642107] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.109 MB/s
buffer usage: 30471 hits, 0 misses, 5 dirtied
system usage: CPU: user: 0.24 s, system: 0.10 s, elapsed: 0.35 s
2024-06-02 04:23:26.416 UTC [633084] LOG: checkpoint complete: wrote 519599 buffers (17.2%); 0 WAL file(s) added, 6 removed, 240 recycled; write=1619.403 s, sync=0.028 s, total=1620.783 s; sync files=23, longest=0.008 s, average=0.002 s; distance=4024789 kB, estimate=18324437 kB; lsn=C/A12C7130, redo lsn=B/8345ADE0
2024-06-02 04:25:11.656 UTC [643637] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 48742 scanned (9.38% of total)
tuples: 2173348 removed, 47772661 remain, 220 are dead but not yet removable
removable cutoff: 4921318, which was 47206 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24371 pages from table (4.69% of total) had 2487642 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6821 newly deleted, 7273 currently deleted, 7273 reusable
index "pi1_marketsegment": pages: 265844 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 268732 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 448440 in total, 11956 newly deleted, 11956 currently deleted, 11956 reusable
I/O timings: read: 123.327 ms, write: 5.267 ms
avg read rate: 23.216 MB/s, avg write rate: 58.496 MB/s
buffer usage: 1400854 hits, 26271 misses, 66193 dirtied
WAL usage: 687428 records, 11974 full page images, 150024255 bytes
system usage: CPU: user: 6.16 s, system: 0.84 s, elapsed: 8.84 s
2024-06-02 04:26:25.513 UTC [633084] LOG: checkpoint starting: time
2024-06-02 04:26:53.779 UTC [643858] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.131 MB/s
buffer usage: 30469 hits, 0 misses, 6 dirtied
system usage: CPU: user: 0.22 s, system: 0.12 s, elapsed: 0.35 s
2024-06-02 04:53:27.861 UTC [633084] LOG: checkpoint complete: wrote 660322 buffers (21.9%); 0 WAL file(s) added, 167 removed, 151 recycled; write=1619.476 s, sync=0.032 s, total=1622.348 s; sync files=23, longest=0.007 s, average=0.002 s; distance=5216011 kB, estimate=17013594 kB; lsn=D/B527F910, redo lsn=C/C1A1DA00
2024-06-02 04:53:48.573 UTC [645421] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49359 scanned (9.50% of total)
tuples: 2200477 removed, 47783107 remain, 326 are dead but not yet removable
removable cutoff: 5928871, which was 45916 XIDs old when operation ended
frozen: 5179 pages from table (1.00% of total) had 528722 tuples frozen
index scan needed: 24680 pages from table (4.75% of total) had 2518904 dead item identifiers removed
index "pi1_pkey": pages: 144371 in total, 6906 newly deleted, 7271 currently deleted, 7271 reusable
index "pi1_marketsegment": pages: 268097 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 270853 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 458879 in total, 12646 newly deleted, 12646 currently deleted, 12646 reusable
I/O timings: read: 104.124 ms, write: 4.083 ms
avg read rate: 12.758 MB/s, avg write rate: 94.547 MB/s
buffer usage: 1436444 hits, 14496 misses, 107427 dirtied
WAL usage: 705752 records, 28441 full page images, 225156429 bytes
system usage: CPU: user: 6.16 s, system: 0.85 s, elapsed: 8.87 s
2024-06-02 04:54:38.430 UTC [633084] LOG: checkpoint starting: immediate force wait
2024-06-02 04:54:46.397 UTC [633084] LOG: checkpoint complete: wrote 637454 buffers (21.1%); 0 WAL file(s) added, 159 removed, 117 recycled; write=5.333 s, sync=0.024 s, total=7.968 s; sync files=22, longest=0.007 s, average=0.002 s; distance=4518411 kB, estimate=15764076 kB; lsn=D/D56A0638, redo lsn=D/D56A0600
2024-06-02 05:24:38.497 UTC [633084] LOG: checkpoint starting: time
2024-06-02 05:51:39.107 UTC [633084] LOG: checkpoint complete: wrote 254809 buffers (8.5%); 0 WAL file(s) added, 89 removed, 0 recycled; write=1619.534 s, sync=0.013 s, total=1620.610 s; sync files=20, longest=0.008 s, average=0.001 s; distance=1467286 kB, estimate=14334397 kB; lsn=E/86141BB8, redo lsn=E/2EF86038
2024-06-02 05:54:38.207 UTC [633084] LOG: checkpoint starting: time
2024-06-02 06:21:39.063 UTC [633084] LOG: checkpoint complete: wrote 219354 buffers (7.3%); 0 WAL file(s) added, 94 removed, 0 recycled; write=1619.830 s, sync=0.026 s, total=1620.856 s; sync files=19, longest=0.021 s, average=0.002 s; distance=1536111 kB, estimate=13054568 kB; lsn=E/E516A8B8, redo lsn=E/8CBA1C48
2024-06-02 06:24:38.161 UTC [633084] LOG: checkpoint starting: time
2024-06-02 06:51:39.209 UTC [633084] LOG: checkpoint complete: wrote 226890 buffers (7.5%); 0 WAL file(s) added, 95 removed, 0 recycled; write=1619.851 s, sync=0.027 s, total=1621.049 s; sync files=20, longest=0.011 s, average=0.002 s; distance=1556091 kB, estimate=11904720 kB; lsn=F/4540C768, redo lsn=E/EBB40A00
2024-06-02 06:54:38.309 UTC [633084] LOG: checkpoint starting: time
2024-06-02 07:21:39.242 UTC [633084] LOG: checkpoint complete: wrote 226694 buffers (7.5%); 0 WAL file(s) added, 96 removed, 0 recycled; write=1619.740 s, sync=0.038 s, total=1620.933 s; sync files=18, longest=0.015 s, average=0.003 s; distance=1576782 kB, estimate=10871927 kB; lsn=10/C04BDA0, redo lsn=F/4BF14440
2024-06-02 07:24:38.342 UTC [633084] LOG: checkpoint starting: time
2024-06-02 07:51:40.444 UTC [633084] LOG: checkpoint complete: wrote 509049 buffers (16.9%); 0 WAL file(s) added, 199 removed, 0 recycled; write=1619.657 s, sync=0.037 s, total=1622.103 s; sync files=19, longest=0.007 s, average=0.002 s; distance=3248614 kB, estimate=10109595 kB; lsn=10/D6F5A9C0, redo lsn=10/1238DFB8
2024-06-02 07:54:38.529 UTC [633084] LOG: checkpoint starting: time
2024-06-02 07:56:22.311 UTC [880482] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49049 scanned (9.44% of total)
tuples: 1619576 removed, 47800057 remain, 0 are dead but not yet removable
removable cutoff: 6200917, which was 360 XIDs old when operation ended
frozen: 22810 pages from table (4.39% of total) had 2328474 tuples frozen
index scan needed: 24521 pages from table (4.72% of total) had 2503050 dead item identifiers removed
index "pi1_pkey": pages: 145145 in total, 6862 newly deleted, 8041 currently deleted, 7878 reusable
index "pi1_marketsegment": pages: 270395 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 272955 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 471465 in total, 22166 newly deleted, 25089 currently deleted, 25089 reusable
I/O timings: read: 0.000 ms, write: 0.021 ms
avg read rate: 0.000 MB/s, avg write rate: 75.357 MB/s
buffer usage: 1571646 hits, 0 misses, 171681 dirtied
WAL usage: 772371 records, 532305 full page images, 2750344432 bytes
system usage: CPU: user: 11.56 s, system: 2.25 s, elapsed: 17.79 s
2024-06-02 07:56:22.557 UTC [880482] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.128 MB/s
buffer usage: 30404 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.22 s, system: 0.00 s, elapsed: 0.24 s
2024-06-02 08:21:40.111 UTC [633084] LOG: checkpoint complete: wrote 467312 buffers (15.5%); 0 WAL file(s) added, 160 removed, 42 recycled; write=1619.465 s, sync=0.012 s, total=1621.583 s; sync files=19, longest=0.005 s, average=0.001 s; distance=3321118 kB, estimate=9430748 kB; lsn=11/BD9A6FC0, redo lsn=10/DCED58D8
2024-06-02 08:24:38.209 UTC [633084] LOG: checkpoint starting: time
2024-06-02 08:51:39.203 UTC [633084] LOG: checkpoint complete: wrote 530912 buffers (17.6%); 0 WAL file(s) added, 0 removed, 228 recycled; write=1619.771 s, sync=0.025 s, total=1620.994 s; sync files=22, longest=0.007 s, average=0.002 s; distance=3733395 kB, estimate=8861012 kB; lsn=12/84A43CF0, redo lsn=11/C0CBA5B0
2024-06-02 08:54:38.301 UTC [633084] LOG: checkpoint starting: time
2024-06-02 09:08:35.272 UTC [1108056] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 49267 scanned (9.48% of total)
tuples: 1611829 removed, 47893743 remain, 0 are dead but not yet removable
removable cutoff: 6301478, which was 560 XIDs old when operation ended
frozen: 5320 pages from table (1.02% of total) had 543090 tuples frozen
index scan needed: 24628 pages from table (4.74% of total) had 2514000 dead item identifiers removed
index "pi1_pkey": pages: 145145 in total, 6893 newly deleted, 8038 currently deleted, 5190 reusable
index "pi1_marketsegment": pages: 272679 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 274883 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 471465 in total, 22317 newly deleted, 24931 currently deleted, 24931 reusable
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 63.865 MB/s
buffer usage: 1575198 hits, 0 misses, 110913 dirtied
WAL usage: 742437 records, 73601 full page images, 346905037 bytes
system usage: CPU: user: 10.89 s, system: 0.66 s, elapsed: 13.56 s
2024-06-02 09:08:42.053 UTC [1108065] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.023 MB/s
buffer usage: 30469 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.22 s, system: 0.11 s, elapsed: 0.34 s
2024-06-02 09:21:39.096 UTC [633084] LOG: checkpoint complete: wrote 458168 buffers (15.2%); 0 WAL file(s) added, 0 removed, 202 recycled; write=1619.727 s, sync=0.019 s, total=1620.795 s; sync files=20, longest=0.007 s, average=0.001 s; distance=3301706 kB, estimate=8305082 kB; lsn=13/888026E8, redo lsn=12/8A50D120
2024-06-02 09:24:38.157 UTC [633084] LOG: checkpoint starting: time
2024-06-02 09:50:14.548 UTC [1110002] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519462 remain, 48940 scanned (9.42% of total)
tuples: 1617435 removed, 47681135 remain, 621 are dead but not yet removable
removable cutoff: 6401359, which was 560 XIDs old when operation ended
frozen: 9504 pages from table (1.83% of total) had 970177 tuples frozen
index scan needed: 24469 pages from table (4.71% of total) had 2497119 dead item identifiers removed
index "pi1_pkey": pages: 146801 in total, 6847 newly deleted, 9695 currently deleted, 9650 reusable
index "pi1_marketsegment": pages: 274714 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 276817 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 471465 in total, 22356 newly deleted, 25042 currently deleted, 20296 reusable
I/O timings: read: 0.000 ms, write: 0.027 ms
avg read rate: 0.000 MB/s, avg write rate: 75.641 MB/s
buffer usage: 1579369 hits, 0 misses, 130826 dirtied
WAL usage: 753877 records, 37324 full page images, 229735885 bytes
system usage: CPU: user: 10.60 s, system: 0.70 s, elapsed: 13.51 s
2024-06-02 09:50:21.390 UTC [1110017] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.089 MB/s
buffer usage: 30469 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.22 s, system: 0.10 s, elapsed: 0.35 s
2024-06-02 09:51:39.413 UTC [633084] LOG: checkpoint complete: wrote 618769 buffers (20.5%); 0 WAL file(s) added, 0 removed, 261 recycled; write=1619.894 s, sync=0.014 s, total=1621.257 s; sync files=23, longest=0.005 s, average=0.001 s; distance=4275473 kB, estimate=7902121 kB; lsn=14/876AE148, redo lsn=13/8F4516F0
2024-06-02 09:54:38.514 UTC [633084] LOG: checkpoint starting: time
2024-06-02 10:21:39.336 UTC [633084] LOG: checkpoint complete: wrote 635545 buffers (21.1%); 0 WAL file(s) added, 0 removed, 255 recycled; write=1619.475 s, sync=0.024 s, total=1620.823 s; sync files=22, longest=0.007 s, average=0.002 s; distance=4176364 kB, estimate=7529545 kB; lsn=15/73872508, redo lsn=14/8E2CC918
2024-06-02 10:24:38.437 UTC [633084] LOG: checkpoint starting: time
2024-06-02 10:32:07.553 UTC [1244376] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 5560 removed, 513902 remain, 49158 scanned (9.46% of total)
tuples: 1623015 removed, 47785190 remain, 486 are dead but not yet removable
removable cutoff: 6501705, which was 576 XIDs old when operation ended
frozen: 28 pages from table (0.01% of total) had 2860 tuples frozen
index scan needed: 24581 pages from table (4.73% of total) had 2508395 dead item identifiers removed
index "pi1_pkey": pages: 146801 in total, 6878 newly deleted, 9696 currently deleted, 9510 reusable
index "pi1_marketsegment": pages: 276746 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 278823 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 473561 in total, 22254 newly deleted, 27000 currently deleted, 27000 reusable
I/O timings: read: 0.000 ms, write: 0.044 ms
avg read rate: 0.000 MB/s, avg write rate: 69.068 MB/s
buffer usage: 1597077 hits, 0 misses, 126149 dirtied
WAL usage: 739199 records, 174126 full page images, 760419777 bytes
system usage: CPU: user: 10.73 s, system: 0.95 s, elapsed: 14.26 s
2024-06-02 10:32:13.635 UTC [1245610] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.089 MB/s
buffer usage: 30469 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.23 s, system: 0.09 s, elapsed: 0.34 s
2024-06-02 10:51:39.330 UTC [633084] LOG: checkpoint complete: wrote 552507 buffers (18.3%); 0 WAL file(s) added, 0 removed, 236 recycled; write=1619.633 s, sync=0.024 s, total=1620.893 s; sync files=21, longest=0.007 s, average=0.002 s; distance=3872249 kB, estimate=7163816 kB; lsn=16/7A5AC500, redo lsn=15/7A84AE00
2024-06-02 10:54:38.349 UTC [633084] LOG: checkpoint starting: time
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment