Skip to content

Instantly share code, notes, and snippets.

@mdcallag
Created June 3, 2024 20:39
Show Gist options
  • Select an option

  • Save mdcallag/a0b140e94b5529acd168c59ed8d41b22 to your computer and use it in GitHub Desktop.

Select an option

Save mdcallag/a0b140e94b5529acd168c59ed8d41b22 to your computer and use it in GitHub Desktop.
logfile with full_page_writes=OFF
2024-06-02 11:07:23.150 UTC [1340362] 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 11:07:23.152 UTC [1340362] LOG: listening on IPv4 address "127.0.0.1", port 5432
2024-06-02 11:07:23.170 UTC [1340362] LOG: listening on Unix socket "/tmp/.s.PGSQL.5432"
2024-06-02 11:07:23.185 UTC [1340365] LOG: database system was shut down at 2024-06-02 11:07:22 UTC
2024-06-02 11:07:23.195 UTC [1340362] LOG: database system is ready to accept connections
2024-06-02 11:07:38.612 UTC [1340363] LOG: checkpoint starting: immediate force wait
2024-06-02 11:07:38.908 UTC [1340363] LOG: checkpoint complete: wrote 1888 buffers (0.1%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.076 s, sync=0.197 s, total=0.296 s; sync files=621, longest=0.009 s, average=0.001 s; distance=12784 kB, estimate=12784 kB; lsn=0/23A6338, redo lsn=0/23A6300
2024-06-02 11:07:39.878 UTC [1340408] LOG: automatic analyze of table "template1.pg_catalog.pg_type"
I/O timings: read: 0.021 ms, write: 0.025 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 11:07:39.879 UTC [1340408] 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: 91.912 MB/s
buffer usage: 12 hits, 0 misses, 1 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 11:07:39.888 UTC [1340408] LOG: automatic analyze of table "template1.pg_catalog.pg_attribute"
I/O timings: read: 0.000 ms, write: 0.014 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 11:07:39.888 UTC [1340408] 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 11:07:39.890 UTC [1340408] 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 11:07:39.891 UTC [1340408] LOG: automatic analyze of table "template1.pg_catalog.pg_constraint"
I/O timings: read: 0.000 ms, write: 0.024 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 11:07:39.892 UTC [1340408] 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 11:07:39.892 UTC [1340408] 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: 608.766 MB/s
buffer usage: 28 hits, 0 misses, 6 dirtied
WAL usage: 7 records, 0 full page images, 543 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 11:07:39.896 UTC [1340408] LOG: automatic analyze of table "template1.pg_catalog.pg_description"
I/O timings: read: 0.000 ms, write: 0.013 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 11:07:39.896 UTC [1340408] 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: 221.806 MB/s
buffer usage: 96 hits, 0 misses, 9 dirtied
WAL usage: 19 records, 0 full page images, 1558 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 11:07:39.898 UTC [1340408] 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 11:07:39.899 UTC [1340408] 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 11:07:39.899 UTC [1340408] 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 11:07:39.900 UTC [1340408] 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 11:07:39.902 UTC [1340408] LOG: automatic analyze of table "template1.information_schema.sql_features"
I/O timings: read: 0.000 ms, write: 0.019 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 11:07:46.574 UTC [1340428] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 3426 remain, 3426 scanned (100.00% of total)
tuples: 0 removed, 349704 remain, 0 are dead but not yet removable
removable cutoff: 4248, 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.812 MB/s
buffer usage: 6888 hits, 0 misses, 3 dirtied
WAL usage: 3405 records, 0 full page images, 200994 bytes
system usage: CPU: user: 0.01 s, system: 0.00 s, elapsed: 0.02 s
2024-06-02 11:07:46.727 UTC [1340428] 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.051 MB/s
buffer usage: 3830 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.14 s, system: 0.00 s, elapsed: 0.15 s
2024-06-02 11:07:51.646 UTC [1340430] 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: 11901, which was 1 XIDs old when operation ended
new relfrozenxid: 11901, which is 11179 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: 40.584 MB/s
buffer usage: 93 hits, 0 misses, 2 dirtied
WAL usage: 66 records, 0 full page images, 5417 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-02 11:07:56.670 UTC [1340433] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 18123 remain, 14721 scanned (81.23% of total)
tuples: 0 removed, 1850173 remain, 0 are dead but not yet removable
removable cutoff: 19252, 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: 29483 hits, 0 misses, 0 dirtied
WAL usage: 14721 records, 0 full page images, 868668 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:07:56.874 UTC [1340433] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.018 ms
avg read rate: 0.000 MB/s, avg write rate: 0.038 MB/s
buffer usage: 18666 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.18 s, system: 0.00 s, elapsed: 0.20 s
2024-06-02 11:08:06.671 UTC [1340437] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 32818 remain, 14696 scanned (44.78% of total)
tuples: 0 removed, 3350101 remain, 0 are dead but not yet removable
removable cutoff: 34254, 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.027 ms
avg read rate: 0.000 MB/s, avg write rate: 0.064 MB/s
buffer usage: 29439 hits, 0 misses, 1 dirtied
WAL usage: 14696 records, 0 full page images, 867193 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:08:06.915 UTC [1340437] 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: 30359 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.01 s, elapsed: 0.24 s
2024-06-02 11:08:16.673 UTC [1340447] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 47499 remain, 14682 scanned (30.91% of total)
tuples: 0 removed, 4848807 remain, 0 are dead but not yet removable
removable cutoff: 49241, which was 183 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: 29414 hits, 0 misses, 0 dirtied
WAL usage: 14682 records, 0 full page images, 866367 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:08:16.932 UTC [1340447] 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.21 s, system: 0.02 s, elapsed: 0.25 s
2024-06-02 11:08:26.674 UTC [1340451] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 62201 remain, 14703 scanned (23.64% of total)
tuples: 0 removed, 6349493 remain, 0 are dead but not yet removable
removable cutoff: 64251, 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: 29460 hits, 0 misses, 0 dirtied
WAL usage: 14703 records, 0 full page images, 867606 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:08:26.942 UTC [1340451] 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: 30351 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.22 s, system: 0.03 s, elapsed: 0.26 s
2024-06-02 11:08:36.675 UTC [1340464] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 76897 remain, 14697 scanned (19.11% of total)
tuples: 0 removed, 7849797 remain, 0 are dead but not yet removable
removable cutoff: 79254, 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.026 ms
avg read rate: 0.000 MB/s, avg write rate: 0.064 MB/s
buffer usage: 29453 hits, 0 misses, 1 dirtied
WAL usage: 14697 records, 0 full page images, 867252 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:08:36.950 UTC [1340464] 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.23 s, system: 0.02 s, elapsed: 0.27 s
2024-06-02 11:08:46.678 UTC [1340474] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 91591 remain, 14695 scanned (16.04% of total)
tuples: 0 removed, 9350084 remain, 0 are dead but not yet removable
removable cutoff: 94256, 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: 29453 hits, 0 misses, 0 dirtied
WAL usage: 14695 records, 0 full page images, 867134 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:08:46.958 UTC [1340474] 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.04 s, elapsed: 0.28 s
2024-06-02 11:08:56.679 UTC [1340478] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 106260 remain, 14670 scanned (13.81% of total)
tuples: 0 removed, 10847694 remain, 0 are dead but not yet removable
removable cutoff: 109233, 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.022 ms
avg read rate: 0.000 MB/s, avg write rate: 0.063 MB/s
buffer usage: 29409 hits, 0 misses, 1 dirtied
WAL usage: 14670 records, 0 full page images, 865659 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:08:56.965 UTC [1340478] 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.21 s, system: 0.05 s, elapsed: 0.28 s
2024-06-02 11:09:06.680 UTC [1340482] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 120952 remain, 14693 scanned (12.15% of total)
tuples: 0 removed, 12347374 remain, 0 are dead but not yet removable
removable cutoff: 124232, 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: 29458 hits, 0 misses, 0 dirtied
WAL usage: 14693 records, 0 full page images, 867016 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:09:06.971 UTC [1340482] 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.21 s, system: 0.05 s, elapsed: 0.29 s
2024-06-02 11:09:16.681 UTC [1340492] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 135634 remain, 14683 scanned (10.83% of total)
tuples: 0 removed, 13846879 remain, 0 are dead but not yet removable
removable cutoff: 139222, 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.027 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: 14683 records, 0 full page images, 866426 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:09:16.974 UTC [1340492] 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: 30359 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.20 s, system: 0.07 s, elapsed: 0.29 s
2024-06-02 11:09:26.681 UTC [1340496] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 150309 remain, 14676 scanned (9.76% of total)
tuples: 0 removed, 15343925 remain, 0 are dead but not yet removable
removable cutoff: 154204, 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: 29433 hits, 0 misses, 0 dirtied
WAL usage: 14677 records, 0 full page images, 866072 bytes
system usage: CPU: user: 0.11 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:09:26.982 UTC [1340496] 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.06 s, elapsed: 0.30 s
2024-06-02 11:09:36.782 UTC [1340509] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 165147 remain, 14838 scanned (8.98% of total)
tuples: 0 removed, 16858972 remain, 0 are dead but not yet removable
removable cutoff: 169352, 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.026 ms
avg read rate: 0.000 MB/s, avg write rate: 0.062 MB/s
buffer usage: 29763 hits, 0 misses, 1 dirtied
WAL usage: 14838 records, 0 full page images, 875571 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:09:46.882 UTC [1340519] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 179972 remain, 14826 scanned (8.24% of total)
tuples: 0 removed, 18372396 remain, 0 are dead but not yet removable
removable cutoff: 184486, 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: 29743 hits, 0 misses, 0 dirtied
WAL usage: 14826 records, 0 full page images, 874863 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:09:47.189 UTC [1340519] 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 11:09:56.982 UTC [1340523] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 194814 remain, 14843 scanned (7.62% of total)
tuples: 0 removed, 19887400 remain, 0 are dead but not yet removable
removable cutoff: 199639, 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: 29780 hits, 0 misses, 0 dirtied
WAL usage: 14843 records, 0 full page images, 875866 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:10:06.983 UTC [1340528] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 209520 remain, 14707 scanned (7.02% of total)
tuples: 0 removed, 21388654 remain, 0 are dead but not yet removable
removable cutoff: 214652, 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.027 ms
avg read rate: 0.000 MB/s, avg write rate: 0.063 MB/s
buffer usage: 29514 hits, 0 misses, 1 dirtied
WAL usage: 14707 records, 0 full page images, 867842 bytes
system usage: CPU: user: 0.08 s, system: 0.02 s, elapsed: 0.12 s
2024-06-02 11:10:07.293 UTC [1340528] 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.06 s, elapsed: 0.30 s
2024-06-02 11:10:16.985 UTC [1340538] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 224216 remain, 14697 scanned (6.55% of total)
tuples: 0 removed, 22888981 remain, 0 are dead but not yet removable
removable cutoff: 229653, 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: 29498 hits, 0 misses, 0 dirtied
WAL usage: 14696 records, 0 full page images, 867193 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:10:26.986 UTC [1340542] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 238911 remain, 14697 scanned (6.15% of total)
tuples: 0 removed, 24389137 remain, 0 are dead but not yet removable
removable cutoff: 244655, 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.022 ms
avg read rate: 0.000 MB/s, avg write rate: 0.062 MB/s
buffer usage: 29503 hits, 0 misses, 1 dirtied
WAL usage: 14696 records, 0 full page images, 867193 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.12 s
2024-06-02 11:10:27.305 UTC [1340542] 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.24 s, system: 0.05 s, elapsed: 0.31 s
2024-06-02 11:10:37.088 UTC [1340555] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 253748 remain, 14839 scanned (5.85% of total)
tuples: 0 removed, 25902220 remain, 0 are dead but not yet removable
removable cutoff: 259803, which was 190 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: 29791 hits, 0 misses, 0 dirtied
WAL usage: 14839 records, 0 full page images, 875630 bytes
system usage: CPU: user: 0.10 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:10:47.089 UTC [1340565] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 268441 remain, 14694 scanned (5.47% of total)
tuples: 0 removed, 27402242 remain, 0 are dead but not yet removable
removable cutoff: 274803, which was 190 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: 29506 hits, 0 misses, 1 dirtied
WAL usage: 14693 records, 0 full page images, 867016 bytes
system usage: CPU: user: 0.09 s, system: 0.02 s, elapsed: 0.12 s
2024-06-02 11:10:47.414 UTC [1340565] 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: 30358 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.24 s, system: 0.06 s, elapsed: 0.32 s
2024-06-02 11:10:57.091 UTC [1340569] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 283125 remain, 14686 scanned (5.19% of total)
tuples: 0 removed, 28903122 remain, 0 are dead but not yet removable
removable cutoff: 289793, which was 190 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: 29494 hits, 0 misses, 0 dirtied
WAL usage: 14685 records, 0 full page images, 866544 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.12 s
2024-06-02 11:11:07.092 UTC [1340573] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 297817 remain, 14694 scanned (4.93% of total)
tuples: 0 removed, 30402935 remain, 0 are dead but not yet removable
removable cutoff: 304792, 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.021 ms
avg read rate: 0.000 MB/s, avg write rate: 0.062 MB/s
buffer usage: 29516 hits, 0 misses, 1 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 11:11:17.305 UTC [1340584] 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: 30387 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.24 s, system: 0.07 s, elapsed: 0.33 s
2024-06-02 11:11:27.213 UTC [1340588] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 327214 remain, 29398 scanned (8.98% of total)
tuples: 0 removed, 33401941 remain, 0 are dead but not yet removable
removable cutoff: 334803, which was 367 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.032 MB/s
buffer usage: 58933 hits, 0 misses, 1 dirtied
WAL usage: 29398 records, 0 full page images, 1734611 bytes
system usage: CPU: user: 0.18 s, system: 0.03 s, elapsed: 0.24 s
2024-06-02 11:11:47.316 UTC [1340611] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 356730 remain, 29517 scanned (8.27% of total)
tuples: 0 removed, 36414879 remain, 0 are dead but not yet removable
removable cutoff: 364933, 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.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 59178 hits, 0 misses, 0 dirtied
WAL usage: 29517 records, 0 full page images, 1741632 bytes
system usage: CPU: user: 0.20 s, system: 0.01 s, elapsed: 0.24 s
2024-06-02 11:11:47.641 UTC [1340611] 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: 30360 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.21 s, system: 0.08 s, elapsed: 0.32 s
2024-06-02 11:12:07.319 UTC [1340619] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 386128 remain, 29399 scanned (7.61% of total)
tuples: 0 removed, 39417935 remain, 0 are dead but not yet removable
removable cutoff: 394943, which was 368 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.032 MB/s
buffer usage: 58951 hits, 0 misses, 1 dirtied
WAL usage: 29399 records, 0 full page images, 1734670 bytes
system usage: CPU: user: 0.20 s, system: 0.01 s, elapsed: 0.24 s
2024-06-02 11:12:20.613 UTC [1340629] 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: 30389 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.24 s, system: 0.08 s, elapsed: 0.34 s
2024-06-02 11:12:30.655 UTC [1340634] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 420334 remain, 34207 scanned (8.14% of total)
tuples: 0 removed, 42911545 remain, 0 are dead but not yet removable
removable cutoff: 429865, which was 428 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.027 MB/s
buffer usage: 68578 hits, 0 misses, 1 dirtied
WAL usage: 34207 records, 0 full page images, 2018342 bytes
system usage: CPU: user: 0.23 s, system: 0.02 s, elapsed: 0.28 s
2024-06-02 11:12:50.621 UTC [1340657] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 449728 remain, 29395 scanned (6.54% of total)
tuples: 0 removed, 45912297 remain, 0 are dead but not yet removable
removable cutoff: 459872, which was 373 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.031 MB/s
buffer usage: 58963 hits, 0 misses, 1 dirtied
WAL usage: 29395 records, 0 full page images, 1734434 bytes
system usage: CPU: user: 0.21 s, system: 0.00 s, elapsed: 0.24 s
2024-06-02 11:12:50.959 UTC [1340657] 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: 30363 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.08 s, elapsed: 0.33 s
2024-06-02 11:13:10.625 UTC [1340671] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 479114 remain, 29387 scanned (6.13% of total)
tuples: 0 removed, 48909285 remain, 0 are dead but not yet removable
removable cutoff: 489873, 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.021 ms
avg read rate: 0.000 MB/s, avg write rate: 0.031 MB/s
buffer usage: 58956 hits, 0 misses, 1 dirtied
WAL usage: 29387 records, 0 full page images, 1733962 bytes
system usage: CPU: user: 0.21 s, system: 0.00 s, elapsed: 0.24 s
2024-06-02 11:15:50.895 UTC [1341709] 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.27 s, system: 0.10 s, elapsed: 0.38 s
2024-06-02 11:17:00.374 UTC [1341771] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 503824 remain, 38751 scanned (7.69% of total)
tuples: 926892 removed, 49407013 remain, 431 are dead but not yet removable
removable cutoff: 558080, which was 13934 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 14040 pages from table (2.79% of total) had 1432769 dead item identifiers removed
index "pi1_pkey": pages: 141146 in total, 3926 newly deleted, 3926 currently deleted, 3926 reusable
index "pi1_marketsegment": pages: 192248 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 192217 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 263343 in total, 6208 newly deleted, 6208 currently deleted, 6208 reusable
I/O timings: read: 366.884 ms, write: 8.615 ms
avg read rate: 264.943 MB/s, avg write rate: 7.506 MB/s
buffer usage: 728287 hits, 246196 misses, 6975 dirtied
WAL usage: 481703 records, 0 full page images, 41357268 bytes
system usage: CPU: user: 4.64 s, system: 0.98 s, elapsed: 7.25 s
2024-06-02 11:18:13.817 UTC [1341868] 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: 30474 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.26 s, system: 0.11 s, elapsed: 0.39 s
2024-06-02 11:20:37.635 UTC [1341979] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 514579 remain, 49597 scanned (9.64% of total)
tuples: 1648201 removed, 48359272 remain, 899 are dead but not yet removable
removable cutoff: 659317, which was 16755 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24808 pages from table (4.82% of total) had 2531232 dead item identifiers removed
index "pi1_pkey": pages: 144148 in total, 6939 newly deleted, 6939 currently deleted, 6939 reusable
index "pi1_marketsegment": pages: 192888 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 193034 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 279930 in total, 12461 newly deleted, 12461 currently deleted, 12461 reusable
I/O timings: read: 429.900 ms, write: 5.264 ms
avg read rate: 206.148 MB/s, avg write rate: 11.470 MB/s
buffer usage: 875190 hits, 239988 misses, 13353 dirtied
WAL usage: 543070 records, 0 full page images, 57627072 bytes
system usage: CPU: user: 6.14 s, system: 1.11 s, elapsed: 9.09 s
2024-06-02 11:21:49.136 UTC [1342081] 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.13 s, elapsed: 0.38 s
2024-06-02 11:24:08.272 UTC [1342191] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518682 remain, 49530 scanned (9.55% of total)
tuples: 1643800 removed, 47712249 remain, 1305 are dead but not yet removable
removable cutoff: 760370, which was 16471 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24767 pages from table (4.77% of total) had 2526244 dead item identifiers removed
index "pi1_pkey": pages: 144148 in total, 6928 newly deleted, 7043 currently deleted, 7043 reusable
index "pi1_marketsegment": pages: 196413 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 197457 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 289903 in total, 12463 newly deleted, 12463 currently deleted, 12463 reusable
I/O timings: read: 405.594 ms, write: 5.089 ms
avg read rate: 196.969 MB/s, avg write rate: 11.556 MB/s
buffer usage: 906178 hits, 227296 misses, 13335 dirtied
WAL usage: 550808 records, 0 full page images, 57953358 bytes
system usage: CPU: user: 6.10 s, system: 1.11 s, elapsed: 9.01 s
2024-06-02 11:25:09.849 UTC [1342282] 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: 30474 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.14 s, elapsed: 0.38 s
2024-06-02 11:27:32.919 UTC [1342394] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518682 remain, 48391 scanned (9.33% of total)
tuples: 1605918 removed, 47704472 remain, 1498 are dead but not yet removable
removable cutoff: 859093, which was 16008 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24199 pages from table (4.67% of total) had 2468257 dead item identifiers removed
index "pi1_pkey": pages: 144148 in total, 6766 newly deleted, 7042 currently deleted, 7042 reusable
index "pi1_marketsegment": pages: 201521 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 203234 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 299543 in total, 11760 newly deleted, 11760 currently deleted, 11760 reusable
I/O timings: read: 373.992 ms, write: 5.090 ms
avg read rate: 189.668 MB/s, avg write rate: 11.107 MB/s
buffer usage: 929202 hits, 214375 misses, 12554 dirtied
WAL usage: 556758 records, 0 full page images, 57521227 bytes
system usage: CPU: user: 5.92 s, system: 1.08 s, elapsed: 8.83 s
2024-06-02 11:28:34.881 UTC [1342481] 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: 30476 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.26 s, system: 0.11 s, elapsed: 0.38 s
2024-06-02 11:31:36.540 UTC [1342628] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518682 remain, 51581 scanned (9.94% of total)
tuples: 1712916 removed, 47722728 remain, 1459 are dead but not yet removable
removable cutoff: 964330, which was 16140 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25792 pages from table (4.97% of total) had 2630789 dead item identifiers removed
index "pi1_pkey": pages: 144407 in total, 7214 newly deleted, 7214 currently deleted, 7214 reusable
index "pi1_marketsegment": pages: 209525 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 211755 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 311108 in total, 13160 newly deleted, 13160 currently deleted, 13160 reusable
I/O timings: read: 387.164 ms, write: 5.238 ms
avg read rate: 171.503 MB/s, avg write rate: 11.829 MB/s
buffer usage: 995252 hits, 202501 misses, 13967 dirtied
WAL usage: 585171 records, 0 full page images, 60944082 bytes
system usage: CPU: user: 6.30 s, system: 1.09 s, elapsed: 9.22 s
2024-06-02 11:32:27.711 UTC [1342702] 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 11:35:01.656 UTC [1342836] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519493 remain, 48481 scanned (9.33% of total)
tuples: 1610603 removed, 47790136 remain, 1378 are dead but not yet removable
removable cutoff: 1063237, which was 15673 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24243 pages from table (4.67% of total) had 2472681 dead item identifiers removed
index "pi1_pkey": pages: 144407 in total, 6779 newly deleted, 7300 currently deleted, 7300 reusable
index "pi1_marketsegment": pages: 215051 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 217569 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 319790 in total, 11962 newly deleted, 11962 currently deleted, 11962 reusable
I/O timings: read: 337.804 ms, write: 4.398 ms
avg read rate: 167.698 MB/s, avg write rate: 11.110 MB/s
buffer usage: 1006428 hits, 189229 misses, 12537 dirtied
WAL usage: 585132 records, 0 full page images, 59009033 bytes
system usage: CPU: user: 6.12 s, system: 0.94 s, elapsed: 8.81 s
2024-06-02 11:35:53.725 UTC [1342914] 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.23 s, system: 0.13 s, elapsed: 0.38 s
2024-06-02 11:37:39.041 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 11:38:42.656 UTC [1343055] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519493 remain, 50703 scanned (9.76% of total)
tuples: 1683887 removed, 47658135 remain, 1552 are dead but not yet removable
removable cutoff: 1166674, which was 16002 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25354 pages from table (4.88% of total) had 2585876 dead item identifiers removed
index "pi1_pkey": pages: 144407 in total, 7090 newly deleted, 7299 currently deleted, 7299 reusable
index "pi1_marketsegment": pages: 221878 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 224673 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 330930 in total, 12979 newly deleted, 12979 currently deleted, 12979 reusable
I/O timings: read: 336.745 ms, write: 4.955 ms
avg read rate: 152.216 MB/s, avg write rate: 11.807 MB/s
buffer usage: 1061837 hits, 177143 misses, 13741 dirtied
WAL usage: 607674 records, 0 full page images, 61530344 bytes
system usage: CPU: user: 6.35 s, system: 0.90 s, elapsed: 9.09 s
2024-06-02 11:39:26.554 UTC [1343118] 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.061 MB/s
buffer usage: 30469 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.25 s, system: 0.11 s, elapsed: 0.38 s
2024-06-02 11:42:38.499 UTC [1343281] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519493 remain, 52906 scanned (10.18% of total)
tuples: 1756748 removed, 47672510 remain, 3861 are dead but not yet removable
removable cutoff: 1274511, which was 16466 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 26467 pages from table (5.09% of total) had 2697191 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 7392 newly deleted, 7392 currently deleted, 7392 reusable
index "pi1_marketsegment": pages: 228887 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 231861 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 342063 in total, 13357 newly deleted, 13357 currently deleted, 13357 reusable
I/O timings: read: 321.000 ms, write: 4.954 ms
avg read rate: 130.884 MB/s, avg write rate: 45.050 MB/s
buffer usage: 1111632 hits, 164083 misses, 56477 dirtied
WAL usage: 628049 records, 0 full page images, 63875656 bytes
system usage: CPU: user: 6.42 s, system: 1.10 s, elapsed: 9.79 s
2024-06-02 11:43:09.085 UTC [1343341] 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.10 s, elapsed: 0.37 s
2024-06-02 11:45:52.954 UTC [1343488] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49202 scanned (9.46% of total)
tuples: 1632626 removed, 47817274 remain, 1431 are dead but not yet removable
removable cutoff: 1374888, which was 15776 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24604 pages from table (4.73% of total) had 2508130 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6881 newly deleted, 7474 currently deleted, 7474 reusable
index "pi1_marketsegment": pages: 233266 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 236339 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 350948 in total, 11945 newly deleted, 11945 currently deleted, 11945 reusable
I/O timings: read: 291.379 ms, write: 5.414 ms
avg read rate: 131.905 MB/s, avg write rate: 11.058 MB/s
buffer usage: 1115944 hits, 150694 misses, 12633 dirtied
WAL usage: 623672 records, 0 full page images, 61344636 bytes
system usage: CPU: user: 6.13 s, system: 0.97 s, elapsed: 8.92 s
2024-06-02 11:46:24.801 UTC [1343534] 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.10 s, elapsed: 0.37 s
2024-06-02 11:49:16.466 UTC [1343691] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 50344 scanned (9.68% of total)
tuples: 1669106 removed, 47733710 remain, 1453 are dead but not yet removable
removable cutoff: 1477601, which was 16172 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25176 pages from table (4.84% of total) had 2567778 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 7040 newly deleted, 7472 currently deleted, 7472 reusable
index "pi1_marketsegment": pages: 238079 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 241201 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 361969 in total, 13307 newly deleted, 13307 currently deleted, 13307 reusable
I/O timings: read: 285.575 ms, write: 5.261 ms
avg read rate: 118.867 MB/s, avg write rate: 12.012 MB/s
buffer usage: 1166163 hits, 138717 misses, 14018 dirtied
WAL usage: 640193 records, 0 full page images, 62959914 bytes
system usage: CPU: user: 6.32 s, system: 0.91 s, elapsed: 9.11 s
2024-06-02 11:49:47.819 UTC [1343746] 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.23 s, system: 0.11 s, elapsed: 0.36 s
2024-06-02 11:52:49.834 UTC [1343896] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 50161 scanned (9.64% of total)
tuples: 1663675 removed, 47788838 remain, 1544 are dead but not yet removable
removable cutoff: 1579934, which was 16497 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25082 pages from table (4.82% of total) had 2558209 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 7014 newly deleted, 7471 currently deleted, 7471 reusable
index "pi1_marketsegment": pages: 242505 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 245659 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 371510 in total, 12628 newly deleted, 12628 currently deleted, 12628 reusable
I/O timings: read: 263.264 ms, write: 5.559 ms
avg read rate: 104.652 MB/s, avg write rate: 27.447 MB/s
buffer usage: 1190486 hits, 125404 misses, 32889 dirtied
WAL usage: 646636 records, 0 full page images, 63122590 bytes
system usage: CPU: user: 6.40 s, system: 0.90 s, elapsed: 9.36 s
2024-06-02 11:53:20.840 UTC [1343950] 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.23 s, system: 0.11 s, elapsed: 0.36 s
2024-06-02 11:56:09.758 UTC [1344102] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49589 scanned (9.53% of total)
tuples: 1644704 removed, 47787379 remain, 1394 are dead but not yet removable
removable cutoff: 1681105, which was 15659 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24798 pages from table (4.77% of total) had 2529350 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6935 newly deleted, 7471 currently deleted, 7471 reusable
index "pi1_marketsegment": pages: 246186 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 249404 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 381266 in total, 12273 newly deleted, 12273 currently deleted, 12273 reusable
I/O timings: read: 242.373 ms, write: 4.496 ms
avg read rate: 98.213 MB/s, avg write rate: 25.812 MB/s
buffer usage: 1214831 hits, 112767 misses, 29637 dirtied
WAL usage: 651417 records, 0 full page images, 62998285 bytes
system usage: CPU: user: 6.34 s, system: 0.83 s, elapsed: 8.97 s
2024-06-02 11:56:41.253 UTC [1344154] 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 11:59:40.492 UTC [1344308] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 51337 scanned (9.87% of total)
tuples: 1702444 removed, 47654987 remain, 1397 are dead but not yet removable
removable cutoff: 1785844, which was 16123 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25672 pages from table (4.93% of total) had 2618497 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 7180 newly deleted, 7472 currently deleted, 7472 reusable
index "pi1_marketsegment": pages: 250278 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 253496 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 392437 in total, 12964 newly deleted, 12964 currently deleted, 12964 reusable
I/O timings: read: 229.014 ms, write: 5.635 ms
avg read rate: 84.539 MB/s, avg write rate: 23.676 MB/s
buffer usage: 1259435 hits, 100469 misses, 28138 dirtied
WAL usage: 666122 records, 0 full page images, 64823988 bytes
system usage: CPU: user: 6.34 s, system: 0.95 s, elapsed: 9.28 s
2024-06-02 12:00:05.075 UTC [1344364] 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.21 s, system: 0.12 s, elapsed: 0.35 s
2024-06-02 12:02:56.674 UTC [1344514] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 48933 scanned (9.41% of total)
tuples: 1624122 removed, 47709273 remain, 1477 are dead but not yet removable
removable cutoff: 1885675, which was 15542 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24469 pages from table (4.70% of total) had 2495620 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6843 newly deleted, 7471 currently deleted, 7471 reusable
index "pi1_marketsegment": pages: 253289 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 256459 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 401562 in total, 12617 newly deleted, 12617 currently deleted, 12617 reusable
I/O timings: read: 218.266 ms, write: 5.970 ms
avg read rate: 76.465 MB/s, avg write rate: 13.410 MB/s
buffer usage: 1275672 hits, 87513 misses, 15348 dirtied
WAL usage: 665088 records, 0 full page images, 63314597 bytes
system usage: CPU: user: 6.26 s, system: 0.81 s, elapsed: 8.94 s
2024-06-02 12:03:20.402 UTC [1344552] 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 12:04:45.933 UTC [1340363] LOG: checkpoint complete: wrote 1249077 buffers (41.4%); 0 WAL file(s) added, 0 removed, 1308 recycled; write=1620.047 s, sync=0.123 s, total=1626.893 s; sync files=345, longest=0.008 s, average=0.001 s; distance=21429735 kB, estimate=21429735 kB; lsn=7/9BEA7FE0, redo lsn=5/1E320298
2024-06-02 12:06:14.134 UTC [1344715] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49518 scanned (9.52% of total)
tuples: 1643158 removed, 47740869 remain, 1392 are dead but not yet removable
removable cutoff: 1986696, which was 14872 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24762 pages from table (4.76% of total) had 2525610 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6925 newly deleted, 7470 currently deleted, 7470 reusable
index "pi1_marketsegment": pages: 256445 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 259400 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 411330 in total, 11923 newly deleted, 11923 currently deleted, 11923 reusable
I/O timings: read: 198.849 ms, write: 6.301 ms
avg read rate: 66.657 MB/s, avg write rate: 11.298 MB/s
buffer usage: 1299276 hits, 74908 misses, 12696 dirtied
WAL usage: 670576 records, 0 full page images, 63887655 bytes
system usage: CPU: user: 6.17 s, system: 0.92 s, elapsed: 8.77 s
2024-06-02 12:06:35.818 UTC [1344753] 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 12:07:39.938 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 12:09:27.171 UTC [1344905] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 48888 scanned (9.40% of total)
tuples: 1620793 removed, 47747458 remain, 1533 are dead but not yet removable
removable cutoff: 2086427, which was 14363 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24447 pages from table (4.70% of total) had 2493134 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6837 newly deleted, 7471 currently deleted, 7471 reusable
index "pi1_marketsegment": pages: 259201 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 261986 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 421549 in total, 12580 newly deleted, 12580 currently deleted, 12556 reusable
I/O timings: read: 186.839 ms, write: 6.590 ms
avg read rate: 56.003 MB/s, avg write rate: 11.856 MB/s
buffer usage: 1330990 hits, 63071 misses, 13352 dirtied
WAL usage: 676324 records, 0 full page images, 63840519 bytes
system usage: CPU: user: 6.21 s, system: 0.88 s, elapsed: 8.79 s
2024-06-02 12:11:59.644 UTC [1345672] 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.065 MB/s
buffer usage: 30469 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.24 s, system: 0.09 s, elapsed: 0.35 s
2024-06-02 12:34:42.719 UTC [1340363] LOG: checkpoint complete: wrote 1008384 buffers (33.4%); 0 WAL file(s) added, 0 removed, 711 recycled; write=1619.076 s, sync=0.032 s, total=1622.782 s; sync files=22, longest=0.008 s, average=0.002 s; distance=11651454 kB, estimate=20451907 kB; lsn=8/4BF8EC58, redo lsn=7/E557FE38
2024-06-02 12:37:39.817 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 12:39:11.117 UTC [1347226] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49630 scanned (9.54% of total)
tuples: 2131824 removed, 47793969 remain, 535 are dead but not yet removable
removable cutoff: 2970182, which was 48191 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24816 pages from table (4.77% of total) had 2532168 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6944 newly deleted, 7483 currently deleted, 7483 reusable
index "pi1_marketsegment": pages: 261768 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 264282 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 429419 in total, 12613 newly deleted, 12637 currently deleted, 12637 reusable
I/O timings: read: 166.541 ms, write: 5.449 ms
avg read rate: 41.711 MB/s, avg write rate: 62.987 MB/s
buffer usage: 1359243 hits, 50592 misses, 76398 dirtied
WAL usage: 706906 records, 0 full page images, 66657033 bytes
system usage: CPU: user: 6.29 s, system: 0.85 s, elapsed: 9.47 s
2024-06-02 12:41:25.410 UTC [1347476] 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.065 MB/s
buffer usage: 30469 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.24 s, system: 0.10 s, elapsed: 0.35 s
2024-06-02 13:04:39.667 UTC [1340363] LOG: checkpoint complete: wrote 573917 buffers (19.0%); 0 WAL file(s) added, 0 removed, 107 recycled; write=1619.246 s, sync=0.027 s, total=1619.850 s; sync files=21, longest=0.007 s, average=0.002 s; distance=1759948 kB, estimate=18582711 kB; lsn=8/9EEFAD40, redo lsn=8/50C330D0
2024-06-02 13:07:39.765 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 13:07:49.413 UTC [1349013] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49101 scanned (9.44% of total)
tuples: 2189178 removed, 47766464 remain, 227 are dead but not yet removable
removable cutoff: 3972531, which was 48177 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24551 pages from table (4.72% of total) had 2505828 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6870 newly deleted, 7484 currently deleted, 7484 reusable
index "pi1_marketsegment": pages: 264104 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 266515 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 439186 in total, 12630 newly deleted, 12630 currently deleted, 12630 reusable
I/O timings: read: 145.149 ms, write: 5.020 ms
avg read rate: 32.620 MB/s, avg write rate: 46.188 MB/s
buffer usage: 1384363 hits, 38080 misses, 53920 dirtied
WAL usage: 686495 records, 0 full page images, 65620724 bytes
system usage: CPU: user: 6.10 s, system: 0.89 s, elapsed: 9.12 s
2024-06-02 13:09:30.764 UTC [1349233] 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.065 MB/s
buffer usage: 30469 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.23 s, system: 0.10 s, elapsed: 0.35 s
2024-06-02 13:34:39.510 UTC [1340363] LOG: checkpoint complete: wrote 592322 buffers (19.6%); 0 WAL file(s) added, 0 removed, 83 recycled; write=1619.246 s, sync=0.031 s, total=1619.745 s; sync files=22, longest=0.007 s, average=0.002 s; distance=1357223 kB, estimate=16860163 kB; lsn=8/F04C78D0, redo lsn=8/A399CE00
2024-06-02 13:36:56.651 UTC [1350908] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 48516 scanned (9.33% of total)
tuples: 2163187 removed, 47794163 remain, 235 are dead but not yet removable
removable cutoff: 4962958, which was 46311 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24257 pages from table (4.66% of total) had 2476052 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6789 newly deleted, 7484 currently deleted, 7484 reusable
index "pi1_marketsegment": pages: 266220 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 268659 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 448606 in total, 11932 newly deleted, 11932 currently deleted, 11932 reusable
I/O timings: read: 123.412 ms, write: 4.330 ms
avg read rate: 22.750 MB/s, avg write rate: 64.730 MB/s
buffer usage: 1401408 hits, 25576 misses, 72770 dirtied
WAL usage: 687174 records, 0 full page images, 65242100 bytes
system usage: CPU: user: 6.13 s, system: 0.77 s, elapsed: 8.78 s
2024-06-02 13:37:39.609 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 13:38:18.734 UTC [1351098] 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: 30469 hits, 0 misses, 5 dirtied
system usage: CPU: user: 0.26 s, system: 0.07 s, elapsed: 0.35 s
2024-06-02 14:04:39.621 UTC [1340363] LOG: checkpoint complete: wrote 641498 buffers (21.3%); 0 WAL file(s) added, 0 removed, 97 recycled; write=1619.454 s, sync=0.030 s, total=1620.012 s; sync files=21, longest=0.007 s, average=0.002 s; distance=1581900 kB, estimate=15332336 kB; lsn=9/41769978, redo lsn=9/4270180
2024-06-02 14:07:32.120 UTC [1352802] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49945 scanned (9.60% of total)
tuples: 2226290 removed, 47797989 remain, 294 are dead but not yet removable
removable cutoff: 5982545, which was 44380 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24973 pages from table (4.80% of total) had 2548986 dead item identifiers removed
index "pi1_pkey": pages: 144583 in total, 6989 newly deleted, 7484 currently deleted, 7484 reusable
index "pi1_marketsegment": pages: 268563 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 270861 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 459328 in total, 12665 newly deleted, 12665 currently deleted, 12665 reusable
I/O timings: read: 106.833 ms, write: 4.172 ms
avg read rate: 12.566 MB/s, avg write rate: 73.614 MB/s
buffer usage: 1440702 hits, 13825 misses, 80989 dirtied
WAL usage: 701237 records, 0 full page images, 66857571 bytes
system usage: CPU: user: 6.07 s, system: 0.87 s, elapsed: 8.59 s
2024-06-02 14:07:39.717 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 14:08:04.767 UTC [1340363] LOG: checkpoint complete: wrote 631060 buffers (20.9%); 0 WAL file(s) added, 75 removed, 0 recycled; write=24.061 s, sync=0.024 s, total=25.050 s; sync files=22, longest=0.007 s, average=0.002 s; distance=1228555 kB, estimate=13921958 kB; lsn=9/53DB68E8, redo lsn=9/4F232E20
2024-06-02 14:08:04.767 UTC [1340363] LOG: checkpoint starting: immediate force wait
2024-06-02 14:08:04.918 UTC [1340363] LOG: checkpoint complete: wrote 4657 buffers (0.2%); 0 WAL file(s) added, 4 removed, 0 recycled; write=0.068 s, sync=0.023 s, total=0.151 s; sync files=15, longest=0.007 s, average=0.002 s; distance=77326 kB, estimate=12537495 kB; lsn=9/53DB6998, redo lsn=9/53DB6960
2024-06-02 14:38:05.017 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 15:05:05.165 UTC [1340363] LOG: checkpoint complete: wrote 255417 buffers (8.5%); 0 WAL file(s) added, 5 removed, 0 recycled; write=1620.063 s, sync=0.012 s, total=1620.148 s; sync files=20, longest=0.006 s, average=0.001 s; distance=81641 kB, estimate=11291910 kB; lsn=9/5D9644A8, redo lsn=9/58D710D0
2024-06-02 15:08:05.261 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 15:35:05.164 UTC [1340363] LOG: checkpoint complete: wrote 219058 buffers (7.3%); 0 WAL file(s) added, 6 removed, 0 recycled; write=1619.806 s, sync=0.012 s, total=1619.904 s; sync files=19, longest=0.006 s, average=0.001 s; distance=86355 kB, estimate=10171354 kB; lsn=9/62DD2ED0, redo lsn=9/5E1C5CD8
2024-06-02 15:38:05.265 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 16:05:05.085 UTC [1340363] LOG: checkpoint complete: wrote 227455 buffers (7.5%); 0 WAL file(s) added, 5 removed, 0 recycled; write=1619.741 s, sync=0.021 s, total=1619.821 s; sync files=19, longest=0.011 s, average=0.002 s; distance=86544 kB, estimate=9162873 kB; lsn=9/6826A490, redo lsn=9/63649D98
2024-06-02 16:08:05.181 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 16:35:05.111 UTC [1340363] LOG: checkpoint complete: wrote 226743 buffers (7.5%); 0 WAL file(s) added, 5 removed, 0 recycled; write=1619.825 s, sync=0.029 s, total=1619.930 s; sync files=19, longest=0.007 s, average=0.002 s; distance=86600 kB, estimate=8255246 kB; lsn=9/7F36C928, redo lsn=9/68ADBFA0
2024-06-02 16:38:05.211 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 17:05:05.336 UTC [1340363] LOG: checkpoint complete: wrote 509669 buffers (16.9%); 0 WAL file(s) added, 25 removed, 0 recycled; write=1619.786 s, sync=0.030 s, total=1620.126 s; sync files=19, longest=0.007 s, average=0.002 s; distance=412916 kB, estimate=7471013 kB; lsn=9/9A2E3180, redo lsn=9/81E19268
2024-06-02 17:08:05.436 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 17:09:33.324 UTC [1586393] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 48982 scanned (9.42% of total)
tuples: 1619661 removed, 47771610 remain, 0 are dead but not yet removable
removable cutoff: 6200797, which was 280 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24490 pages from table (4.71% of total) had 2500050 dead item identifiers removed
index "pi1_pkey": pages: 144768 in total, 6854 newly deleted, 7665 currently deleted, 3613 reusable
index "pi1_marketsegment": pages: 270692 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 272762 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 470700 in total, 22068 newly deleted, 24240 currently deleted, 24240 reusable
I/O timings: read: 0.000 ms, write: 0.044 ms
avg read rate: 0.000 MB/s, avg write rate: 96.742 MB/s
buffer usage: 1564613 hits, 0 misses, 171177 dirtied
WAL usage: 749317 records, 0 full page images, 68129773 bytes
system usage: CPU: user: 10.58 s, system: 0.68 s, elapsed: 13.82 s
2024-06-02 17:09:33.570 UTC [1586393] 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.096 MB/s
buffer usage: 30404 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.22 s, system: 0.00 s, elapsed: 0.24 s
2024-06-02 17:35:05.448 UTC [1340363] LOG: checkpoint complete: wrote 467173 buffers (15.5%); 0 WAL file(s) added, 27 removed, 0 recycled; write=1619.648 s, sync=0.012 s, total=1620.012 s; sync files=20, longest=0.005 s, average=0.001 s; distance=443243 kB, estimate=6768236 kB; lsn=9/B8EC17A0, redo lsn=9/9CEF4058
2024-06-02 17:38:05.545 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 18:05:05.485 UTC [1340363] LOG: checkpoint complete: wrote 529362 buffers (17.6%); 0 WAL file(s) added, 31 removed, 0 recycled; write=1619.512 s, sync=0.033 s, total=1619.940 s; sync files=22, longest=0.007 s, average=0.002 s; distance=501810 kB, estimate=6141593 kB; lsn=9/D371D590, redo lsn=9/BB900B08
2024-06-02 18:08:05.581 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 18:21:56.439 UTC [1811927] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49324 scanned (9.48% of total)
tuples: 1614244 removed, 47913064 remain, 0 are dead but not yet removable
removable cutoff: 6301478, which was 520 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24657 pages from table (4.74% of total) had 2517000 dead item identifiers removed
index "pi1_pkey": pages: 148060 in total, 6901 newly deleted, 10953 currently deleted, 7950 reusable
index "pi1_marketsegment": pages: 272820 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 274752 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 470700 in total, 22468 newly deleted, 24201 currently deleted, 24201 reusable
I/O timings: read: 0.000 ms, write: 0.087 ms
avg read rate: 0.000 MB/s, avg write rate: 66.261 MB/s
buffer usage: 1581254 hits, 0 misses, 111298 dirtied
WAL usage: 737918 records, 0 full page images, 67857591 bytes
system usage: CPU: user: 10.46 s, system: 0.71 s, elapsed: 13.12 s
2024-06-02 18:21:56.684 UTC [1811927] 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.032 MB/s
buffer usage: 30404 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.22 s, system: 0.00 s, elapsed: 0.24 s
2024-06-02 18:35:05.301 UTC [1340363] LOG: checkpoint complete: wrote 458470 buffers (15.2%); 0 WAL file(s) added, 27 removed, 0 recycled; write=1619.422 s, sync=0.018 s, total=1619.721 s; sync files=20, longest=0.006 s, average=0.001 s; distance=435061 kB, estimate=5570940 kB; lsn=A/8CB0038, redo lsn=9/D61DE148
2024-06-02 18:38:05.401 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 19:03:48.109 UTC [1813892] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49257 scanned (9.47% of total)
tuples: 1629370 removed, 47780038 remain, 0 are dead but not yet removable
removable cutoff: 6402039, which was 346 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24626 pages from table (4.73% of total) had 2514000 dead item identifiers removed
index "pi1_pkey": pages: 148060 in total, 6893 newly deleted, 10959 currently deleted, 9006 reusable
index "pi1_marketsegment": pages: 274936 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 276709 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 470700 in total, 22378 newly deleted, 24225 currently deleted, 10360 reusable
I/O timings: read: 0.000 ms, write: 0.031 ms
avg read rate: 0.000 MB/s, avg write rate: 116.857 MB/s
buffer usage: 1565717 hits, 0 misses, 130204 dirtied
WAL usage: 745396 records, 0 full page images, 68177997 bytes
system usage: CPU: user: 5.76 s, system: 0.79 s, elapsed: 8.70 s
2024-06-02 19:03:48.356 UTC [1813892] 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.095 MB/s
buffer usage: 30404 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.22 s, system: 0.00 s, elapsed: 0.24 s
2024-06-02 19:05:05.752 UTC [1340363] LOG: checkpoint complete: wrote 618198 buffers (20.5%); 0 WAL file(s) added, 56 removed, 0 recycled; write=1619.631 s, sync=0.015 s, total=1620.351 s; sync files=23, longest=0.005 s, average=0.001 s; distance=916935 kB, estimate=5105539 kB; lsn=A/42A24F80, redo lsn=A/E14FD98
2024-06-02 19:08:05.801 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 19:35:05.660 UTC [1340363] LOG: checkpoint complete: wrote 637484 buffers (21.1%); 0 WAL file(s) added, 57 removed, 0 recycled; write=1619.195 s, sync=0.031 s, total=1619.859 s; sync files=22, longest=0.007 s, average=0.002 s; distance=948019 kB, estimate=4689787 kB; lsn=A/778913B0, redo lsn=A/47F1CA78
2024-06-02 19:38:05.757 UTC [1340363] LOG: checkpoint starting: time
2024-06-02 19:45:44.782 UTC [1946850] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 520210 remain, 49237 scanned (9.46% of total)
tuples: 1626093 removed, 47851487 remain, 671 are dead but not yet removable
removable cutoff: 6502520, which was 360 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24616 pages from table (4.73% of total) had 2512329 dead item identifiers removed
index "pi1_pkey": pages: 148060 in total, 6889 newly deleted, 10958 currently deleted, 10958 reusable
index "pi1_marketsegment": pages: 276935 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 278571 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 482767 in total, 22387 newly deleted, 36252 currently deleted, 27279 reusable
I/O timings: read: 0.000 ms, write: 0.056 ms
avg read rate: 0.000 MB/s, avg write rate: 112.264 MB/s
buffer usage: 1600708 hits, 0 misses, 126482 dirtied
WAL usage: 739605 records, 0 full page images, 67922769 bytes
system usage: CPU: user: 5.94 s, system: 0.74 s, elapsed: 8.80 s
2024-06-02 19:45:46.338 UTC [1947455] 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.065 MB/s
buffer usage: 30469 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.21 s, system: 0.12 s, elapsed: 0.35 s
2024-06-02 20:05:05.627 UTC [1340363] LOG: checkpoint complete: wrote 552946 buffers (18.3%); 0 WAL file(s) added, 53 removed, 0 recycled; write=1619.268 s, sync=0.024 s, total=1619.870 s; sync files=21, longest=0.006 s, average=0.002 s; distance=868675 kB, estimate=4307676 kB; lsn=A/B1200EB0, redo lsn=A/7CF6D6C0
2024-06-02 20:08:05.629 UTC [1340363] LOG: checkpoint starting: time
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment