Skip to content

Instantly share code, notes, and snippets.

@mdcallag
Created June 6, 2024 00:10
Show Gist options
  • Save mdcallag/a4096d333e709e03bafab693be3326a6 to your computer and use it in GitHub Desktop.
Save mdcallag/a4096d333e709e03bafab693be3326a6 to your computer and use it in GitHub Desktop.
logfile.fpw0.vic
2024-06-04 08:23:45.303 UTC [653356] 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-04 08:23:45.303 UTC [653356] LOG: listening on IPv4 address "127.0.0.1", port 5432
2024-06-04 08:23:45.323 UTC [653356] LOG: listening on Unix socket "/tmp/.s.PGSQL.5432"
2024-06-04 08:23:45.338 UTC [653359] LOG: database system was shut down at 2024-06-04 08:23:44 UTC
2024-06-04 08:23:45.348 UTC [653356] LOG: database system is ready to accept connections
2024-06-04 08:24:00.778 UTC [653357] LOG: checkpoint starting: immediate force wait
2024-06-04 08:24:01.072 UTC [653357] LOG: checkpoint complete: wrote 1888 buffers (0.1%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.073 s, sync=0.197 s, total=0.294 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-04 08:24:02.032 UTC [653393] LOG: automatic analyze of table "template1.pg_catalog.pg_type"
I/O timings: read: 0.021 ms, write: 0.083 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-04 08:24:02.033 UTC [653393] LOG: automatic aggressive vacuum of table "template1.pg_catalog.pg_attribute": index scans: 0
pages: 0 removed, 57 remain, 1 scanned (1.75% of total)
tuples: 0 removed, 3108 remain, 0 are dead but not yet removable
removable cutoff: 738, which was 0 XIDs old when operation ended
new relfrozenxid: 738, which is 16 XIDs ahead of previous value
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan not needed: 0 pages from table (0.00% of total) had 0 dead item identifiers removed
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 100.160 MB/s
buffer usage: 12 hits, 0 misses, 1 dirtied
WAL usage: 1 records, 0 full page images, 241 bytes
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-04 08:24:02.042 UTC [653393] LOG: automatic analyze of table "template1.pg_catalog.pg_attribute"
I/O timings: read: 0.000 ms, write: 0.015 ms
avg read rate: 0.000 MB/s, avg write rate: 4.688 MB/s
buffer usage: 510 hits, 0 misses, 6 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-04 08:24:02.042 UTC [653393] 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-04 08:24:02.044 UTC [653393] 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-04 08:24:02.045 UTC [653393] LOG: automatic analyze of table "template1.pg_catalog.pg_constraint"
I/O timings: read: 0.000 ms, write: 0.022 ms
avg read rate: 0.000 MB/s, avg write rate: 19.531 MB/s
buffer usage: 533 hits, 0 misses, 5 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-04 08:24:02.045 UTC [653393] 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-04 08:24:02.046 UTC [653393] 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: 616.776 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-04 08:24:02.050 UTC [653393] LOG: automatic analyze of table "template1.pg_catalog.pg_description"
I/O timings: read: 0.000 ms, write: 0.014 ms
avg read rate: 0.000 MB/s, avg write rate: 6.250 MB/s
buffer usage: 105 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2024-06-04 08:24:02.050 UTC [653393] 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: 209.888 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-04 08:24:02.052 UTC [653393] 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-04 08:24:02.052 UTC [653393] 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-04 08:24:02.053 UTC [653393] 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-04 08:24:02.054 UTC [653393] 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-04 08:24:02.056 UTC [653393] LOG: automatic analyze of table "template1.information_schema.sql_features"
I/O timings: read: 0.000 ms, write: 0.017 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-04 08:24:08.726 UTC [653413] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 3124 remain, 3124 scanned (100.00% of total)
tuples: 0 removed, 318857 remain, 0 are dead but not yet removable
removable cutoff: 3938, 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.016 ms
avg read rate: 0.000 MB/s, avg write rate: 0.893 MB/s
buffer usage: 6284 hits, 0 misses, 3 dirtied
WAL usage: 3123 records, 0 full page images, 184405 bytes
system usage: CPU: user: 0.02 s, system: 0.00 s, elapsed: 0.02 s
2024-06-04 08:24:08.872 UTC [653413] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.026 ms
avg read rate: 0.000 MB/s, avg write rate: 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-04 08:24:13.704 UTC [653415] 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: 10830, which was 1 XIDs old when operation ended
new relfrozenxid: 10830, which is 10108 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: 42.926 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-04 08:24:18.812 UTC [653418] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 16622 remain, 13502 scanned (81.23% of total)
tuples: 0 removed, 1696669 remain, 0 are dead but not yet removable
removable cutoff: 17718, 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.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 27045 hits, 0 misses, 0 dirtied
WAL usage: 13502 records, 0 full page images, 796796 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:24:19.007 UTC [653418] 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.040 MB/s
buffer usage: 17134 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.18 s, system: 0.00 s, elapsed: 0.19 s
2024-06-04 08:24:28.814 UTC [653422] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 30103 remain, 13482 scanned (44.79% of total)
tuples: 0 removed, 3072908 remain, 0 are dead but not yet removable
removable cutoff: 31481, 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.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 27008 hits, 0 misses, 0 dirtied
WAL usage: 13481 records, 0 full page images, 795557 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:24:29.057 UTC [653422] 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.21 s, system: 0.00 s, elapsed: 0.24 s
2024-06-04 08:24:38.816 UTC [653432] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 43595 remain, 13494 scanned (30.95% of total)
tuples: 0 removed, 4450379 remain, 0 are dead but not yet removable
removable cutoff: 45257, 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.030 ms
avg read rate: 0.000 MB/s, avg write rate: 0.070 MB/s
buffer usage: 27037 hits, 0 misses, 1 dirtied
WAL usage: 13494 records, 0 full page images, 796324 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:24:39.069 UTC [653432] 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.01 s, elapsed: 0.25 s
2024-06-04 08:24:48.917 UTC [653436] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 57190 remain, 13596 scanned (23.77% of total)
tuples: 0 removed, 5838324 remain, 0 are dead but not yet removable
removable cutoff: 59137, which was 154 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: 27245 hits, 0 misses, 0 dirtied
WAL usage: 13596 records, 0 full page images, 802342 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-04 08:24:49.177 UTC [653436] 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-04 08:24:59.018 UTC [653440] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 70825 remain, 13636 scanned (19.25% of total)
tuples: 0 removed, 7230139 remain, 0 are dead but not yet removable
removable cutoff: 73056, 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.029 ms
avg read rate: 0.000 MB/s, avg write rate: 0.069 MB/s
buffer usage: 27330 hits, 0 misses, 1 dirtied
WAL usage: 13636 records, 0 full page images, 804702 bytes
system usage: CPU: user: 0.07 s, system: 0.02 s, elapsed: 0.11 s
2024-06-04 08:24:59.286 UTC [653440] 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.03 s, elapsed: 0.26 s
2024-06-04 08:25:09.119 UTC [653454] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 84443 remain, 13619 scanned (16.13% of total)
tuples: 0 removed, 8620125 remain, 0 are dead but not yet removable
removable cutoff: 86959, 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: 27299 hits, 0 misses, 0 dirtied
WAL usage: 13619 records, 0 full page images, 803699 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:25:09.392 UTC [653454] 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-04 08:25:19.217 UTC [653459] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 98063 remain, 13621 scanned (13.89% of total)
tuples: 0 removed, 10010484 remain, 0 are dead but not yet removable
removable cutoff: 100863, 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.030 ms
avg read rate: 0.000 MB/s, avg write rate: 0.069 MB/s
buffer usage: 27309 hits, 0 misses, 1 dirtied
WAL usage: 13620 records, 0 full page images, 803758 bytes
system usage: CPU: user: 0.10 s, system: 0.00 s, elapsed: 0.11 s
2024-06-04 08:25:19.497 UTC [653459] 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.04 s, elapsed: 0.27 s
2024-06-04 08:25:29.219 UTC [653463] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 111526 remain, 13465 scanned (12.07% of total)
tuples: 0 removed, 11384891 remain, 0 are dead but not yet removable
removable cutoff: 114608, which was 154 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: 27000 hits, 0 misses, 0 dirtied
WAL usage: 13464 records, 0 full page images, 794554 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-04 08:25:29.503 UTC [653463] 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.23 s, system: 0.03 s, elapsed: 0.28 s
2024-06-04 08:25:39.220 UTC [653473] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 125020 remain, 13496 scanned (10.80% of total)
tuples: 0 removed, 12762566 remain, 0 are dead but not yet removable
removable cutoff: 128384, 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.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 27065 hits, 0 misses, 0 dirtied
WAL usage: 13495 records, 0 full page images, 796383 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:25:39.509 UTC [653473] 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.23 s, system: 0.03 s, elapsed: 0.28 s
2024-06-04 08:25:49.223 UTC [653477] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 138495 remain, 13477 scanned (9.73% of total)
tuples: 0 removed, 14138687 remain, 0 are dead but not yet removable
removable cutoff: 142142, 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.030 ms
avg read rate: 0.000 MB/s, avg write rate: 0.070 MB/s
buffer usage: 27033 hits, 0 misses, 1 dirtied
WAL usage: 13477 records, 0 full page images, 795321 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:25:59.226 UTC [653481] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 151983 remain, 13489 scanned (8.88% of total)
tuples: 0 removed, 15515544 remain, 0 are dead but not yet removable
removable cutoff: 155911, 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: 27060 hits, 0 misses, 0 dirtied
WAL usage: 13489 records, 0 full page images, 796029 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:25:59.520 UTC [653481] 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-04 08:26:09.227 UTC [653491] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 165489 remain, 13507 scanned (8.16% of total)
tuples: 0 removed, 16894274 remain, 0 are dead but not yet removable
removable cutoff: 169699, 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.025 ms
avg read rate: 0.000 MB/s, avg write rate: 0.070 MB/s
buffer usage: 27101 hits, 0 misses, 1 dirtied
WAL usage: 13507 records, 0 full page images, 797091 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:26:19.230 UTC [653495] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 178991 remain, 13503 scanned (7.54% of total)
tuples: 0 removed, 18272643 remain, 0 are dead but not yet removable
removable cutoff: 183483, 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: 27096 hits, 0 misses, 0 dirtied
WAL usage: 13503 records, 0 full page images, 796855 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-04 08:26:19.531 UTC [653495] 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-04 08:26:29.232 UTC [653499] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 192492 remain, 13502 scanned (7.01% of total)
tuples: 0 removed, 19650647 remain, 0 are dead but not yet removable
removable cutoff: 197266, 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: 27098 hits, 0 misses, 0 dirtied
WAL usage: 13502 records, 0 full page images, 796796 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-04 08:26:39.231 UTC [653510] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 205992 remain, 13501 scanned (6.55% of total)
tuples: 0 removed, 21028834 remain, 0 are dead but not yet removable
removable cutoff: 211048, 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.024 ms
avg read rate: 0.000 MB/s, avg write rate: 0.068 MB/s
buffer usage: 27101 hits, 0 misses, 1 dirtied
WAL usage: 13501 records, 0 full page images, 796737 bytes
system usage: CPU: user: 0.07 s, system: 0.02 s, elapsed: 0.11 s
2024-06-04 08:26:39.538 UTC [653510] 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-04 08:26:49.234 UTC [653514] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 219475 remain, 13484 scanned (6.14% of total)
tuples: 0 removed, 22405278 remain, 0 are dead but not yet removable
removable cutoff: 224813, which was 150 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: 27070 hits, 0 misses, 0 dirtied
WAL usage: 13484 records, 0 full page images, 795734 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:26:59.234 UTC [653518] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 232957 remain, 13483 scanned (5.79% of total)
tuples: 0 removed, 23781542 remain, 0 are dead but not yet removable
removable cutoff: 238576, which was 149 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: 27074 hits, 0 misses, 1 dirtied
WAL usage: 13483 records, 0 full page images, 795675 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-04 08:26:59.544 UTC [653518] 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.07 s, elapsed: 0.30 s
2024-06-04 08:27:11.766 UTC [653528] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 249816 remain, 16860 scanned (6.75% of total)
tuples: 0 removed, 25501552 remain, 0 are dead but not yet removable
removable cutoff: 255786, which was 195 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: 33832 hits, 0 misses, 0 dirtied
WAL usage: 16860 records, 0 full page images, 994918 bytes
system usage: CPU: user: 0.11 s, system: 0.01 s, elapsed: 0.14 s
2024-06-04 08:27:21.739 UTC [653532] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 263283 remain, 13468 scanned (5.12% of total)
tuples: 0 removed, 26876373 remain, 0 are dead but not yet removable
removable cutoff: 269535, 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.030 ms
avg read rate: 0.000 MB/s, avg write rate: 0.069 MB/s
buffer usage: 27053 hits, 0 misses, 1 dirtied
WAL usage: 13468 records, 0 full page images, 794790 bytes
system usage: CPU: user: 0.08 s, system: 0.01 s, elapsed: 0.11 s
2024-06-04 08:27:22.051 UTC [653532] 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.23 s, system: 0.05 s, elapsed: 0.31 s
2024-06-04 08:27:31.739 UTC [653542] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 276762 remain, 13480 scanned (4.87% of total)
tuples: 0 removed, 28253762 remain, 0 are dead but not yet removable
removable cutoff: 283295, 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: 27081 hits, 0 misses, 0 dirtied
WAL usage: 13479 records, 0 full page images, 795439 bytes
system usage: CPU: user: 0.09 s, system: 0.00 s, elapsed: 0.11 s
2024-06-04 08:27:51.858 UTC [653550] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 303718 remain, 26958 scanned (8.88% of total)
tuples: 0 removed, 31005466 remain, 0 are dead but not yet removable
removable cutoff: 310813, which was 317 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.034 MB/s
buffer usage: 54045 hits, 0 misses, 1 dirtied
WAL usage: 26958 records, 0 full page images, 1590700 bytes
system usage: CPU: user: 0.18 s, system: 0.02 s, elapsed: 0.22 s
2024-06-04 08:27:52.174 UTC [653550] 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.22 s, system: 0.07 s, elapsed: 0.31 s
2024-06-04 08:28:11.861 UTC [653564] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 330677 remain, 26960 scanned (8.15% of total)
tuples: 0 removed, 33755393 remain, 0 are dead but not yet removable
removable cutoff: 338333, which was 309 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.034 MB/s
buffer usage: 54058 hits, 0 misses, 1 dirtied
WAL usage: 26959 records, 0 full page images, 1590759 bytes
system usage: CPU: user: 0.19 s, system: 0.00 s, elapsed: 0.22 s
2024-06-04 08:28:21.964 UTC [653568] 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.22 s, system: 0.09 s, elapsed: 0.32 s
2024-06-04 08:28:31.861 UTC [653578] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 357650 remain, 26975 scanned (7.54% of total)
tuples: 0 removed, 36509316 remain, 0 are dead but not yet removable
removable cutoff: 365869, which was 309 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: 54094 hits, 0 misses, 0 dirtied
WAL usage: 26975 records, 0 full page images, 1591703 bytes
system usage: CPU: user: 0.16 s, system: 0.02 s, elapsed: 0.22 s
2024-06-04 08:28:51.867 UTC [653586] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 384589 remain, 26941 scanned (7.01% of total)
tuples: 0 removed, 39259295 remain, 0 are dead but not yet removable
removable cutoff: 393369, which was 312 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.034 MB/s
buffer usage: 54035 hits, 0 misses, 1 dirtied
WAL usage: 26941 records, 0 full page images, 1589697 bytes
system usage: CPU: user: 0.17 s, system: 0.02 s, elapsed: 0.22 s
2024-06-04 08:28:52.188 UTC [653586] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30361 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.22 s, system: 0.07 s, elapsed: 0.32 s
2024-06-04 08:29:11.968 UTC [653600] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 411697 remain, 27110 scanned (6.58% of total)
tuples: 0 removed, 42027728 remain, 0 are dead but not yet removable
removable cutoff: 421045, which was 315 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: 54384 hits, 0 misses, 1 dirtied
WAL usage: 27110 records, 0 full page images, 1599668 bytes
system usage: CPU: user: 0.18 s, system: 0.01 s, elapsed: 0.22 s
2024-06-04 08:29:31.970 UTC [653614] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 438643 remain, 26947 scanned (6.14% of total)
tuples: 0 removed, 44778603 remain, 0 are dead but not yet removable
removable cutoff: 448553, which was 314 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: 54064 hits, 0 misses, 1 dirtied
WAL usage: 26947 records, 0 full page images, 1590051 bytes
system usage: CPU: user: 0.19 s, system: 0.00 s, elapsed: 0.22 s
2024-06-04 08:29:32.297 UTC [653614] 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.26 s, system: 0.04 s, elapsed: 0.32 s
2024-06-04 08:29:51.972 UTC [653622] LOG: automatic vacuum of table "ib.public.pi1": index scans: 0
pages: 0 removed, 465608 remain, 26966 scanned (5.79% of total)
tuples: 0 removed, 47532814 remain, 0 are dead but not yet removable
removable cutoff: 476079, which was 315 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.034 MB/s
buffer usage: 54111 hits, 0 misses, 1 dirtied
WAL usage: 26966 records, 0 full page images, 1591172 bytes
system usage: CPU: user: 0.16 s, system: 0.03 s, elapsed: 0.22 s
2024-06-04 08:30:11.746 UTC [654148] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:30:21.749 UTC [654152] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:30:31.750 UTC [654157] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:30:41.752 UTC [654167] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:30:51.754 UTC [654171] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:31:01.756 UTC [654175] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:31:11.758 UTC [654185] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:31:21.760 UTC [654189] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:31:31.761 UTC [654193] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:31:41.763 UTC [654203] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:31:51.766 UTC [654208] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:32:01.768 UTC [654212] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:32:11.770 UTC [654222] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:32:21.772 UTC [654226] LOG: skipping vacuum of "pi1" --- lock not available
2024-06-04 08:32:40.984 UTC [654630] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 492199 remain, 28995 scanned (5.89% of total)
tuples: 156422 removed, 50000412 remain, 275 are dead but not yet removable
removable cutoff: 510579, which was 14552 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 2403 pages from table (0.49% of total) had 244997 dead item identifiers removed
index "pi1_pkey": pages: 137860 in total, 671 newly deleted, 671 currently deleted, 671 reusable
index "pi1_marketsegment": pages: 192248 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 192218 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 253044 in total, 832 newly deleted, 832 currently deleted, 832 reusable
I/O timings: read: 403.290 ms, write: 200.084 ms
avg read rate: 250.413 MB/s, avg write rate: 31.198 MB/s
buffer usage: 554511 hits, 295244 misses, 36783 dirtied
WAL usage: 316711 records, 0 full page images, 18970255 bytes
system usage: CPU: user: 3.23 s, system: 1.39 s, elapsed: 9.21 s
2024-06-04 08:32:41.235 UTC [654630] 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: 30404 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.23 s, system: 0.00 s, elapsed: 0.25 s
2024-06-04 08:36:14.832 UTC [654809] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 514224 remain, 48863 scanned (9.50% of total)
tuples: 1619768 removed, 49397739 remain, 460 are dead but not yet removable
removable cutoff: 610326, which was 15128 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24437 pages from table (4.75% of total) had 2493932 dead item identifiers removed
index "pi1_pkey": pages: 144038 in total, 6837 newly deleted, 6837 currently deleted, 6837 reusable
index "pi1_marketsegment": pages: 192303 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 192298 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 274195 in total, 12148 newly deleted, 12148 currently deleted, 12148 reusable
I/O timings: read: 434.146 ms, write: 8.558 ms
avg read rate: 209.642 MB/s, avg write rate: 11.061 MB/s
buffer usage: 856446 hits, 245442 misses, 12950 dirtied
WAL usage: 538913 records, 0 full page images, 56952976 bytes
system usage: CPU: user: 6.25 s, system: 1.08 s, elapsed: 9.14 s
2024-06-04 08:36:16.171 UTC [654822] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.035 ms
avg read rate: 0.000 MB/s, avg write rate: 0.020 MB/s
buffer usage: 30479 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.24 s, system: 0.12 s, elapsed: 0.38 s
2024-06-04 08:39:57.731 UTC [654991] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 517928 remain, 49614 scanned (9.58% of total)
tuples: 1641654 removed, 47780781 remain, 1217 are dead but not yet removable
removable cutoff: 711563, which was 15206 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24807 pages from table (4.79% of total) had 2531012 dead item identifiers removed
index "pi1_pkey": pages: 144132 in total, 6939 newly deleted, 6939 currently deleted, 6939 reusable
index "pi1_marketsegment": pages: 194278 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 194650 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 284501 in total, 12095 newly deleted, 12095 currently deleted, 12095 reusable
I/O timings: read: 420.721 ms, write: 8.473 ms
avg read rate: 197.481 MB/s, avg write rate: 10.956 MB/s
buffer usage: 886224 hits, 233123 misses, 12933 dirtied
WAL usage: 544821 records, 0 full page images, 57672482 bytes
system usage: CPU: user: 6.26 s, system: 1.07 s, elapsed: 9.22 s
2024-06-04 08:39:58.991 UTC [655009] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30476 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.26 s, system: 0.10 s, elapsed: 0.38 s
2024-06-04 08:43:31.371 UTC [655168] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518303 remain, 49017 scanned (9.46% of total)
tuples: 1620884 removed, 47693957 remain, 1246 are dead but not yet removable
removable cutoff: 811564, which was 14743 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24511 pages from table (4.73% of total) had 2500213 dead item identifiers removed
index "pi1_pkey": pages: 144132 in total, 6855 newly deleted, 7028 currently deleted, 7028 reusable
index "pi1_marketsegment": pages: 199015 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 199958 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 294689 in total, 12125 newly deleted, 12125 currently deleted, 12125 reusable
I/O timings: read: 398.382 ms, write: 7.771 ms
avg read rate: 190.522 MB/s, avg write rate: 11.150 MB/s
buffer usage: 917763 hits, 220686 misses, 12915 dirtied
WAL usage: 553469 records, 0 full page images, 57744990 bytes
system usage: CPU: user: 6.17 s, system: 1.07 s, elapsed: 9.04 s
2024-06-04 08:43:32.703 UTC [655186] 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-04 08:47:16.954 UTC [655360] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518303 remain, 50036 scanned (9.65% of total)
tuples: 1656319 removed, 47714883 remain, 1139 are dead but not yet removable
removable cutoff: 913657, which was 14805 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25022 pages from table (4.83% of total) had 2552357 dead item identifiers removed
index "pi1_pkey": pages: 144182 in total, 6997 newly deleted, 7027 currently deleted, 7027 reusable
index "pi1_marketsegment": pages: 205827 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 207165 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 305381 in total, 12776 newly deleted, 12776 currently deleted, 12776 reusable
I/O timings: read: 392.434 ms, write: 7.483 ms
avg read rate: 176.780 MB/s, avg write rate: 11.479 MB/s
buffer usage: 965561 hits, 208252 misses, 13523 dirtied
WAL usage: 571849 records, 0 full page images, 59323094 bytes
system usage: CPU: user: 6.21 s, system: 1.17 s, elapsed: 9.20 s
2024-06-04 08:47:18.230 UTC [655372] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.034 ms
avg read rate: 0.000 MB/s, avg write rate: 0.021 MB/s
buffer usage: 30475 hits, 0 misses, 1 dirtied
system usage: CPU: user: 0.23 s, system: 0.13 s, elapsed: 0.37 s
2024-06-04 08:50:52.871 UTC [655538] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518401 remain, 49515 scanned (9.55% of total)
tuples: 1637545 removed, 47724639 remain, 1210 are dead but not yet removable
removable cutoff: 1014686, which was 14789 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24760 pages from table (4.78% of total) had 2525679 dead item identifiers removed
index "pi1_pkey": pages: 144182 in total, 6926 newly deleted, 7076 currently deleted, 7076 reusable
index "pi1_marketsegment": pages: 212353 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 213998 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 314991 in total, 12119 newly deleted, 12119 currently deleted, 12119 reusable
I/O timings: read: 368.835 ms, write: 7.532 ms
avg read rate: 165.846 MB/s, avg write rate: 10.917 MB/s
buffer usage: 993653 hits, 195365 misses, 12860 dirtied
WAL usage: 581971 records, 0 full page images, 59466630 bytes
system usage: CPU: user: 6.20 s, system: 1.16 s, elapsed: 9.20 s
2024-06-04 08:50:54.046 UTC [655550] 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.11 s, elapsed: 0.37 s
2024-06-04 08:54:00.137 UTC [653357] LOG: checkpoint starting: time
2024-06-04 08:54:29.014 UTC [655715] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518401 remain, 49116 scanned (9.47% of total)
tuples: 1623420 removed, 47753807 remain, 1188 are dead but not yet removable
removable cutoff: 1114895, which was 14898 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24560 pages from table (4.74% of total) had 2505191 dead item identifiers removed
index "pi1_pkey": pages: 144182 in total, 6869 newly deleted, 7076 currently deleted, 7076 reusable
index "pi1_marketsegment": pages: 218299 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 220405 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 325168 in total, 12131 newly deleted, 12131 currently deleted, 12131 reusable
I/O timings: read: 353.328 ms, write: 8.201 ms
avg read rate: 153.243 MB/s, avg write rate: 10.747 MB/s
buffer usage: 1027663 hits, 183134 misses, 12843 dirtied
WAL usage: 593304 records, 0 full page images, 59792870 bytes
system usage: CPU: user: 6.31 s, system: 1.05 s, elapsed: 9.33 s
2024-06-04 08:54:30.157 UTC [655733] 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.082 MB/s
buffer usage: 30469 hits, 0 misses, 4 dirtied
system usage: CPU: user: 0.24 s, system: 0.11 s, elapsed: 0.37 s
2024-06-04 08:58:00.381 UTC [655897] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518401 remain, 48677 scanned (9.39% of total)
tuples: 1608063 removed, 47770971 remain, 3733 are dead but not yet removable
removable cutoff: 1214096, which was 14698 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24351 pages from table (4.70% of total) had 2481336 dead item identifiers removed
index "pi1_pkey": pages: 144182 in total, 6799 newly deleted, 7065 currently deleted, 7065 reusable
index "pi1_marketsegment": pages: 223909 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 226125 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 335065 in total, 12227 newly deleted, 12227 currently deleted, 12227 reusable
I/O timings: read: 323.752 ms, write: 7.374 ms
avg read rate: 137.823 MB/s, avg write rate: 47.330 MB/s
buffer usage: 1060507 hits, 170897 misses, 58688 dirtied
WAL usage: 603605 records, 0 full page images, 60034414 bytes
system usage: CPU: user: 6.32 s, system: 1.06 s, elapsed: 9.68 s
2024-06-04 08:58:01.071 UTC [655914] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 0.000 MB/s
buffer usage: 30469 hits, 0 misses, 0 dirtied
system usage: CPU: user: 0.28 s, system: 0.08 s, elapsed: 0.37 s
2024-06-04 09:01:35.535 UTC [656080] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518401 remain, 49040 scanned (9.46% of total)
tuples: 1624103 removed, 47682936 remain, 1213 are dead but not yet removable
removable cutoff: 1314155, which was 14496 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24522 pages from table (4.73% of total) had 2500120 dead item identifiers removed
index "pi1_pkey": pages: 144182 in total, 6858 newly deleted, 7074 currently deleted, 7074 reusable
index "pi1_marketsegment": pages: 229136 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 231534 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 345072 in total, 12929 newly deleted, 12929 currently deleted, 12929 reusable
I/O timings: read: 319.454 ms, write: 8.110 ms
avg read rate: 134.384 MB/s, avg write rate: 11.496 MB/s
buffer usage: 1101675 hits, 158613 misses, 13569 dirtied
WAL usage: 617193 records, 0 full page images, 60991777 bytes
system usage: CPU: user: 6.16 s, system: 1.09 s, elapsed: 9.22 s
2024-06-04 09:01:36.677 UTC [656092] 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-04 09:05:29.328 UTC [656269] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518401 remain, 50505 scanned (9.74% of total)
tuples: 1671076 removed, 47735552 remain, 1245 are dead but not yet removable
removable cutoff: 1417200, which was 14740 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25255 pages from table (4.87% of total) had 2576018 dead item identifiers removed
index "pi1_pkey": pages: 144224 in total, 7064 newly deleted, 7073 currently deleted, 7073 reusable
index "pi1_marketsegment": pages: 234681 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 237118 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 355229 in total, 12275 newly deleted, 12275 currently deleted, 12275 reusable
I/O timings: read: 298.805 ms, write: 7.552 ms
avg read rate: 121.074 MB/s, avg write rate: 10.766 MB/s
buffer usage: 1134503 hits, 145650 misses, 12951 dirtied
WAL usage: 629967 records, 0 full page images, 62464421 bytes
system usage: CPU: user: 6.63 s, system: 0.92 s, elapsed: 9.39 s
2024-06-04 09:05:30.388 UTC [656287] 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-04 09:09:09.853 UTC [656457] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49509 scanned (9.55% of total)
tuples: 1637142 removed, 47776948 remain, 1228 are dead but not yet removable
removable cutoff: 1518209, which was 14609 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24757 pages from table (4.77% of total) had 2525267 dead item identifiers removed
index "pi1_pkey": pages: 144224 in total, 6923 newly deleted, 7114 currently deleted, 7114 reusable
index "pi1_marketsegment": pages: 239098 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 241596 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 365385 in total, 12946 newly deleted, 12946 currently deleted, 12946 reusable
I/O timings: read: 278.428 ms, write: 7.917 ms
avg read rate: 110.982 MB/s, avg write rate: 26.738 MB/s
buffer usage: 1169139 hits, 133364 misses, 32131 dirtied
WAL usage: 638393 records, 0 full page images, 62338678 bytes
system usage: CPU: user: 6.36 s, system: 1.03 s, elapsed: 9.38 s
2024-06-04 09:09:10.824 UTC [656469] 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-04 09:12:50.291 UTC [656638] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49759 scanned (9.59% of total)
tuples: 1647507 removed, 47808920 remain, 1287 are dead but not yet removable
removable cutoff: 1619736, which was 14705 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24882 pages from table (4.80% of total) had 2538091 dead item identifiers removed
index "pi1_pkey": pages: 144224 in total, 6960 newly deleted, 7114 currently deleted, 7114 reusable
index "pi1_marketsegment": pages: 243408 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 245857 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 375068 in total, 12252 newly deleted, 12252 currently deleted, 12252 reusable
I/O timings: read: 255.846 ms, write: 7.972 ms
avg read rate: 99.049 MB/s, avg write rate: 26.718 MB/s
buffer usage: 1194332 hits, 120412 misses, 32480 dirtied
WAL usage: 645515 records, 0 full page images, 62798702 bytes
system usage: CPU: user: 6.40 s, system: 1.02 s, elapsed: 9.49 s
2024-06-04 09:12:51.250 UTC [656650] 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-04 09:16:30.825 UTC [656822] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49862 scanned (9.61% of total)
tuples: 1649998 removed, 47804786 remain, 1328 are dead but not yet removable
removable cutoff: 1721463, which was 14671 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24933 pages from table (4.81% of total) had 2543109 dead item identifiers removed
index "pi1_pkey": pages: 144224 in total, 6973 newly deleted, 7114 currently deleted, 7114 reusable
index "pi1_marketsegment": pages: 247336 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 249803 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 385491 in total, 12944 newly deleted, 12944 currently deleted, 12944 reusable
I/O timings: read: 244.238 ms, write: 7.917 ms
avg read rate: 89.848 MB/s, avg write rate: 24.834 MB/s
buffer usage: 1232097 hits, 108148 misses, 29892 dirtied
WAL usage: 655615 records, 0 full page images, 63409409 bytes
system usage: CPU: user: 6.35 s, system: 1.01 s, elapsed: 9.40 s
2024-06-04 09:16:31.877 UTC [656841] 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-04 09:20:21.078 UTC [657021] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 50614 scanned (9.76% of total)
tuples: 1675068 removed, 47768211 remain, 1354 are dead but not yet removable
removable cutoff: 1824730, which was 14184 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25310 pages from table (4.88% of total) had 2581624 dead item identifiers removed
index "pi1_pkey": pages: 144225 in total, 7079 newly deleted, 7114 currently deleted, 7114 reusable
index "pi1_marketsegment": pages: 251053 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 253520 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 395456 in total, 12262 newly deleted, 12262 currently deleted, 12262 reusable
I/O timings: read: 221.088 ms, write: 8.250 ms
avg read rate: 80.586 MB/s, avg write rate: 11.692 MB/s
buffer usage: 1258341 hits, 95198 misses, 13812 dirtied
WAL usage: 662877 records, 0 full page images, 64173590 bytes
system usage: CPU: user: 6.43 s, system: 0.96 s, elapsed: 9.22 s
2024-06-04 09:20:22.303 UTC [657033] 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-04 09:21:07.111 UTC [653357] LOG: checkpoint complete: wrote 1235839 buffers (41.0%); 0 WAL file(s) added, 0 removed, 1277 recycled; write=1619.889 s, sync=0.124 s, total=1626.974 s; sync files=345, longest=0.007 s, average=0.001 s; distance=20922608 kB, estimate=20922608 kB; lsn=7/53A039F0, redo lsn=4/FF3E25C8
2024-06-04 09:24:00.211 UTC [653357] LOG: checkpoint starting: time
2024-06-04 09:24:13.382 UTC [657212] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 50421 scanned (9.72% of total)
tuples: 1670362 removed, 47792274 remain, 1363 are dead but not yet removable
removable cutoff: 1927597, which was 13916 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 25212 pages from table (4.86% of total) had 2571591 dead item identifiers removed
index "pi1_pkey": pages: 144225 in total, 7051 newly deleted, 7115 currently deleted, 7115 reusable
index "pi1_marketsegment": pages: 254326 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 256852 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 406012 in total, 12932 newly deleted, 12932 currently deleted, 12932 reusable
I/O timings: read: 205.176 ms, write: 8.037 ms
avg read rate: 71.282 MB/s, avg write rate: 11.762 MB/s
buffer usage: 1293726 hits, 82926 misses, 13683 dirtied
WAL usage: 671034 records, 0 full page images, 64515530 bytes
system usage: CPU: user: 6.43 s, system: 0.94 s, elapsed: 9.08 s
2024-06-04 09:24:14.648 UTC [657224] 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 09:27:46.598 UTC [657392] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 48881 scanned (9.43% of total)
tuples: 1618107 removed, 47778497 remain, 1285 are dead but not yet removable
removable cutoff: 2027326, which was 14240 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24444 pages from table (4.71% of total) had 2493328 dead item identifiers removed
index "pi1_pkey": pages: 144225 in total, 6836 newly deleted, 7114 currently deleted, 7114 reusable
index "pi1_marketsegment": pages: 257098 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 259620 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 415421 in total, 12284 newly deleted, 12284 currently deleted, 12284 reusable
I/O timings: read: 183.956 ms, write: 8.331 ms
avg read rate: 59.559 MB/s, avg write rate: 11.026 MB/s
buffer usage: 1309780 hits, 70056 misses, 12969 dirtied
WAL usage: 670822 records, 0 full page images, 63541411 bytes
system usage: CPU: user: 6.53 s, system: 0.95 s, elapsed: 9.18 s
2024-06-04 09:27:47.866 UTC [657404] 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.25 s, system: 0.08 s, elapsed: 0.35 s
2024-06-04 09:44:28.703 UTC [658697] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 48827 scanned (9.41% of total)
tuples: 1762933 removed, 47802890 remain, 205 are dead but not yet removable
removable cutoff: 2362923, which was 55714 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24415 pages from table (4.71% of total) had 2491480 dead item identifiers removed
index "pi1_pkey": pages: 144225 in total, 6833 newly deleted, 7127 currently deleted, 7127 reusable
index "pi1_marketsegment": pages: 259572 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 261887 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 423437 in total, 12236 newly deleted, 12236 currently deleted, 12236 reusable
I/O timings: read: 177.758 ms, write: 10.178 ms
avg read rate: 41.522 MB/s, avg write rate: 124.808 MB/s
buffer usage: 1334084 hits, 57860 misses, 173916 dirtied
WAL usage: 675363 records, 0 full page images, 64034752 bytes
system usage: CPU: user: 6.55 s, system: 0.96 s, elapsed: 10.88 s
2024-06-04 09:44:48.464 UTC [658774] LOG: automatic analyze of table "ib.public.pi1"
I/O timings: read: 0.000 ms, write: 0.028 ms
avg read rate: 0.000 MB/s, avg write rate: 0.045 MB/s
buffer usage: 30477 hits, 0 misses, 2 dirtied
system usage: CPU: user: 0.25 s, system: 0.07 s, elapsed: 0.34 s
2024-06-04 09:51:03.617 UTC [653357] LOG: checkpoint complete: wrote 958489 buffers (31.8%); 0 WAL file(s) added, 0 removed, 645 recycled; write=1619.838 s, sync=0.022 s, total=1623.407 s; sync files=22, longest=0.007 s, average=0.001 s; distance=10567944 kB, estimate=19887142 kB; lsn=8/465BD668, redo lsn=7/844248D0
2024-06-04 09:54:00.658 UTC [653357] LOG: checkpoint starting: time
2024-06-04 10:16:31.271 UTC [660365] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 48711 scanned (9.39% of total)
tuples: 2170759 removed, 47716714 remain, 404 are dead but not yet removable
removable cutoff: 3357224, which was 53387 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24356 pages from table (4.70% of total) had 2485761 dead item identifiers removed
index "pi1_pkey": pages: 144225 in total, 6815 newly deleted, 7125 currently deleted, 7125 reusable
index "pi1_marketsegment": pages: 261983 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 264250 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 433280 in total, 12275 newly deleted, 12275 currently deleted, 12275 reusable
I/O timings: read: 156.816 ms, write: 9.251 ms
avg read rate: 33.436 MB/s, avg write rate: 119.884 MB/s
buffer usage: 1360828 hits, 45720 misses, 163928 dirtied
WAL usage: 692918 records, 0 full page images, 65594736 bytes
system usage: CPU: user: 6.48 s, system: 0.94 s, elapsed: 10.68 s
2024-06-04 10:16:40.939 UTC [660418] 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.111 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 10:21:01.199 UTC [653357] LOG: checkpoint complete: wrote 660162 buffers (21.9%); 0 WAL file(s) added, 0 removed, 202 recycled; write=1619.383 s, sync=0.023 s, total=1620.542 s; sync files=23, longest=0.007 s, average=0.001 s; distance=3308696 kB, estimate=18229297 kB; lsn=8/8D8979A8, redo lsn=8/4E34AA58
2024-06-04 10:24:00.298 UTC [653357] LOG: checkpoint starting: time
2024-06-04 10:49:36.951 UTC [662076] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49111 scanned (9.47% of total)
tuples: 2188372 removed, 47680262 remain, 371 are dead but not yet removable
removable cutoff: 4359785, which was 53295 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24556 pages from table (4.73% of total) had 2506403 dead item identifiers removed
index "pi1_pkey": pages: 144225 in total, 6872 newly deleted, 7125 currently deleted, 7125 reusable
index "pi1_marketsegment": pages: 264443 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 266612 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 443394 in total, 12936 newly deleted, 12936 currently deleted, 12936 reusable
I/O timings: read: 143.544 ms, write: 7.646 ms
avg read rate: 24.805 MB/s, avg write rate: 116.007 MB/s
buffer usage: 1395904 hits, 33564 misses, 156970 dirtied
WAL usage: 698487 records, 0 full page images, 66182269 bytes
system usage: CPU: user: 6.50 s, system: 0.88 s, elapsed: 10.57 s
2024-06-04 10:49:46.727 UTC [662128] 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.24 s, system: 0.08 s, elapsed: 0.34 s
2024-06-04 10:51:00.484 UTC [653357] LOG: checkpoint complete: wrote 618638 buffers (20.5%); 0 WAL file(s) added, 0 removed, 73 recycled; write=1619.742 s, sync=0.020 s, total=1620.187 s; sync files=23, longest=0.007 s, average=0.001 s; distance=1201213 kB, estimate=16526489 kB; lsn=8/D4D54128, redo lsn=8/9785A040
2024-06-04 10:54:00.542 UTC [653357] LOG: checkpoint starting: time
2024-06-04 11:21:00.440 UTC [653357] LOG: checkpoint complete: wrote 627672 buffers (20.8%); 0 WAL file(s) added, 0 removed, 72 recycled; write=1619.439 s, sync=0.032 s, total=1619.899 s; sync files=21, longest=0.007 s, average=0.002 s; distance=1180724 kB, estimate=14991912 kB; lsn=9/11F4B3B8, redo lsn=8/DF967120
2024-06-04 11:21:59.005 UTC [663761] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 48742 scanned (9.40% of total)
tuples: 2171396 removed, 47770638 remain, 304 are dead but not yet removable
removable cutoff: 5354710, which was 49454 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24372 pages from table (4.70% of total) had 2487312 dead item identifiers removed
index "pi1_pkey": pages: 144225 in total, 6820 newly deleted, 7125 currently deleted, 7125 reusable
index "pi1_marketsegment": pages: 266690 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 268817 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 452577 in total, 12324 newly deleted, 12324 currently deleted, 12324 reusable
I/O timings: read: 116.594 ms, write: 7.400 ms
avg read rate: 16.718 MB/s, avg write rate: 102.446 MB/s
buffer usage: 1414685 hits, 20756 misses, 127193 dirtied
WAL usage: 697208 records, 0 full page images, 65857800 bytes
system usage: CPU: user: 6.42 s, system: 0.80 s, elapsed: 9.69 s
2024-06-04 11:21:59.753 UTC [663790] 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.24 s, system: 0.09 s, elapsed: 0.34 s
2024-06-04 11:24:00.538 UTC [653357] LOG: checkpoint starting: time
2024-06-04 11:40:20.095 UTC [653357] LOG: checkpoint complete: wrote 629735 buffers (20.9%); 0 WAL file(s) added, 71 removed, 0 recycled; write=978.616 s, sync=0.025 s, total=979.558 s; sync files=22, longest=0.007 s, average=0.002 s; distance=1166123 kB, estimate=13609333 kB; lsn=9/5324D4C8, redo lsn=9/26C31FE0
2024-06-04 11:40:20.096 UTC [653357] LOG: checkpoint starting: immediate force wait
2024-06-04 11:40:23.777 UTC [653357] LOG: checkpoint complete: wrote 365624 buffers (12.1%); 0 WAL file(s) added, 45 removed, 0 recycled; write=3.069 s, sync=0.025 s, total=3.681 s; sync files=21, longest=0.007 s, average=0.002 s; distance=727149 kB, estimate=12321115 kB; lsn=9/5324D578, redo lsn=9/5324D540
2024-06-04 12:10:20.862 UTC [653357] LOG: checkpoint starting: time
2024-06-04 12:37:20.139 UTC [653357] LOG: checkpoint complete: wrote 254504 buffers (8.4%); 0 WAL file(s) added, 5 removed, 0 recycled; write=1619.194 s, sync=0.014 s, total=1619.277 s; sync files=19, longest=0.007 s, average=0.001 s; distance=81513 kB, estimate=11097155 kB; lsn=9/5CDCD5B8, redo lsn=9/581E7A30
2024-06-04 12:40:20.239 UTC [653357] LOG: checkpoint starting: time
2024-06-04 13:07:20.181 UTC [653357] LOG: checkpoint complete: wrote 218227 buffers (7.2%); 0 WAL file(s) added, 5 removed, 0 recycled; write=1619.848 s, sync=0.028 s, total=1619.943 s; sync files=18, longest=0.016 s, average=0.002 s; distance=86269 kB, estimate=9996066 kB; lsn=9/6220F3F0, redo lsn=9/5D626E60
2024-06-04 13:10:20.194 UTC [653357] LOG: checkpoint starting: time
2024-06-04 13:37:20.107 UTC [653357] LOG: checkpoint complete: wrote 226834 buffers (7.5%); 0 WAL file(s) added, 5 removed, 0 recycled; write=1619.819 s, sync=0.024 s, total=1619.913 s; sync files=19, longest=0.007 s, average=0.002 s; distance=86356 kB, estimate=9005095 kB; lsn=9/67695D18, redo lsn=9/62A7C058
2024-06-04 13:40:20.183 UTC [653357] LOG: checkpoint starting: time
2024-06-04 14:07:20.196 UTC [653357] LOG: checkpoint complete: wrote 226395 buffers (7.5%); 0 WAL file(s) added, 5 removed, 0 recycled; write=1619.895 s, sync=0.042 s, total=1620.014 s; sync files=19, longest=0.011 s, average=0.003 s; distance=86608 kB, estimate=8113246 kB; lsn=9/7E78B868, redo lsn=9/67F10248
2024-06-04 14:10:20.297 UTC [653357] LOG: checkpoint starting: time
2024-06-04 14:37:20.388 UTC [653357] LOG: checkpoint complete: wrote 507979 buffers (16.9%); 0 WAL file(s) added, 26 removed, 0 recycled; write=1619.730 s, sync=0.036 s, total=1620.092 s; sync files=19, longest=0.008 s, average=0.002 s; distance=413084 kB, estimate=7343230 kB; lsn=9/9992B4C0, redo lsn=9/81277430
2024-06-04 14:40:20.486 UTC [653357] LOG: checkpoint starting: time
2024-06-04 14:42:06.410 UTC [880350] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49139 scanned (9.47% of total)
tuples: 1627907 removed, 47840236 remain, 279 are dead but not yet removable
removable cutoff: 6201097, which was 280 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24570 pages from table (4.74% of total) had 2507771 dead item identifiers removed
index "pi1_pkey": pages: 149092 in total, 6875 newly deleted, 11990 currently deleted, 5784 reusable
index "pi1_marketsegment": pages: 269987 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 271824 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 478186 in total, 22243 newly deleted, 31834 currently deleted, 31834 reusable
I/O timings: read: 0.000 ms, write: 0.032 ms
avg read rate: 0.000 MB/s, avg write rate: 95.840 MB/s
buffer usage: 1582374 hits, 0 misses, 172102 dirtied
WAL usage: 749057 records, 0 full page images, 68207189 bytes
system usage: CPU: user: 10.58 s, system: 0.71 s, elapsed: 14.02 s
2024-06-04 14:42:06.656 UTC [880350] 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-04 15:07:20.357 UTC [653357] LOG: checkpoint complete: wrote 466467 buffers (15.5%); 0 WAL file(s) added, 27 removed, 0 recycled; write=1619.563 s, sync=0.025 s, total=1619.871 s; sync files=18, longest=0.007 s, average=0.002 s; distance=445612 kB, estimate=6653468 kB; lsn=9/B85A3AC8, redo lsn=9/9C5A2460
2024-06-04 15:10:20.454 UTC [653357] LOG: checkpoint starting: time
2024-06-04 15:37:20.494 UTC [653357] LOG: checkpoint complete: wrote 538056 buffers (17.8%); 0 WAL file(s) added, 31 removed, 0 recycled; write=1619.611 s, sync=0.026 s, total=1620.041 s; sync files=22, longest=0.007 s, average=0.002 s; distance=502298 kB, estimate=6038351 kB; lsn=9/D2E40BF0, redo lsn=9/BB028C88
2024-06-04 15:40:20.594 UTC [653357] LOG: checkpoint starting: time
2024-06-04 15:54:09.432 UTC [1090101] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49060 scanned (9.46% of total)
tuples: 1615021 removed, 47701223 remain, 0 are dead but not yet removable
removable cutoff: 6301238, which was 558 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24526 pages from table (4.73% of total) had 2503279 dead item identifiers removed
index "pi1_pkey": pages: 150176 in total, 6864 newly deleted, 13070 currently deleted, 12330 reusable
index "pi1_marketsegment": pages: 272170 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 273867 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 478186 in total, 22299 newly deleted, 31739 currently deleted, 31642 reusable
I/O timings: read: 0.000 ms, write: 0.050 ms
avg read rate: 0.000 MB/s, avg write rate: 65.423 MB/s
buffer usage: 1605482 hits, 0 misses, 111912 dirtied
WAL usage: 736590 records, 0 full page images, 67658300 bytes
system usage: CPU: user: 10.54 s, system: 0.74 s, elapsed: 13.36 s
2024-06-04 15:54:09.677 UTC [1090101] 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-04 16:07:20.313 UTC [653357] LOG: checkpoint complete: wrote 459929 buffers (15.3%); 0 WAL file(s) added, 26 removed, 0 recycled; write=1619.402 s, sync=0.029 s, total=1619.720 s; sync files=20, longest=0.012 s, average=0.002 s; distance=435180 kB, estimate=5478034 kB; lsn=A/82E8350, redo lsn=9/D5923E30
2024-06-04 16:10:20.410 UTC [653357] LOG: checkpoint starting: time
2024-06-04 16:35:59.150 UTC [1091682] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49121 scanned (9.47% of total)
tuples: 1628768 removed, 47825962 remain, 0 are dead but not yet removable
removable cutoff: 6401519, which was 520 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24560 pages from table (4.74% of total) had 2507000 dead item identifiers removed
index "pi1_pkey": pages: 150176 in total, 6874 newly deleted, 13073 currently deleted, 12976 reusable
index "pi1_marketsegment": pages: 274251 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 275884 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 478186 in total, 22355 newly deleted, 31739 currently deleted, 31739 reusable
I/O timings: read: 0.000 ms, write: 0.000 ms
avg read rate: 0.000 MB/s, avg write rate: 75.069 MB/s
buffer usage: 1610858 hits, 0 misses, 130245 dirtied
WAL usage: 743565 records, 0 full page images, 68059942 bytes
system usage: CPU: user: 10.54 s, system: 0.71 s, elapsed: 13.55 s
2024-06-04 16:36:05.941 UTC [1091697] 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.068 MB/s
buffer usage: 30469 hits, 0 misses, 3 dirtied
system usage: CPU: user: 0.23 s, system: 0.08 s, elapsed: 0.34 s
2024-06-04 16:37:20.756 UTC [653357] LOG: checkpoint complete: wrote 615296 buffers (20.4%); 0 WAL file(s) added, 56 removed, 0 recycled; write=1619.621 s, sync=0.016 s, total=1620.347 s; sync files=23, longest=0.006 s, average=0.001 s; distance=915860 kB, estimate=5021817 kB; lsn=A/42066DD0, redo lsn=A/D788FB8
2024-06-04 16:40:20.854 UTC [653357] LOG: checkpoint starting: time
2024-06-04 17:07:20.729 UTC [653357] LOG: checkpoint complete: wrote 643359 buffers (21.3%); 0 WAL file(s) added, 58 removed, 0 recycled; write=1619.139 s, sync=0.020 s, total=1619.875 s; sync files=22, longest=0.007 s, average=0.001 s; distance=948020 kB, estimate=4614437 kB; lsn=A/76EE84E0, redo lsn=A/475562C8
2024-06-04 17:10:20.826 UTC [653357] LOG: checkpoint starting: time
2024-06-04 17:17:44.859 UTC [1213987] LOG: automatic vacuum of table "ib.public.pi1": index scans: 1
pages: 0 removed, 518619 remain, 49097 scanned (9.47% of total)
tuples: 1624801 removed, 47840315 remain, 0 are dead but not yet removable
removable cutoff: 6501760, which was 360 XIDs old when operation ended
frozen: 0 pages from table (0.00% of total) had 0 tuples frozen
index scan needed: 24547 pages from table (4.73% of total) had 2506000 dead item identifiers removed
index "pi1_pkey": pages: 150176 in total, 6872 newly deleted, 13076 currently deleted, 12106 reusable
index "pi1_marketsegment": pages: 276227 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_registersegment": pages: 277777 in total, 0 newly deleted, 0 currently deleted, 0 reusable
index "pi1_pdc": pages: 478186 in total, 22376 newly deleted, 31815 currently deleted, 31815 reusable
I/O timings: read: 0.000 ms, write: 0.034 ms
avg read rate: 0.000 MB/s, avg write rate: 108.743 MB/s
buffer usage: 1601941 hits, 0 misses, 124778 dirtied
WAL usage: 737823 records, 0 full page images, 67765048 bytes
system usage: CPU: user: 6.12 s, system: 0.65 s, elapsed: 8.96 s
2024-06-04 17:17:46.239 UTC [1214547] 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.068 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 17:37:20.697 UTC [653357] LOG: checkpoint complete: wrote 550349 buffers (18.3%); 0 WAL file(s) added, 53 removed, 0 recycled; write=1619.161 s, sync=0.021 s, total=1619.872 s; sync files=21, longest=0.007 s, average=0.001 s; distance=868672 kB, estimate=4239861 kB; lsn=A/B088FF58, redo lsn=A/7C5A6580
2024-06-04 17:40:20.794 UTC [653357] LOG: checkpoint starting: time
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment