Skip to content

Instantly share code, notes, and snippets.

@mdcallag
Created June 6, 2024 00:10
Show Gist options
  • Save mdcallag/3b532fab096a77c9411fe846a87cb9eb to your computer and use it in GitHub Desktop.
Save mdcallag/3b532fab096a77c9411fe846a87cb9eb to your computer and use it in GitHub Desktop.
logfile.fpw1.vic
2024-06-03 22:46:50.592 UTC [5952] 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-03 22:46:50.592 UTC [5952] LOG: listening on IPv4 address "127.0.0.1", port 5432
2024-06-03 22:46:50.612 UTC [5952] LOG: listening on Unix socket "/tmp/.s.PGSQL.5432"
2024-06-03 22:46:50.627 UTC [5956] LOG: database system was shut down at 2024-06-03 22:46:49 UTC
2024-06-03 22:46:50.637 UTC [5952] LOG: database system is ready to accept connections
2024-06-03 22:47:06.065 UTC [5954] LOG: checkpoint starting: immediate force wait
2024-06-03 22:47:06.358 UTC [5954] LOG: checkpoint complete: wrote 1888 buffers (0.1%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.070 s, sync=0.198 s, total=0.293 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-03 22:47:07.322 UTC [5990] LOG: automatic analyze of table "template1.pg_catalog.pg_type"
I/O timings: read: 0.024 ms, write: 0.027 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-03 22:47:07.322 UTC [5990] 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: 110.035 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-03 22:47:07.331 UTC [5990] 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-03 22:47:07.332 UTC [5990] 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-03 22:47:07.334 UTC [5990] LOG: automatic analyze of table "template1.pg_catalog.pg_class"
I/O timings: read: 0.000 ms, write: 0.010 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-03 22:47:07.335 UTC [5990] LOG: automatic analyze of table "template1.pg_catalog.pg_constraint"
I/O timings: read: 0.000 ms, write: 0.025 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-03 22:47:07.335 UTC [5990] 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-03 22:47:07.335 UTC [5990] 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: 504.032 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-03 22:47:07.339 UTC [5990] 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-03 22:47:07.340 UTC [5990] 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: 199.751 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-03 22:47:07.342 UTC [5990] 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-03 22:47:07.342 UTC [5990] 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-03 22:47:07.343 UTC [5990] 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-03 22:47:07.344 UTC [5990] 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-03 22:47:07.345 UTC [5990] 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-03 22:47:14.016 UTC [6010] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 3123 remain, 3123 scanned (100.00% of total)
tuples: 0 removed, 318850 remain, 0 are dead but not yet removable
removable cutoff: 3939, which was 37 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.013 ms
avg read rate: 0.000 MB/s, avg write rate: 0.904 MB/s
buffer usage: 6282 hits, 0 misses, 3 dirtied
WAL usage: 3122 records, 3 full page images, 208937 bytes
system usage: CPU: user: 0.01 s, system: 0.00 s, elapsed: 0.02 s
2024-06-03 22:47:14.161 UTC [6010] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.022 ms
avg read rate: 0.000 MB/s, avg write rate: 0.054 MB/s
buffer usage: 3519 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.13 s, system: 0.00 s, elapsed: 0.14 s
2024-06-03 22:47:18.994 UTC [6012] 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: 10933, which was 0 XIDs old when operation ended
new relfrozenxid: 10933, which is 10211 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: 43.768 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-03 22:47:24.104 UTC [6015] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 16782 remain, 13663 scanned (81.41% of total)
tuples: 0 removed, 1713217 remain, 0 are dead but not yet removable
removable cutoff: 17884, which was 155 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: 27367 hits, 0 misses, 0 dirtied
WAL usage: 13663 records, 0 full page images, 806295 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:47:24.299 UTC [6015] 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.040 MB/s
buffer usage: 17301 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.18 s, system: 0.00 s, elapsed: 0.19 s
2024-06-03 22:47:34.106 UTC [6019] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 30443 remain, 13662 scanned (44.88% of total)
tuples: 0 removed, 3107946 remain, 0 are dead but not yet removable
removable cutoff: 31831, which was 155 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: 27368 hits, 0 misses, 0 dirtied
WAL usage: 13662 records, 0 full page images, 806236 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:47:34.346 UTC [6019] 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.01 s, elapsed: 0.23 s
2024-06-03 22:47:44.109 UTC [6029] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 44102 remain, 13660 scanned (30.97% of total)
tuples: 0 removed, 4502332 remain, 0 are dead but not yet removable
removable cutoff: 45776, which was 157 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.070 MB/s
buffer usage: 27369 hits, 0 misses, 1 dirtied
WAL usage: 13660 records, 1 full page images, 814315 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:47:44.362 UTC [6029] 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.00 s, elapsed: 0.25 s
2024-06-03 22:47:54.110 UTC [6033] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 57726 remain, 13625 scanned (23.60% of total)
tuples: 0 removed, 5893131 remain, 0 are dead but not yet removable
removable cutoff: 59685, which was 155 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: 27303 hits, 0 misses, 0 dirtied
WAL usage: 13625 records, 0 full page images, 804053 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:47:54.368 UTC [6033] 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.01 s, elapsed: 0.25 s
2024-06-03 22:48:04.210 UTC [6037] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 71498 remain, 13773 scanned (19.26% of total)
tuples: 0 removed, 7298900 remain, 0 are dead but not yet removable
removable cutoff: 73745, which was 153 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.069 MB/s
buffer usage: 27604 hits, 0 misses, 1 dirtied
WAL usage: 13773 records, 1 full page images, 820982 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:48:04.474 UTC [6037] 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.02 s, elapsed: 0.26 s
2024-06-03 22:48:14.310 UTC [6047] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 85264 remain, 13767 scanned (16.15% of total)
tuples: 0 removed, 8703890 remain, 0 are dead but not yet removable
removable cutoff: 87798, which was 156 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: 27595 hits, 0 misses, 0 dirtied
WAL usage: 13767 records, 0 full page images, 812431 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:48:14.580 UTC [6047] 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.02 s, elapsed: 0.26 s
2024-06-03 22:48:24.311 UTC [6051] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 98922 remain, 13660 scanned (13.81% of total)
tuples: 0 removed, 10098473 remain, 0 are dead but not yet removable
removable cutoff: 101742, which was 156 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.069 MB/s
buffer usage: 27387 hits, 0 misses, 1 dirtied
WAL usage: 13660 records, 1 full page images, 814315 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:48:24.589 UTC [6051] 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.028 MB/s
buffer usage: 30363 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.22 s, system: 0.03 s, elapsed: 0.27 s
2024-06-03 22:48:34.313 UTC [6055] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 112569 remain, 13649 scanned (12.13% of total)
tuples: 0 removed, 11491820 remain, 0 are dead but not yet removable
removable cutoff: 115674, which was 158 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: 27370 hits, 0 misses, 0 dirtied
WAL usage: 13649 records, 0 full page images, 805469 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:48:34.597 UTC [6055] 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.21 s, system: 0.05 s, elapsed: 0.28 s
2024-06-03 22:48:44.315 UTC [6079] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 126237 remain, 13670 scanned (10.83% of total)
tuples: 0 removed, 12886709 remain, 0 are dead but not yet removable
removable cutoff: 129627, which was 158 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: 27414 hits, 0 misses, 0 dirtied
WAL usage: 13670 records, 0 full page images, 806708 bytes
system usage: CPU: user: 0.07 s, system: 0.02 s, elapsed: 0.11 s
2024-06-03 22:48:44.602 UTC [6079] 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.22 s, system: 0.04 s, elapsed: 0.28 s
2024-06-03 22:48:54.318 UTC [6087] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 139863 remain, 13627 scanned (9.74% of total)
tuples: 0 removed, 14277405 remain, 0 are dead but not yet removable
removable cutoff: 143538, which was 157 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.069 MB/s
buffer usage: 27333 hits, 0 misses, 1 dirtied
WAL usage: 13627 records, 1 full page images, 812368 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:48:54.609 UTC [6087] 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.20 s, system: 0.06 s, elapsed: 0.29 s
2024-06-03 22:49:04.319 UTC [6093] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 153508 remain, 13646 scanned (8.89% of total)
tuples: 0 removed, 15670280 remain, 0 are dead but not yet removable
removable cutoff: 157469, which was 158 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: 27374 hits, 0 misses, 0 dirtied
WAL usage: 13646 records, 0 full page images, 805292 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:49:14.420 UTC [6103] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 167264 remain, 13757 scanned (8.22% of total)
tuples: 0 removed, 17074574 remain, 0 are dead but not yet removable
removable cutoff: 171512, which was 157 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.068 MB/s
buffer usage: 27602 hits, 0 misses, 1 dirtied
WAL usage: 13757 records, 1 full page images, 820038 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:49:14.717 UTC [6103] 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.24 s, system: 0.03 s, elapsed: 0.29 s
2024-06-03 22:49:24.521 UTC [6107] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 181034 remain, 13771 scanned (7.61% of total)
tuples: 0 removed, 18480660 remain, 0 are dead but not yet removable
removable cutoff: 185569, which was 158 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: 27633 hits, 0 misses, 0 dirtied
WAL usage: 13770 records, 0 full page images, 812608 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:49:34.621 UTC [6111] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 194801 remain, 13770 scanned (7.07% of total)
tuples: 0 removed, 19886055 remain, 0 are dead but not yet removable
removable cutoff: 199625, which was 160 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: 27634 hits, 0 misses, 0 dirtied
WAL usage: 13770 records, 0 full page images, 812608 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:49:34.923 UTC [6111] 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.06 s, elapsed: 0.30 s
2024-06-03 22:49:44.622 UTC [6121] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 208440 remain, 13641 scanned (6.54% of total)
tuples: 0 removed, 21278897 remain, 0 are dead but not yet removable
removable cutoff: 213550, which was 158 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.068 MB/s
buffer usage: 27382 hits, 0 misses, 1 dirtied
WAL usage: 13641 records, 1 full page images, 813194 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:49:54.623 UTC [6125] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 222060 remain, 13622 scanned (6.13% of total)
tuples: 0 removed, 22669231 remain, 0 are dead but not yet removable
removable cutoff: 227454, which was 158 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: 27349 hits, 0 misses, 0 dirtied
WAL usage: 13622 records, 0 full page images, 803876 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:49:54.931 UTC [6125] 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.25 s, system: 0.04 s, elapsed: 0.30 s
2024-06-03 22:50:04.624 UTC [6129] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 235683 remain, 13625 scanned (5.78% of total)
tuples: 0 removed, 24059307 remain, 0 are dead but not yet removable
removable cutoff: 241361, which was 159 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.068 MB/s
buffer usage: 27358 hits, 0 misses, 1 dirtied
WAL usage: 13625 records, 1 full page images, 812250 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-03 22:50:14.624 UTC [6139] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 249299 remain, 13617 scanned (5.46% of total)
tuples: 0 removed, 25449292 remain, 0 are dead but not yet removable
removable cutoff: 255260, which was 160 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: 27346 hits, 0 misses, 0 dirtied
WAL usage: 13616 records, 0 full page images, 803522 bytes
system usage: CPU: user: 0.06 s, system: 0.03 s, elapsed: 0.11 s
2024-06-03 22:50:14.933 UTC [6139] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.020 ms
avg read rate: 0.000 MB/s, avg write rate: 0.025 MB/s
buffer usage: 30363 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.21 s, system: 0.07 s, elapsed: 0.30 s
2024-06-03 22:50:24.626 UTC [6143] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 262924 remain, 13627 scanned (5.18% of total)
tuples: 0 removed, 26840853 remain, 0 are dead but not yet removable
removable cutoff: 269170, which was 160 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.057 ms
avg read rate: 0.000 MB/s, avg write rate: 0.068 MB/s
buffer usage: 27371 hits, 0 misses, 1 dirtied
WAL usage: 13627 records, 1 full page images, 812368 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:50:34.627 UTC [6147] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 276567 remain, 13644 scanned (4.93% of total)
tuples: 0 removed, 28233567 remain, 0 are dead but not yet removable
removable cutoff: 283097, which was 159 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: 27408 hits, 0 misses, 0 dirtied
WAL usage: 13644 records, 0 full page images, 805174 bytes
system usage: CPU: user: 0.09 s, system: 0.01 s, elapsed: 0.11 s
2024-06-03 22:50:44.836 UTC [6157] 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.06 s, elapsed: 0.32 s
2024-06-03 22:50:54.841 UTC [6161] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 303982 remain, 27416 scanned (9.02% of total)
tuples: 0 removed, 31031249 remain, 0 are dead but not yet removable
removable cutoff: 311083, which was 316 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.034 MB/s
buffer usage: 54961 hits, 0 misses, 1 dirtied
WAL usage: 27416 records, 1 full page images, 1625919 bytes
system usage: CPU: user: 0.18 s, system: 0.01 s, elapsed: 0.22 s
2024-06-03 22:51:15.044 UTC [6175] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 331497 remain, 27516 scanned (8.30% of total)
tuples: 0 removed, 33840120 remain, 0 are dead but not yet removable
removable cutoff: 339172, which was 321 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.020 ms
avg read rate: 0.000 MB/s, avg write rate: 0.034 MB/s
buffer usage: 55170 hits, 0 misses, 1 dirtied
WAL usage: 27516 records, 1 full page images, 1631819 bytes
system usage: CPU: user: 0.18 s, system: 0.01 s, elapsed: 0.23 s
2024-06-03 22:51:15.390 UTC [6175] 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.23 s, system: 0.06 s, elapsed: 0.31 s
2024-06-03 22:51:35.245 UTC [6183] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 359003 remain, 27507 scanned (7.66% of total)
tuples: 0 removed, 36648784 remain, 0 are dead but not yet removable
removable cutoff: 367250, which was 320 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: 55159 hits, 0 misses, 0 dirtied
WAL usage: 27506 records, 0 full page images, 1623032 bytes
system usage: CPU: user: 0.18 s, system: 0.01 s, elapsed: 0.23 s
2024-06-03 22:51:45.343 UTC [6193] 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.21 s, system: 0.09 s, elapsed: 0.32 s
2024-06-03 22:51:55.245 UTC [6198] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 386219 remain, 27218 scanned (7.05% of total)
tuples: 0 removed, 39427284 remain, 0 are dead but not yet removable
removable cutoff: 395035, which was 316 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.034 MB/s
buffer usage: 54589 hits, 0 misses, 1 dirtied
WAL usage: 27217 records, 1 full page images, 1614178 bytes
system usage: CPU: user: 0.17 s, system: 0.02 s, elapsed: 0.22 s
2024-06-03 22:52:15.252 UTC [6212] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 413444 remain, 27227 scanned (6.59% of total)
tuples: 0 removed, 42206720 remain, 0 are dead but not yet removable
removable cutoff: 422830, which was 318 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.020 ms
avg read rate: 0.000 MB/s, avg write rate: 0.034 MB/s
buffer usage: 54616 hits, 0 misses, 1 dirtied
WAL usage: 27227 records, 1 full page images, 1614768 bytes
system usage: CPU: user: 0.17 s, system: 0.02 s, elapsed: 0.23 s
2024-06-03 22:52:15.574 UTC [6212] 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: 30362 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.06 s, elapsed: 0.32 s
2024-06-03 22:52:35.252 UTC [6220] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 440662 remain, 27219 scanned (6.18% of total)
tuples: 0 removed, 44985244 remain, 0 are dead but not yet removable
removable cutoff: 450616, which was 319 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.034 MB/s
buffer usage: 54609 hits, 0 misses, 1 dirtied
WAL usage: 27219 records, 1 full page images, 1614296 bytes
system usage: CPU: user: 0.18 s, system: 0.01 s, elapsed: 0.23 s
2024-06-03 22:52:55.454 UTC [6234] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 468093 remain, 27432 scanned (5.86% of total)
tuples: 0 removed, 47785542 remain, 0 are dead but not yet removable
removable cutoff: 478619, which was 324 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.034 MB/s
buffer usage: 55044 hits, 0 misses, 1 dirtied
WAL usage: 27432 records, 1 full page images, 1626863 bytes
system usage: CPU: user: 0.18 s, system: 0.02 s, elapsed: 0.23 s
2024-06-03 22:52:55.781 UTC [6234] 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: 30364 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.24 s, system: 0.06 s, elapsed: 0.32 s
2024-06-03 22:55:43.671 UTC [7244] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 492890 remain, 27895 scanned (5.66% of total)
tuples: 201536 removed, 50000460 remain, 222 are dead but not yet removable
removable cutoff: 513414, which was 13511 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 3097 pages from table (0.63% of total) had 315975 dead item identifiers removed
index "pi1_pkey": pages: 138054 in total, 865 newly deleted, 865 currently deleted, 865 reusable
index "pi1_marketsegment": pages: 192248 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 192219 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 253477 in total, 718 newly deleted, 718 currently deleted, 718 reusable
I/O timings: read: 372.599 ms, write: 165.354 ms
avg read rate: 262.409 MB/s, avg write rate: 28.538 MB/s
buffer usage: 566808 hits, 282464 misses, 30719 dirtied
WAL usage: 350904 records, 67 full page images, 22025438 bytes
system usage: CPU: user: 3.21 s, system: 1.37 s, elapsed: 8.40 s
2024-06-03 22:56:16.052 UTC [7287] 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.39 s
2024-06-03 22:59:45.277 UTC [7440] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 514671 remain, 49760 scanned (9.67% of total)
tuples: 1652960 removed, 49031484 remain, 567 are dead but not yet removable
removable cutoff: 614995, which was 15079 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24885 pages from table (4.84% of total) had 2539558 dead item identifiers removed
index "pi1_pkey": pages: 144163 in total, 6963 newly deleted, 6963 currently deleted, 6963 reusable
index "pi1_marketsegment": pages: 192314 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 192356 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 275352 in total, 12889 newly deleted, 12889 currently deleted, 12889 reusable
I/O timings: read: 437.005 ms, write: 8.905 ms
avg read rate: 206.813 MB/s, avg write rate: 11.546 MB/s
buffer usage: 868422 hits, 245565 misses, 13710 dirtied
WAL usage: 543422 records, 1 full page images, 57780166 bytes
system usage: CPU: user: 6.49 s, system: 0.97 s, elapsed: 9.27 s
2024-06-03 23:00:16.495 UTC [7484] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.033 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.22 s, system: 0.14 s, elapsed: 0.38 s
2024-06-03 23:03:45.998 UTC [7635] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518363 remain, 49379 scanned (9.53% of total)
tuples: 1640050 removed, 47812366 remain, 1168 are dead but not yet removable
removable cutoff: 715742, which was 15008 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24690 pages from table (4.76% of total) had 2518949 dead item identifiers removed
index "pi1_pkey": pages: 144163 in total, 6905 newly deleted, 7058 currently deleted, 7058 reusable
index "pi1_marketsegment": pages: 194358 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 194896 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 284789 in total, 12253 newly deleted, 12253 currently deleted, 12253 reusable
I/O timings: read: 416.038 ms, write: 8.238 ms
avg read rate: 198.173 MB/s, avg write rate: 11.150 MB/s
buffer usage: 888451 hits, 232476 misses, 13080 dirtied
WAL usage: 545132 records, 2 full page images, 57587485 bytes
system usage: CPU: user: 6.18 s, system: 1.16 s, elapsed: 9.16 s
2024-06-03 23:04:17.226 UTC [7678] 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.24 s, system: 0.12 s, elapsed: 0.38 s
2024-06-03 23:07:56.461 UTC [7839] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518363 remain, 50300 scanned (9.70% of total)
tuples: 1671575 removed, 47663132 remain, 1319 are dead but not yet removable
removable cutoff: 818371, which was 15117 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25152 pages from table (4.85% of total) had 2565749 dead item identifiers removed
index "pi1_pkey": pages: 144222 in total, 7035 newly deleted, 7058 currently deleted, 7058 reusable
index "pi1_marketsegment": pages: 199719 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 201086 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 295674 in total, 12239 newly deleted, 12239 currently deleted, 12239 reusable
I/O timings: read: 402.570 ms, write: 8.150 ms
avg read rate: 184.839 MB/s, avg write rate: 10.962 MB/s
buffer usage: 927315 hits, 219743 misses, 13032 dirtied
WAL usage: 558873 records, 2 full page images, 58822321 bytes
system usage: CPU: user: 6.37 s, system: 1.07 s, elapsed: 9.28 s
2024-06-03 23:08:17.858 UTC [7875] 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.22 s, system: 0.14 s, elapsed: 0.38 s
2024-06-03 23:11:46.631 UTC [8030] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518617 remain, 48923 scanned (9.43% of total)
tuples: 1625480 removed, 47776636 remain, 1299 are dead but not yet removable
removable cutoff: 918184, which was 14510 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24465 pages from table (4.72% of total) had 2495320 dead item identifiers removed
index "pi1_pkey": pages: 144223 in total, 6842 newly deleted, 7118 currently deleted, 7118 reusable
index "pi1_marketsegment": pages: 205638 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 207458 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 305388 in total, 12264 newly deleted, 12264 currently deleted, 12264 reusable
I/O timings: read: 376.157 ms, write: 7.365 ms
avg read rate: 179.314 MB/s, avg write rate: 11.251 MB/s
buffer usage: 957762 hits, 207350 misses, 13010 dirtied
WAL usage: 567833 records, 2 full page images, 58448638 bytes
system usage: CPU: user: 6.17 s, system: 1.05 s, elapsed: 9.03 s
2024-06-03 23:12:08.283 UTC [8067] 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.20 s, system: 0.16 s, elapsed: 0.38 s
2024-06-03 23:15:42.193 UTC [8226] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518617 remain, 50282 scanned (9.70% of total)
tuples: 1669721 removed, 47693163 remain, 1303 are dead but not yet removable
removable cutoff: 1020771, which was 14370 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25145 pages from table (4.85% of total) had 2564646 dead item identifiers removed
index "pi1_pkey": pages: 144223 in total, 7031 newly deleted, 7117 currently deleted, 7117 reusable
index "pi1_marketsegment": pages: 212588 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 214803 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 315850 in total, 12945 newly deleted, 12945 currently deleted, 12945 reusable
I/O timings: read: 358.368 ms, write: 5.816 ms
avg read rate: 168.716 MB/s, avg write rate: 11.679 MB/s
buffer usage: 1006977 hits, 194979 misses, 13497 dirtied
WAL usage: 587243 records, 1 full page images, 60266743 bytes
system usage: CPU: user: 6.35 s, system: 0.96 s, elapsed: 9.02 s
2024-06-03 23:16:06.055 UTC [8263] 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.23 s, system: 0.14 s, elapsed: 0.38 s
2024-06-03 23:17:06.489 UTC [5954] LOG: checkpoint starting: time
2024-06-03 23:19:46.635 UTC [8426] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518617 remain, 50699 scanned (9.78% of total)
tuples: 1681579 removed, 47775464 remain, 3165 are dead but not yet removable
removable cutoff: 1124118, which was 15557 XIDs old when operation ended
frozen: 13336 pages from table (2.57% of total) had 1361310 tuples frozen
index scan needed: 25358 pages from table (4.89% of total) had 2584738 dead item identifiers removed
index "pi1_pkey": pages: 144272 in total, 7085 newly deleted, 7109 currently deleted, 7109 reusable
index "pi1_marketsegment": pages: 219229 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 221818 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 326157 in total, 12260 newly deleted, 12260 currently deleted, 12260 reusable
I/O timings: read: 357.592 ms, write: 8.577 ms
avg read rate: 140.087 MB/s, avg write rate: 35.193 MB/s
buffer usage: 1039177 hits, 181922 misses, 45703 dirtied
WAL usage: 626595 records, 51864 full page images, 343849987 bytes
system usage: CPU: user: 6.65 s, system: 1.06 s, elapsed: 10.14 s
2024-06-03 23:20:06.868 UTC [8463] 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.084 MB/s
buffer usage: 30469 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.27 s, system: 0.08 s, elapsed: 0.37 s
2024-06-03 23:23:46.527 UTC [8623] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518897 remain, 50061 scanned (9.65% of total)
tuples: 1662869 removed, 47788389 remain, 1193 are dead but not yet removable
removable cutoff: 1226257, which was 15325 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25033 pages from table (4.82% of total) had 2552472 dead item identifiers removed
index "pi1_pkey": pages: 144272 in total, 7000 newly deleted, 7165 currently deleted, 7165 reusable
index "pi1_marketsegment": pages: 225084 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 227757 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 336576 in total, 12390 newly deleted, 12390 currently deleted, 12390 reusable
I/O timings: read: 338.360 ms, write: 7.637 ms
avg read rate: 139.299 MB/s, avg write rate: 10.717 MB/s
buffer usage: 1072803 hits, 169552 misses, 13045 dirtied
WAL usage: 610419 records, 19397 full page images, 201392411 bytes
system usage: CPU: user: 6.47 s, system: 0.98 s, elapsed: 9.50 s
2024-06-03 23:24:07.493 UTC [8659] 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.10 s, elapsed: 0.37 s
2024-06-03 23:27:37.116 UTC [8819] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518897 remain, 49685 scanned (9.58% of total)
tuples: 1649076 removed, 47718539 remain, 1316 are dead but not yet removable
removable cutoff: 1327632, which was 15449 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24845 pages from table (4.79% of total) had 2534177 dead item identifiers removed
index "pi1_pkey": pages: 144272 in total, 6950 newly deleted, 7165 currently deleted, 7165 reusable
index "pi1_marketsegment": pages: 230343 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 233151 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 346819 in total, 13053 newly deleted, 13053 currently deleted, 13053 reusable
I/O timings: read: 331.869 ms, write: 8.017 ms
avg read rate: 126.984 MB/s, avg write rate: 11.103 MB/s
buffer usage: 1111381 hits, 157116 misses, 13737 dirtied
WAL usage: 622099 records, 20049 full page images, 209126537 bytes
system usage: CPU: user: 6.59 s, system: 0.99 s, elapsed: 9.66 s
2024-06-03 23:27:58.008 UTC [8855] 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-03 23:31:42.737 UTC [9016] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518897 remain, 51095 scanned (9.85% of total)
tuples: 1697635 removed, 47798289 remain, 1232 are dead but not yet removable
removable cutoff: 1431883, which was 15742 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25550 pages from table (4.92% of total) had 2606363 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 7146 newly deleted, 7163 currently deleted, 7163 reusable
index "pi1_marketsegment": pages: 235857 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 238722 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 357088 in total, 12422 newly deleted, 12422 currently deleted, 12422 reusable
I/O timings: read: 310.171 ms, write: 7.497 ms
avg read rate: 113.972 MB/s, avg write rate: 14.531 MB/s
buffer usage: 1144478 hits, 144029 misses, 18363 dirtied
WAL usage: 634664 records, 17675 full page images, 190738725 bytes
system usage: CPU: user: 6.73 s, system: 0.99 s, elapsed: 9.87 s
2024-06-03 23:31:53.329 UTC [9038] 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.10 s, elapsed: 0.36 s
2024-06-03 23:35:25.173 UTC [9198] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 48920 scanned (9.42% of total)
tuples: 1621439 removed, 47717246 remain, 1359 are dead but not yet removable
removable cutoff: 1531688, which was 15340 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24462 pages from table (4.71% of total) had 2494944 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6841 newly deleted, 7198 currently deleted, 7198 reusable
index "pi1_marketsegment": pages: 239862 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 242824 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 366773 in total, 12379 newly deleted, 12379 currently deleted, 12379 reusable
I/O timings: read: 278.605 ms, write: 7.538 ms
avg read rate: 106.153 MB/s, avg write rate: 26.374 MB/s
buffer usage: 1166732 hits, 131599 misses, 32696 dirtied
WAL usage: 637302 records, 12415 full page images, 150809887 bytes
system usage: CPU: user: 6.41 s, system: 1.00 s, elapsed: 9.68 s
2024-06-03 23:35:35.849 UTC [9226] 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.35 s
2024-06-03 23:39:08.613 UTC [9386] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 48997 scanned (9.44% of total)
tuples: 1624496 removed, 47849933 remain, 1288 are dead but not yet removable
removable cutoff: 1631651, which was 15486 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24501 pages from table (4.72% of total) had 2499171 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6852 newly deleted, 7198 currently deleted, 7198 reusable
index "pi1_marketsegment": pages: 243704 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 246735 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 376708 in total, 12406 newly deleted, 12406 currently deleted, 12406 reusable
I/O timings: read: 252.910 ms, write: 7.508 ms
avg read rate: 97.038 MB/s, avg write rate: 23.942 MB/s
buffer usage: 1197360 hits, 119213 misses, 29413 dirtied
WAL usage: 645339 records, 12453 full page images, 151693203 bytes
system usage: CPU: user: 6.46 s, system: 0.91 s, elapsed: 9.59 s
2024-06-03 23:39:19.474 UTC [9408] 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.10 s, elapsed: 0.35 s
2024-06-03 23:43:01.374 UTC [9572] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 49570 scanned (9.55% of total)
tuples: 1644789 removed, 47832184 remain, 1320 are dead but not yet removable
removable cutoff: 1732786, which was 14752 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24788 pages from table (4.77% of total) had 2528268 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6933 newly deleted, 7198 currently deleted, 7198 reusable
index "pi1_marketsegment": pages: 247463 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 250478 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 386726 in total, 12441 newly deleted, 12441 currently deleted, 12441 reusable
I/O timings: read: 234.696 ms, write: 7.408 ms
avg read rate: 88.424 MB/s, avg write rate: 12.282 MB/s
buffer usage: 1229646 hits, 106798 misses, 14834 dirtied
WAL usage: 654283 records, 12430 full page images, 152055310 bytes
system usage: CPU: user: 6.40 s, system: 0.96 s, elapsed: 9.43 s
2024-06-03 23:43:12.400 UTC [9599] 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-03 23:44:12.924 UTC [5954] LOG: checkpoint complete: wrote 1225909 buffers (40.7%); 0 WAL file(s) added, 0 removed, 1252 recycled; write=1619.530 s, sync=0.120 s, total=1626.435 s; sync files=344, longest=0.007 s, average=0.001 s; distance=20518775 kB, estimate=20518775 kB; lsn=8/5AD839B8, redo lsn=4/E69C19D8
2024-06-03 23:46:54.155 UTC [9769] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 50111 scanned (9.65% of total)
tuples: 1661441 removed, 47693025 remain, 1338 are dead but not yet removable
removable cutoff: 1835025, which was 14224 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25058 pages from table (4.83% of total) had 2555982 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 7007 newly deleted, 7197 currently deleted, 7197 reusable
index "pi1_marketsegment": pages: 251106 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 254025 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 396926 in total, 13033 newly deleted, 13033 currently deleted, 13033 reusable
I/O timings: read: 222.704 ms, write: 7.907 ms
avg read rate: 80.179 MB/s, avg write rate: 11.715 MB/s
buffer usage: 1267258 hits, 94349 misses, 13785 dirtied
WAL usage: 664611 records, 13159 full page images, 160049448 bytes
system usage: CPU: user: 6.46 s, system: 1.01 s, elapsed: 9.19 s
2024-06-03 23:47:05.418 UTC [9796] 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.35 s
2024-06-03 23:47:06.963 UTC [5954] LOG: checkpoint starting: time
2024-06-03 23:50:34.263 UTC [9949] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 48249 scanned (9.29% of total)
tuples: 1600515 removed, 47614691 remain, 1361 are dead but not yet removable
removable cutoff: 1933458, which was 14083 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24127 pages from table (4.65% of total) had 2460877 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6748 newly deleted, 7196 currently deleted, 7196 reusable
index "pi1_marketsegment": pages: 254021 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 256926 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 405809 in total, 12392 newly deleted, 12392 currently deleted, 12392 reusable
I/O timings: read: 200.051 ms, write: 7.671 ms
avg read rate: 70.817 MB/s, avg write rate: 11.345 MB/s
buffer usage: 1282144 hits, 81307 misses, 13026 dirtied
WAL usage: 663835 records, 31369 full page images, 237638092 bytes
system usage: CPU: user: 6.33 s, system: 0.92 s, elapsed: 8.96 s
2024-06-03 23:50:45.855 UTC [9977] 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.27 s, system: 0.07 s, elapsed: 0.36 s
2024-06-03 23:54:30.031 UTC [10141] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 50299 scanned (9.69% of total)
tuples: 1669246 removed, 47785870 remain, 2019 are dead but not yet removable
removable cutoff: 2036043, which was 14247 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25152 pages from table (4.84% of total) had 2564842 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 7031 newly deleted, 7193 currently deleted, 7193 reusable
index "pi1_marketsegment": pages: 257220 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 260181 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 416368 in total, 12385 newly deleted, 12385 currently deleted, 12385 reusable
I/O timings: read: 180.963 ms, write: 7.963 ms
avg read rate: 57.900 MB/s, avg write rate: 21.203 MB/s
buffer usage: 1318520 hits, 68993 misses, 25265 dirtied
WAL usage: 675358 records, 12503 full page images, 155093583 bytes
system usage: CPU: user: 6.56 s, system: 0.88 s, elapsed: 9.30 s
2024-06-03 23:54:41.182 UTC [10169] 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.022 MB/s
buffer usage: 30474 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.27 s, system: 0.07 s, elapsed: 0.35 s
2024-06-04 00:14:11.351 UTC [5954] LOG: checkpoint complete: wrote 962029 buffers (31.9%); 0 WAL file(s) added, 0 removed, 968 recycled; write=1619.094 s, sync=0.029 s, total=1624.389 s; sync files=22, longest=0.007 s, average=0.002 s; distance=15858716 kB, estimate=20052770 kB; lsn=A/67041388, redo lsn=8/AE8C8CE8
2024-06-04 00:15:44.048 UTC [11703] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 49613 scanned (9.56% of total)
tuples: 1849555 removed, 47786801 remain, 177 are dead but not yet removable
removable cutoff: 2466184, which was 50733 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.78% of total) had 2531452 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6944 newly deleted, 7208 currently deleted, 7208 reusable
index "pi1_marketsegment": pages: 259890 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 262490 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 424485 in total, 12380 newly deleted, 12380 currently deleted, 12380 reusable
I/O timings: read: 170.054 ms, write: 8.911 ms
avg read rate: 46.624 MB/s, avg write rate: 63.573 MB/s
buffer usage: 1341539 hits, 56702 misses, 77314 dirtied
WAL usage: 678687 records, 12461 full page images, 154324542 bytes
system usage: CPU: user: 6.32 s, system: 0.80 s, elapsed: 9.50 s
2024-06-04 00:17:06.450 UTC [5954] LOG: checkpoint starting: time
2024-06-04 00:17:07.411 UTC [11876] 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.199 MB/s
buffer usage: 30477 hits, 0 misses, 9 dirtied
system usage: CPU: user: 0.21 s, system: 0.12 s, elapsed: 0.35 s
2024-06-04 00:44:08.697 UTC [5954] LOG: checkpoint complete: wrote 693252 buffers (23.0%); 0 WAL file(s) added, 0 removed, 473 recycled; write=1619.581 s, sync=0.035 s, total=1622.248 s; sync files=22, longest=0.007 s, average=0.002 s; distance=7744172 kB, estimate=18821910 kB; lsn=B/71162770, redo lsn=A/87373E08
2024-06-04 00:47:06.794 UTC [5954] LOG: checkpoint starting: time
2024-06-04 00:50:14.158 UTC [13461] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 49186 scanned (9.47% of total)
tuples: 2192905 removed, 47766222 remain, 580 are dead but not yet removable
removable cutoff: 3470147, which was 53761 XIDs old when operation ended
frozen: 23119 pages from table (4.45% of total) had 2360102 tuples frozen
index scan needed: 24594 pages from table (4.74% of total) had 2509972 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6882 newly deleted, 7207 currently deleted, 7207 reusable
index "pi1_marketsegment": pages: 262346 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 264831 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 434534 in total, 12379 newly deleted, 12379 currently deleted, 12379 reusable
I/O timings: read: 152.726 ms, write: 9.377 ms
avg read rate: 32.490 MB/s, avg write rate: 85.888 MB/s
buffer usage: 1367181 hits, 44418 misses, 117420 dirtied
WAL usage: 728795 records, 347018 full page images, 1835672892 bytes
system usage: CPU: user: 6.98 s, system: 0.88 s, elapsed: 10.68 s
2024-06-04 00:51:16.341 UTC [13605] 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.09 s, elapsed: 0.35 s
2024-06-04 01:14:07.408 UTC [5954] LOG: checkpoint complete: wrote 511791 buffers (17.0%); 0 WAL file(s) added, 0 removed, 239 recycled; write=1619.239 s, sync=0.031 s, total=1620.614 s; sync files=23, longest=0.007 s, average=0.002 s; distance=3913140 kB, estimate=17331033 kB; lsn=C/8D817DA8, redo lsn=B/760E0F80
2024-06-04 01:17:06.506 UTC [5954] LOG: checkpoint starting: time
2024-06-04 01:22:56.494 UTC [15152] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 48340 scanned (9.31% of total)
tuples: 2154772 removed, 47780353 remain, 583 are dead but not yet removable
removable cutoff: 4456824, which was 52795 XIDs old when operation ended
frozen: 21575 pages from table (4.16% of total) had 2202549 tuples frozen
index scan needed: 24172 pages from table (4.66% of total) had 2466692 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6762 newly deleted, 7207 currently deleted, 7207 reusable
index "pi1_marketsegment": pages: 264596 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 266968 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 444191 in total, 12366 newly deleted, 12366 currently deleted, 12366 reusable
I/O timings: read: 136.836 ms, write: 8.477 ms
avg read rate: 24.090 MB/s, avg write rate: 86.057 MB/s
buffer usage: 1390352 hits, 32151 misses, 114854 dirtied
WAL usage: 727869 records, 251309 full page images, 1303175269 bytes
system usage: CPU: user: 6.75 s, system: 0.90 s, elapsed: 10.42 s
2024-06-04 01:23:48.929 UTC [15278] 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.088 MB/s
buffer usage: 30469 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.24 s, system: 0.09 s, elapsed: 0.35 s
2024-06-04 01:44:08.214 UTC [5954] LOG: checkpoint complete: wrote 584709 buffers (19.4%); 0 WAL file(s) added, 86 removed, 198 recycled; write=1619.497 s, sync=0.032 s, total=1621.708 s; sync files=23, longest=0.007 s, average=0.002 s; distance=4660870 kB, estimate=16064016 kB; lsn=D/A8DFEF60, redo lsn=C/928829C8
2024-06-04 01:47:06.302 UTC [5954] LOG: checkpoint starting: time
2024-06-04 01:56:24.537 UTC [16866] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 48730 scanned (9.39% of total)
tuples: 2172466 removed, 47779732 remain, 539 are dead but not yet removable
removable cutoff: 5451509, which was 50684 XIDs old when operation ended
frozen: 20246 pages from table (3.90% of total) had 2066878 tuples frozen
index scan needed: 24366 pages from table (4.69% of total) had 2486694 dead item identifiers removed
index "pi1_pkey": pages: 144307 in total, 6818 newly deleted, 7207 currently deleted, 7207 reusable
index "pi1_marketsegment": pages: 266836 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 269178 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 453950 in total, 12446 newly deleted, 12446 currently deleted, 12446 reusable
I/O timings: read: 115.734 ms, write: 6.403 ms
avg read rate: 15.593 MB/s, avg write rate: 112.847 MB/s
buffer usage: 1418713 hits, 19918 misses, 144150 dirtied
WAL usage: 730772 records, 171826 full page images, 889114076 bytes
system usage: CPU: user: 6.53 s, system: 0.86 s, elapsed: 9.97 s
2024-06-04 01:57:04.993 UTC [16980] 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.090 MB/s
buffer usage: 30469 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.25 s, system: 0.08 s, elapsed: 0.34 s
2024-06-04 02:11:02.580 UTC [5954] LOG: checkpoint complete: wrote 589964 buffers (19.6%); 0 WAL file(s) added, 146 removed, 138 recycled; write=1433.688 s, sync=0.018 s, total=1436.278 s; sync files=21, longest=0.006 s, average=0.001 s; distance=4650456 kB, estimate=14922660 kB; lsn=E/C56ECEB0, redo lsn=D/AE5F89E8
2024-06-04 02:11:02.580 UTC [5954] LOG: checkpoint starting: immediate force wait
2024-06-04 02:11:10.368 UTC [5954] LOG: checkpoint complete: wrote 597295 buffers (19.8%); 0 WAL file(s) added, 132 removed, 147 recycled; write=5.118 s, sync=0.059 s, total=7.789 s; sync files=22, longest=0.009 s, average=0.003 s; distance=4572113 kB, estimate=13887606 kB; lsn=E/C56ECF60, redo lsn=E/C56ECF28
2024-06-04 02:41:02.466 UTC [5954] LOG: checkpoint starting: time
2024-06-04 03:08:03.045 UTC [5954] LOG: checkpoint complete: wrote 254719 buffers (8.4%); 0 WAL file(s) added, 90 removed, 0 recycled; write=1619.520 s, sync=0.015 s, total=1620.579 s; sync files=19, longest=0.007 s, average=0.001 s; distance=1473289 kB, estimate=12646174 kB; lsn=F/767F0748, redo lsn=F/1F5AF638
2024-06-04 03:11:02.142 UTC [5954] LOG: checkpoint starting: time
2024-06-04 03:38:03.105 UTC [5954] LOG: checkpoint complete: wrote 218216 buffers (7.2%); 0 WAL file(s) added, 94 removed, 0 recycled; write=1619.876 s, sync=0.023 s, total=1620.964 s; sync files=18, longest=0.011 s, average=0.002 s; distance=1536303 kB, estimate=11535187 kB; lsn=F/D5CFF550, redo lsn=F/7D1FB398
2024-06-04 03:41:02.174 UTC [5954] LOG: checkpoint starting: time
2024-06-04 04:08:03.226 UTC [5954] LOG: checkpoint complete: wrote 226685 buffers (7.5%); 0 WAL file(s) added, 95 removed, 0 recycled; write=1619.901 s, sync=0.028 s, total=1621.052 s; sync files=20, longest=0.016 s, average=0.002 s; distance=1561968 kB, estimate=10537865 kB; lsn=10/36697668, redo lsn=F/DC757420
2024-06-04 04:11:02.274 UTC [5954] LOG: checkpoint starting: time
2024-06-04 04:38:03.252 UTC [5954] LOG: checkpoint complete: wrote 225985 buffers (7.5%); 0 WAL file(s) added, 97 removed, 0 recycled; write=1619.708 s, sync=0.033 s, total=1620.978 s; sync files=18, longest=0.007 s, average=0.002 s; distance=1582855 kB, estimate=9642364 kB; lsn=10/FD8429E8, redo lsn=10/3D119180
2024-06-04 04:41:02.352 UTC [5954] LOG: checkpoint starting: time
2024-06-04 05:08:03.910 UTC [5954] LOG: checkpoint complete: wrote 508118 buffers (16.9%); 0 WAL file(s) added, 117 removed, 81 recycled; write=1619.652 s, sync=0.032 s, total=1621.559 s; sync files=19, longest=0.007 s, average=0.002 s; distance=3255073 kB, estimate=9003635 kB; lsn=11/C8A3E290, redo lsn=11/3BE1780
2024-06-04 05:11:03.011 UTC [5954] LOG: checkpoint starting: time
2024-06-04 05:12:35.542 UTC [234248] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 48985 scanned (9.44% of total)
tuples: 1619952 removed, 47735613 remain, 303 are dead but not yet removable
removable cutoff: 6200778, which was 360 XIDs old when operation ended
frozen: 23365 pages from table (4.50% of total) had 2385233 tuples frozen
index scan needed: 24493 pages from table (4.72% of total) had 2499747 dead item identifiers removed
index "pi1_pkey": pages: 148407 in total, 6853 newly deleted, 11305 currently deleted, 5315 reusable
index "pi1_marketsegment": pages: 269847 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 271962 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 477212 in total, 22189 newly deleted, 30798 currently deleted, 30798 reusable
I/O timings: read: 0.000 ms, write: 0.056 ms
avg read rate: 0.000 MB/s, avg write rate: 73.289 MB/s
buffer usage: 1578545 hits, 0 misses, 169381 dirtied
WAL usage: 771931 records, 540617 full page images, 2804198805 bytes
system usage: CPU: user: 11.48 s, system: 2.36 s, elapsed: 18.05 s
2024-06-04 05:12:35.788 UTC [234248] 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.21 s, system: 0.01 s, elapsed: 0.24 s
2024-06-04 05:38:04.205 UTC [5954] LOG: checkpoint complete: wrote 466682 buffers (15.5%); 0 WAL file(s) added, 0 removed, 203 recycled; write=1620.042 s, sync=0.027 s, total=1621.195 s; sync files=18, longest=0.011 s, average=0.002 s; distance=3323114 kB, estimate=8435583 kB; lsn=12/AF248E48, redo lsn=11/CE91C208
2024-06-04 05:41:03.302 UTC [5954] LOG: checkpoint starting: time
2024-06-04 06:08:04.289 UTC [5954] LOG: checkpoint complete: wrote 538552 buffers (17.9%); 0 WAL file(s) added, 0 removed, 228 recycled; write=1619.688 s, sync=0.025 s, total=1620.988 s; sync files=22, longest=0.007 s, average=0.002 s; distance=3731813 kB, estimate=7965206 kB; lsn=13/76638640, redo lsn=12/B25756F8
2024-06-04 06:11:03.310 UTC [5954] LOG: checkpoint starting: time
2024-06-04 06:24:37.146 UTC [444570] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 48954 scanned (9.43% of total)
tuples: 1611000 removed, 47759159 remain, 0 are dead but not yet removable
removable cutoff: 6300719, which was 520 XIDs old when operation ended
frozen: 6964 pages from table (1.34% of total) had 710881 tuples frozen
index scan needed: 24477 pages from table (4.71% of total) had 2498303 dead item identifiers removed
index "pi1_pkey": pages: 149947 in total, 6850 newly deleted, 12840 currently deleted, 11875 reusable
index "pi1_marketsegment": pages: 272046 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 274057 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 477212 in total, 22279 newly deleted, 30719 currently deleted, 30719 reusable
I/O timings: read: 0.000 ms, write: 0.045 ms
avg read rate: 0.000 MB/s, avg write rate: 65.271 MB/s
buffer usage: 1601752 hits, 0 misses, 111971 dirtied
WAL usage: 743435 records, 77650 full page images, 369510613 bytes
system usage: CPU: user: 10.57 s, system: 0.69 s, elapsed: 13.40 s
2024-06-04 06:24:44.085 UTC [444584] 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.23 s, system: 0.08 s, elapsed: 0.33 s
2024-06-04 06:38:04.224 UTC [5954] LOG: checkpoint complete: wrote 459361 buffers (15.2%); 0 WAL file(s) added, 0 removed, 202 recycled; write=1619.756 s, sync=0.020 s, total=1620.914 s; sync files=20, longest=0.008 s, average=0.001 s; distance=3304470 kB, estimate=7499132 kB; lsn=14/7A4B5A68, redo lsn=13/7C07B1F8
2024-06-04 06:41:03.322 UTC [5954] LOG: checkpoint starting: time
2024-06-04 07:06:27.726 UTC [446170] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 49232 scanned (9.48% of total)
tuples: 1631664 removed, 47889618 remain, 256 are dead but not yet removable
removable cutoff: 6401200, which was 360 XIDs old when operation ended
frozen: 9725 pages from table (1.87% of total) had 992826 tuples frozen
index scan needed: 24612 pages from table (4.74% of total) had 2512144 dead item identifiers removed
index "pi1_pkey": pages: 149947 in total, 6888 newly deleted, 12846 currently deleted, 12846 reusable
index "pi1_marketsegment": pages: 274190 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 276069 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 477212 in total, 22409 newly deleted, 30783 currently deleted, 30783 reusable
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 115.053 MB/s
buffer usage: 1597411 hits, 0 misses, 132482 dirtied
WAL usage: 753972 records, 37961 full page images, 230815808 bytes
system usage: CPU: user: 5.98 s, system: 0.65 s, elapsed: 8.99 s
2024-06-04 07:06:29.070 UTC [446174] 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.069 MB/s
buffer usage: 30469 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.23 s, system: 0.09 s, elapsed: 0.34 s
2024-06-04 07:08:04.480 UTC [5954] LOG: checkpoint complete: wrote 614385 buffers (20.4%); 0 WAL file(s) added, 0 removed, 261 recycled; write=1619.674 s, sync=0.014 s, total=1621.159 s; sync files=23, longest=0.005 s, average=0.001 s; distance=4276646 kB, estimate=7176884 kB; lsn=15/7962D250, redo lsn=14/810E4A40
2024-06-04 07:11:03.574 UTC [5954] LOG: checkpoint starting: time
2024-06-04 07:38:04.539 UTC [5954] LOG: checkpoint complete: wrote 641460 buffers (21.3%); 0 WAL file(s) added, 0 removed, 255 recycled; write=1619.493 s, sync=0.026 s, total=1620.965 s; sync files=22, longest=0.007 s, average=0.002 s; distance=4179336 kB, estimate=6877129 kB; lsn=16/6593F708, redo lsn=15/80246CC0
2024-06-04 07:41:03.639 UTC [5954] LOG: checkpoint starting: time
2024-06-04 07:48:28.993 UTC [566964] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 519166 remain, 49232 scanned (9.48% of total)
tuples: 1630949 removed, 47748646 remain, 612 are dead but not yet removable
removable cutoff: 6501681, which was 600 XIDs old when operation ended
frozen: 20 pages from table (0.00% of total) had 1905 tuples frozen
index scan needed: 24619 pages from table (4.74% of total) had 2512244 dead item identifiers removed
index "pi1_pkey": pages: 149947 in total, 6888 newly deleted, 12846 currently deleted, 12781 reusable
index "pi1_marketsegment": pages: 276237 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 278051 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 477212 in total, 22360 newly deleted, 30705 currently deleted, 30705 reusable
I/O timings: read: 0.000 ms, write: 0.031 ms
avg read rate: 0.000 MB/s, avg write rate: 67.080 MB/s
buffer usage: 1612104 hits, 0 misses, 124845 dirtied
WAL usage: 738310 records, 175247 full page images, 767116939 bytes
system usage: CPU: user: 10.96 s, system: 1.00 s, elapsed: 14.54 s
2024-06-04 07:48:29.282 UTC [566964] 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.094 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-04 08:08:04.398 UTC [5954] LOG: checkpoint complete: wrote 550745 buffers (18.3%); 0 WAL file(s) added, 0 removed, 236 recycled; write=1619.434 s, sync=0.027 s, total=1620.760 s; sync files=21, longest=0.007 s, average=0.002 s; distance=3874185 kB, estimate=6576834 kB; lsn=17/6C73A5C0, redo lsn=16/6C9A9470
2024-06-04 08:11:03.418 UTC [5954] LOG: checkpoint starting: time
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment