[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:20:10.098968  7890 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.7.180.190:38587
I20260812 06:20:10.099901  7890 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:20:10.100483  7890 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:10.106489  7905 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:10.106523  7898 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:10.106602  7890 server_base.cc:1061] running on GCE node
W20260812 06:20:10.106859  7901 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:10.107311  7890 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:10.107420  7890 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:10.107466  7890 hybrid_clock.cc:648] HybridClock initialized: now 1786515610107464 us; error 0 us; skew 500 ppm
I20260812 06:20:10.109119  7890 webserver.cc:533] Webserver started at http://127.7.180.190:45013/ using document root <none> and password file <none>
I20260812 06:20:10.109620  7890 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:10.109704  7890 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:10.109979  7890 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:10.111554  7890 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/master-0-root/instance:
uuid: "1d47d8bef77b4a70a051ecf49ca1024d"
format_stamp: "Formatted at 2026-08-12 06:20:10 on dist-test-slave-2p8l"
I20260812 06:20:10.114758  7890 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:20:10.116667  7913 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:10.117550  7890 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:20:10.117676  7890 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/master-0-root
uuid: "1d47d8bef77b4a70a051ecf49ca1024d"
format_stamp: "Formatted at 2026-08-12 06:20:10 on dist-test-slave-2p8l"
I20260812 06:20:10.117764  7890 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:10.148089  7890 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:10.148761  7890 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:20:10.148950  7890 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:10.156804  7890 rpc_server.cc:307] RPC server started. Bound to: 127.7.180.190:38587
I20260812 06:20:10.156809  8040 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.180.190:38587 every 8 connection(s)
I20260812 06:20:10.159083  8042 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:10.164384  8042 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d: Bootstrap starting.
I20260812 06:20:10.166749  8042 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:10.167625  8042 log.cc:826] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d: Log is configured to *not* fsync() on all Append() calls
I20260812 06:20:10.169301  8042 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d: No bootstrap required, opened a new log
I20260812 06:20:10.171984  8042 raft_consensus.cc:359] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" member_type: VOTER }
I20260812 06:20:10.172144  8042 raft_consensus.cc:385] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:10.172185  8042 raft_consensus.cc:740] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1d47d8bef77b4a70a051ecf49ca1024d, State: Initialized, Role: FOLLOWER
I20260812 06:20:10.172770  8042 consensus_queue.cc:260] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" member_type: VOTER }
I20260812 06:20:10.172910  8042 raft_consensus.cc:399] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:10.173010  8042 raft_consensus.cc:493] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:10.173175  8042 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:10.173964  8042 raft_consensus.cc:515] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" member_type: VOTER }
I20260812 06:20:10.174448  8042 leader_election.cc:304] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: 1d47d8bef77b4a70a051ecf49ca1024d; no voters: 
I20260812 06:20:10.174780  8042 leader_election.cc:290] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:10.174999  8048 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:10.175294  8048 raft_consensus.cc:697] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 1 LEADER]: Becoming Leader. State: Replica: 1d47d8bef77b4a70a051ecf49ca1024d, State: Running, Role: LEADER
I20260812 06:20:10.175743  8048 consensus_queue.cc:237] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 1, Majority size: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" member_type: VOTER }
I20260812 06:20:10.175798  8042 sys_catalog.cc:565] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:10.177656  8055 sys_catalog.cc:455] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [sys.catalog]: SysCatalogTable state changed. Reason: New leader 1d47d8bef77b4a70a051ecf49ca1024d. Latest consensus state: current_term: 1 leader_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" member_type: VOTER } }
I20260812 06:20:10.177768  8055 sys_catalog.cc:458] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:10.178025  8053 sys_catalog.cc:455] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1d47d8bef77b4a70a051ecf49ca1024d" member_type: VOTER } }
I20260812 06:20:10.178231  8053 sys_catalog.cc:458] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:10.178134  8081 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:10.178151  7890 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:10.180328  8081 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:10.184926  8081 catalog_manager.cc:1383] Generated new cluster ID: 4ee543de39884b8a927d209655cec554
I20260812 06:20:10.184986  8081 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:10.193917  8081 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:10.194738  8081 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:10.201403  8081 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d: Generated new TSK 0
I20260812 06:20:10.201885  8081 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:10.213491  7890 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:10.216470  8089 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:10.216675  7890 server_base.cc:1061] running on GCE node
W20260812 06:20:10.216533  8091 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:10.216535  8095 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:10.217099  7890 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:10.217165  7890 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:10.217193  7890 hybrid_clock.cc:648] HybridClock initialized: now 1786515610217193 us; error 0 us; skew 500 ppm
I20260812 06:20:10.218180  7890 webserver.cc:533] Webserver started at http://127.7.180.129:33423/ using document root <none> and password file <none>
I20260812 06:20:10.218346  7890 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:10.218421  7890 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:10.218499  7890 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:10.218876  7890 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/instance:
uuid: "b1a0d15e88ed43e090e05488e96cbd5c"
format_stamp: "Formatted at 2026-08-12 06:20:10 on dist-test-slave-2p8l"
I20260812 06:20:10.220368  7890 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:20:10.221441  8109 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:10.221766  7890 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:10.221828  7890 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root
uuid: "b1a0d15e88ed43e090e05488e96cbd5c"
format_stamp: "Formatted at 2026-08-12 06:20:10 on dist-test-slave-2p8l"
I20260812 06:20:10.221927  7890 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:10.227711  7890 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:10.228089  7890 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:10.228507  7890 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:10.229290  7890 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:10.229363  7890 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:10.229434  7890 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:10.229480  7890 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:10.236263  7890 rpc_server.cc:307] RPC server started. Bound to: 127.7.180.129:45261
I20260812 06:20:10.236325  8243 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.180.129:45261 every 8 connection(s)
I20260812 06:20:10.246217  8244 heartbeater.cc:344] Connected to a master server at 127.7.180.190:38587
I20260812 06:20:10.246450  8244 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:10.246871  8244 heartbeater.cc:507] Master 127.7.180.190:38587 requested a full tablet report, sending...
I20260812 06:20:10.248375  7957 ts_manager.cc:194] Registered new tserver with Master: b1a0d15e88ed43e090e05488e96cbd5c (127.7.180.129:45261)
I20260812 06:20:10.248633  7890 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.01175441s
I20260812 06:20:10.249913  7957 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:57076
I20260812 06:20:10.258255  7957 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:57082:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:20:10.273231  8161 tablet_service.cc:1511] Processing CreateTablet for tablet 7e0736e41a19467ba31319d6e5947d3a (DEFAULT_TABLE table=heavy-update-compaction-test [id=4853f16fdc944b4bb422ac1ac3c7df91]), partition=
I20260812 06:20:10.273729  8161 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 7e0736e41a19467ba31319d6e5947d3a. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:10.276428  8261 tablet_bootstrap.cc:492] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Bootstrap starting.
I20260812 06:20:10.277377  8261 tablet_bootstrap.cc:654] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:10.278591  8261 tablet_bootstrap.cc:492] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: No bootstrap required, opened a new log
I20260812 06:20:10.278707  8261 ts_tablet_manager.cc:1403] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:20:10.279114  8261 raft_consensus.cc:359] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b1a0d15e88ed43e090e05488e96cbd5c" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 45261 } }
I20260812 06:20:10.279232  8261 raft_consensus.cc:385] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:10.279279  8261 raft_consensus.cc:740] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: b1a0d15e88ed43e090e05488e96cbd5c, State: Initialized, Role: FOLLOWER
I20260812 06:20:10.279549  8261 consensus_queue.cc:260] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b1a0d15e88ed43e090e05488e96cbd5c" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 45261 } }
I20260812 06:20:10.279896  8261 raft_consensus.cc:399] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:10.279949  8261 raft_consensus.cc:493] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:10.279992  8261 raft_consensus.cc:3060] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:10.280694  8261 raft_consensus.cc:515] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b1a0d15e88ed43e090e05488e96cbd5c" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 45261 } }
I20260812 06:20:10.280836  8261 leader_election.cc:304] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: b1a0d15e88ed43e090e05488e96cbd5c; no voters: 
I20260812 06:20:10.281080  8261 leader_election.cc:290] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:10.281172  8263 raft_consensus.cc:2804] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:10.281368  8263 raft_consensus.cc:697] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 1 LEADER]: Becoming Leader. State: Replica: b1a0d15e88ed43e090e05488e96cbd5c, State: Running, Role: LEADER
I20260812 06:20:10.281456  8261 ts_tablet_manager.cc:1434] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:20:10.281672  8244 heartbeater.cc:499] Master 127.7.180.190:38587 was elected leader, sending a full tablet report...
I20260812 06:20:10.281981  8263 consensus_queue.cc:237] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 1, Majority size: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b1a0d15e88ed43e090e05488e96cbd5c" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 45261 } }
I20260812 06:20:10.284884  7957 catalog_manager.cc:5719] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c reported cstate change: term changed from 0 to 1, leader changed from <none> to b1a0d15e88ed43e090e05488e96cbd5c (127.7.180.129). New cstate: current_term: 1 leader_uuid: "b1a0d15e88ed43e090e05488e96cbd5c" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b1a0d15e88ed43e090e05488e96cbd5c" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 45261 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:10.348384  7890 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.056s	user 0.012s	sys 0.013s
I20260812 06:20:10.487314  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a): perf score=19.054940
I20260812 06:20:10.669118  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.181s	user 0.136s	sys 0.040s Metrics: {"bytes_written":13907425,"cfile_init":1,"compiler_manager_pool.queue_time_us":200,"delete_count":0,"dirs.queue_time_us":40,"dirs.run_cpu_time_us":246,"dirs.run_wall_time_us":993,"drs_written":1,"lbm_read_time_us":73,"lbm_reads_lt_1ms":4,"lbm_write_time_us":47820,"lbm_writes_lt_1ms":796,"mutex_wait_us":1134,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":202112,"thread_start_us":123,"threads_started":1,"update_count":1695}
I20260812 06:20:10.670388  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a): 16411396 bytes on disk
I20260812 06:20:10.670953  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4}
I20260812 06:20:10.671468  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=3.181125
I20260812 06:20:10.688337  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.017s	user 0.006s	sys 0.009s Metrics: {"bytes_written":4307783,"delete_count":0,"lbm_write_time_us":6765,"lbm_writes_lt_1ms":108,"reinsert_count":0,"update_count":525}
I20260812 06:20:10.688757  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling LogGCOp(7e0736e41a19467ba31319d6e5947d3a): free 20743880 bytes of WAL
I20260812 06:20:10.689047  8117 log_reader.cc:385] T 7e0736e41a19467ba31319d6e5947d3a: removed 2 log segments from log reader
I20260812 06:20:10.689118  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000001 (ops 1-6)
I20260812 06:20:10.689178  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000002 (ops 7-11)
I20260812 06:20:10.695199  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: LogGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.006s	user 0.000s	sys 0.005s Metrics: {}
I20260812 06:20:10.695547  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.196750
I20260812 06:20:10.705353  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.010s	user 0.007s	sys 0.001s Metrics: {"bytes_written":2297554,"delete_count":0,"lbm_write_time_us":3411,"lbm_writes_lt_1ms":59,"reinsert_count":0,"update_count":280}
I20260812 06:20:10.705816  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:10.882513  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.177s	user 0.124s	sys 0.053s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774762,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":582,"lbm_read_time_us":15556,"lbm_reads_lt_1ms":569,"lbm_write_time_us":27274,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"thread_start_us":422,"threads_started":5,"update_count":2500}
I20260812 06:20:10.883230  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=10.126437
I20260812 06:20:10.921662  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.038s	user 0.029s	sys 0.006s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16638,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:10.922619  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:10.940909  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.018s	user 0.011s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6454,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:10.941406  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:11.099370  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.158s	user 0.124s	sys 0.027s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":177,"lbm_read_time_us":9993,"lbm_reads_lt_1ms":464,"lbm_write_time_us":27562,"lbm_writes_lt_1ms":443,"mutex_wait_us":50,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":11392,"update_count":2000}
I20260812 06:20:11.100054  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=11.118625
I20260812 06:20:11.144920  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.045s	user 0.014s	sys 0.028s Metrics: {"bytes_written":12717736,"delete_count":0,"lbm_write_time_us":19469,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:11.145433  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:11.166162  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.021s	user 0.007s	sys 0.006s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4983,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:11.166563  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:11.178330  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.012s	user 0.010s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4006,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:11.178938  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:11.338016  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.159s	user 0.123s	sys 0.025s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774800,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":211,"lbm_read_time_us":10600,"lbm_reads_lt_1ms":573,"lbm_write_time_us":30390,"lbm_writes_lt_1ms":543,"mutex_wait_us":49,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":19328,"update_count":2500}
I20260812 06:20:11.338660  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:11.395339  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.056s	user 0.032s	sys 0.023s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":25735,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:11.395840  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:11.407593  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.012s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4469,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:11.408042  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:11.560401  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.152s	user 0.129s	sys 0.023s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774691,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":271,"lbm_read_time_us":10227,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30273,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1920,"update_count":2500}
I20260812 06:20:11.560920  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:11.606783  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.045s	user 0.031s	sys 0.012s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":19560,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:11.607281  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:11.757606  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.150s	user 0.090s	sys 0.049s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672159,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":160,"lbm_read_time_us":9765,"lbm_reads_lt_1ms":463,"lbm_write_time_us":24143,"lbm_writes_lt_1ms":443,"mutex_wait_us":49,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2816,"update_count":2000}
I20260812 06:20:11.758249  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:11.820839  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.060s	user 0.014s	sys 0.043s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":22376,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:11.821557  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:11.833554  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.012s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4483,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:11.834362  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:11.871747  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.037s	user 0.023s	sys 0.005s Metrics: {"bytes_written":1152508,"cfile_init":1,"dirs.queue_time_us":56,"dirs.run_cpu_time_us":209,"dirs.run_wall_time_us":1367,"drs_written":1,"lbm_read_time_us":60,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2018,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:11.872566  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling LogGCOp(7e0736e41a19467ba31319d6e5947d3a): free 111786282 bytes of WAL
I20260812 06:20:11.872781  8117 log_reader.cc:385] T 7e0736e41a19467ba31319d6e5947d3a: removed 11 log segments from log reader
I20260812 06:20:11.872850  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000003 (ops 12-16)
I20260812 06:20:11.872900  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000004 (ops 17-21)
I20260812 06:20:11.872952  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000005 (ops 22-26)
I20260812 06:20:11.872993  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000006 (ops 27-30)
I20260812 06:20:11.873027  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000007 (ops 31-35)
I20260812 06:20:11.873063  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000008 (ops 36-40)
I20260812 06:20:11.873098  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000009 (ops 41-44)
I20260812 06:20:11.873134  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000010 (ops 45-49)
I20260812 06:20:11.873169  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000011 (ops 50-54)
I20260812 06:20:11.873204  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000012 (ops 55-59)
I20260812 06:20:11.873239  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000013 (ops 60-64)
I20260812 06:20:11.898682  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: LogGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.026s	user 0.000s	sys 0.025s Metrics: {}
I20260812 06:20:11.899204  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a): 447 bytes on disk
I20260812 06:20:11.899793  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4}
I20260812 06:20:11.900310  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=3.181125
I20260812 06:20:11.922587  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.022s	user 0.009s	sys 0.008s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":7288,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:11.923064  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:11.932507  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3669,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:11.932914  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:12.148984  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.216s	user 0.148s	sys 0.062s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979738,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":448,"lbm_read_time_us":16150,"lbm_reads_lt_1ms":774,"lbm_write_time_us":40730,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":742,"mutex_wait_us":35,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":1280,"thread_start_us":79,"threads_started":1,"update_count":3500}
I20260812 06:20:12.149760  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:12.209983  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.060s	user 0.024s	sys 0.030s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":24731,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:12.210520  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:12.228158  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.017s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4495,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:12.228614  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:12.243572  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.015s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5830,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:12.244103  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:12.433317  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.189s	user 0.153s	sys 0.036s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877220,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":978,"lbm_read_time_us":14609,"lbm_reads_lt_1ms":673,"lbm_write_time_us":38299,"lbm_writes_lt_1ms":643,"mutex_wait_us":385,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":5376,"update_count":3000}
I20260812 06:20:12.433827  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:12.481303  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.047s	user 0.017s	sys 0.027s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":20747,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:20:12.481853  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:12.497581  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.016s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6323,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:12.498644  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:12.657784  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.159s	user 0.123s	sys 0.027s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774689,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":204,"lbm_read_time_us":11224,"lbm_reads_lt_1ms":564,"lbm_write_time_us":29392,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:12.658460  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:12.723798  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.065s	user 0.040s	sys 0.013s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24118,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:12.724327  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:12.736737  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.012s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4348,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:12.737402  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:12.924000  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.186s	user 0.119s	sys 0.058s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":367,"lbm_read_time_us":13208,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30760,"lbm_writes_lt_1ms":543,"mutex_wait_us":26,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":9344,"update_count":2500}
I20260812 06:20:12.924763  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:12.977313  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.052s	user 0.041s	sys 0.009s Metrics: {"bytes_written":16409898,"delete_count":0,"lbm_write_time_us":23182,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:12.977823  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:12.990618  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.013s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4925,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:12.991240  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:13.150920  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.159s	user 0.109s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774685,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":144,"lbm_read_time_us":11719,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27203,"lbm_writes_lt_1ms":543,"mutex_wait_us":23,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":18048,"update_count":2500}
I20260812 06:20:13.151525  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:13.215832  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.064s	user 0.033s	sys 0.024s Metrics: {"bytes_written":16409907,"delete_count":0,"lbm_write_time_us":21841,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:13.216368  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:13.227322  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4333,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:13.227746  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:13.258425  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.031s	user 0.022s	sys 0.004s Metrics: {"bytes_written":1152508,"cfile_init":1,"dirs.queue_time_us":70,"dirs.run_cpu_time_us":196,"dirs.run_wall_time_us":1524,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1474,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:13.259341  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a): 447 bytes on disk
I20260812 06:20:13.259902  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":62,"lbm_reads_lt_1ms":4}
I20260812 06:20:13.260463  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:13.437978  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.177s	user 0.145s	sys 0.032s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774694,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":537,"lbm_read_time_us":12776,"lbm_reads_lt_1ms":564,"lbm_write_time_us":28444,"lbm_writes_lt_1ms":543,"mutex_wait_us":295,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":2500}
I20260812 06:20:13.438787  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling LogGCOp(7e0736e41a19467ba31319d6e5947d3a): free 121006383 bytes of WAL
I20260812 06:20:13.439177  8117 log_reader.cc:385] T 7e0736e41a19467ba31319d6e5947d3a: removed 12 log segments from log reader
I20260812 06:20:13.439280  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000014 (ops 65-69)
I20260812 06:20:13.439348  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000015 (ops 70-74)
I20260812 06:20:13.439395  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000016 (ops 75-79)
I20260812 06:20:13.439451  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000017 (ops 80-84)
I20260812 06:20:13.439517  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000018 (ops 85-89)
I20260812 06:20:13.439566  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000019 (ops 90-94)
I20260812 06:20:13.439610  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000020 (ops 95-98)
I20260812 06:20:13.439652  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000021 (ops 99-103)
I20260812 06:20:13.439702  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000022 (ops 104-108)
I20260812 06:20:13.439757  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000023 (ops 109-113)
I20260812 06:20:13.439803  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000024 (ops 114-118)
I20260812 06:20:13.439864  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000025 (ops 119-123)
I20260812 06:20:13.469403  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: LogGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.030s	user 0.000s	sys 0.027s Metrics: {}
I20260812 06:20:13.469983  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=18.063937
I20260812 06:20:13.532407  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.062s	user 0.033s	sys 0.027s Metrics: {"bytes_written":20266173,"delete_count":0,"lbm_write_time_us":24173,"lbm_writes_lt_1ms":497,"reinsert_count":0,"update_count":2470}
I20260812 06:20:13.532922  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=3.181125
I20260812 06:20:13.543979  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4348807,"delete_count":0,"lbm_write_time_us":4225,"lbm_writes_lt_1ms":109,"reinsert_count":0,"update_count":530}
I20260812 06:20:13.544409  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:13.749488  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.205s	user 0.157s	sys 0.048s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877108,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":220,"lbm_read_time_us":14592,"lbm_reads_lt_1ms":672,"lbm_write_time_us":38275,"lbm_writes_lt_1ms":643,"mutex_wait_us":46,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":53888,"update_count":3000}
I20260812 06:20:13.750149  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:13.808933  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.059s	user 0.038s	sys 0.017s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":25535,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:13.809453  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:13.820577  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.011s	user 0.002s	sys 0.007s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4303,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:13.821174  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:14.005287  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.184s	user 0.124s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":219,"lbm_read_time_us":13490,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31194,"lbm_writes_lt_1ms":543,"mutex_wait_us":25,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2500}
I20260812 06:20:14.006147  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:14.062901  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.056s	user 0.028s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21213,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:14.063436  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:14.074759  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3978,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:14.075215  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:14.261998  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.187s	user 0.122s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":292,"lbm_read_time_us":11556,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31808,"lbm_writes_lt_1ms":543,"mutex_wait_us":23,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10880,"update_count":2500}
I20260812 06:20:14.262632  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:14.322403  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.060s	user 0.024s	sys 0.035s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":25515,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:20:14.322913  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:14.333199  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4004,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:14.333666  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:14.510581  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.177s	user 0.098s	sys 0.072s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1046,"lbm_read_time_us":12434,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27861,"lbm_writes_lt_1ms":543,"mutex_wait_us":385,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2816,"update_count":2500}
I20260812 06:20:14.511360  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:14.567219  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.053s	user 0.032s	sys 0.020s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23492,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:14.567795  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:14.582821  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.015s	user 0.008s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5686,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:14.583274  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:14.777498  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.194s	user 0.113s	sys 0.070s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774689,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":309,"lbm_read_time_us":11160,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30637,"lbm_writes_lt_1ms":543,"mutex_wait_us":38,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10496,"update_count":2500}
I20260812 06:20:14.778184  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=14.095187
I20260812 06:20:14.832050  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.054s	user 0.031s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21712,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:14.832590  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:14.844723  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4090,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:14.845275  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:14.875840  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushMRSOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.030s	user 0.029s	sys 0.000s Metrics: {"bytes_written":1275445,"cfile_init":1,"dirs.queue_time_us":70,"dirs.run_cpu_time_us":248,"dirs.run_wall_time_us":1512,"drs_written":1,"lbm_read_time_us":56,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1865,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:20:14.876581  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling LogGCOp(7e0736e41a19467ba31319d6e5947d3a): free 124710599 bytes of WAL
I20260812 06:20:14.876890  8117 log_reader.cc:385] T 7e0736e41a19467ba31319d6e5947d3a: removed 12 log segments from log reader
I20260812 06:20:14.876948  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000026 (ops 124-128)
I20260812 06:20:14.876987  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000027 (ops 129-133)
I20260812 06:20:14.877015  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000028 (ops 134-138)
I20260812 06:20:14.877067  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000029 (ops 139-143)
I20260812 06:20:14.877100  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000030 (ops 144-148)
I20260812 06:20:14.877127  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000031 (ops 149-153)
I20260812 06:20:14.877149  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000032 (ops 154-158)
I20260812 06:20:14.877178  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000033 (ops 159-163)
I20260812 06:20:14.877207  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000034 (ops 164-168)
I20260812 06:20:14.877241  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000035 (ops 169-173)
I20260812 06:20:14.877271  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000036 (ops 174-178)
I20260812 06:20:14.877297  8117 log.cc:1079] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/7e0736e41a19467ba31319d6e5947d3a/wal-000000037 (ops 179-183)
I20260812 06:20:14.910177  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: LogGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.033s	user 0.000s	sys 0.033s Metrics: {}
I20260812 06:20:14.914419  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a): 481 bytes on disk
I20260812 06:20:14.915001  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: UndoDeltaBlockGCOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":68,"lbm_reads_lt_1ms":4}
I20260812 06:20:14.915787  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:14.937778  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.022s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6481,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:14.938263  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:14.948452  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4049,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:14.948969  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:15.200534  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.251s	user 0.141s	sys 0.101s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979749,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":505,"lbm_read_time_us":16132,"lbm_reads_lt_1ms":774,"lbm_write_time_us":42096,"lbm_writes_lt_1ms":743,"mutex_wait_us":46,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":20480,"thread_start_us":78,"threads_started":1,"update_count":3500}
I20260812 06:20:15.201270  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=18.063937
I20260812 06:20:15.270790  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.069s	user 0.047s	sys 0.012s Metrics: {"bytes_written":20512319,"delete_count":0,"lbm_write_time_us":26783,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:15.271318  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a): perf score=2.188937
I20260812 06:20:15.281558  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: FlushDeltaMemStoresOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.010s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4063,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:15.282148  8245 maintenance_manager.cc:419] P b1a0d15e88ed43e090e05488e96cbd5c: Scheduling MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a): perf score=1.000000
I20260812 06:20:15.321517  7890 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.973s	user 1.768s	sys 0.219s
I20260812 06:20:15.395996  7890 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.074s	user 0.003s	sys 0.000s
I20260812 06:20:15.396881  7890 tablet_server.cc:179] TabletServer@127.7.180.129:0 shutting down...
I20260812 06:20:15.453732  8117 maintenance_manager.cc:643] P b1a0d15e88ed43e090e05488e96cbd5c: MajorDeltaCompactionOp(7e0736e41a19467ba31319d6e5947d3a) complete. Timing: real 0.171s	user 0.114s	sys 0.057s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877106,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":308,"lbm_read_time_us":15273,"lbm_reads_lt_1ms":668,"lbm_write_time_us":29865,"lbm_writes_lt_1ms":643,"mutex_wait_us":49,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":3000}
I20260812 06:20:15.454939  7890 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:15.455327  7890 tablet_replica.cc:333] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c: stopping tablet replica
I20260812 06:20:15.455567  7890 raft_consensus.cc:2243] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:15.455822  7890 raft_consensus.cc:2272] T 7e0736e41a19467ba31319d6e5947d3a P b1a0d15e88ed43e090e05488e96cbd5c [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:15.471985  7890 tablet_server.cc:196] TabletServer@127.7.180.129:0 shutdown complete.
I20260812 06:20:15.506767  7890 master.cc:562] Master@127.7.180.190:38587 shutting down...
I20260812 06:20:15.510565  7890 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:15.510764  7890 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:15.510857  7890 tablet_replica.cc:333] T 00000000000000000000000000000000 P 1d47d8bef77b4a70a051ecf49ca1024d: stopping tablet replica
I20260812 06:20:15.523057  7890 master.cc:584] Master@127.7.180.190:38587 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5520 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:20:15.630235  7890 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.7.180.190:36385
I20260812 06:20:15.630687  7890 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:20:15.633062  7890 server_base.cc:1061] running on GCE node
W20260812 06:20:15.633194  8294 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:15.633292  8296 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:15.633195  8300 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:15.633646  7890 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:15.633698  7890 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:15.633714  7890 hybrid_clock.cc:648] HybridClock initialized: now 1786515615633714 us; error 0 us; skew 500 ppm
I20260812 06:20:15.634546  7890 webserver.cc:533] Webserver started at http://127.7.180.190:46803/ using document root <none> and password file <none>
I20260812 06:20:15.634688  7890 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:15.634766  7890 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:15.634841  7890 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:15.635226  7890 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/master-0-root/instance:
uuid: "f8f3d0b8ad2c472e85c12c594765590c"
format_stamp: "Formatted at 2026-08-12 06:20:15 on dist-test-slave-2p8l"
I20260812 06:20:15.636765  7890 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:20:15.637733  8309 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:15.637998  7890 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:15.638209  7890 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/master-0-root
uuid: "f8f3d0b8ad2c472e85c12c594765590c"
format_stamp: "Formatted at 2026-08-12 06:20:15 on dist-test-slave-2p8l"
I20260812 06:20:15.638278  7890 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:15.656924  7890 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:15.657299  7890 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:15.661609  7890 rpc_server.cc:307] RPC server started. Bound to: 127.7.180.190:36385
I20260812 06:20:15.662092  8420 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.180.190:36385 every 8 connection(s)
I20260812 06:20:15.667044  8422 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:15.668818  8422 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c: Bootstrap starting.
I20260812 06:20:15.669621  8422 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:15.670655  8422 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c: No bootstrap required, opened a new log
I20260812 06:20:15.671044  8422 raft_consensus.cc:359] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f8f3d0b8ad2c472e85c12c594765590c" member_type: VOTER }
I20260812 06:20:15.671154  8422 raft_consensus.cc:385] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:15.671221  8422 raft_consensus.cc:740] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: f8f3d0b8ad2c472e85c12c594765590c, State: Initialized, Role: FOLLOWER
I20260812 06:20:15.671399  8422 consensus_queue.cc:260] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f8f3d0b8ad2c472e85c12c594765590c" member_type: VOTER }
I20260812 06:20:15.671502  8422 raft_consensus.cc:399] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:15.671546  8422 raft_consensus.cc:493] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:15.671602  8422 raft_consensus.cc:3060] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:15.672238  8422 raft_consensus.cc:515] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f8f3d0b8ad2c472e85c12c594765590c" member_type: VOTER }
I20260812 06:20:15.672389  8422 leader_election.cc:304] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: f8f3d0b8ad2c472e85c12c594765590c; no voters: 
I20260812 06:20:15.672585  8422 leader_election.cc:290] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:15.672715  8427 raft_consensus.cc:2804] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:15.672937  8427 raft_consensus.cc:697] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 1 LEADER]: Becoming Leader. State: Replica: f8f3d0b8ad2c472e85c12c594765590c, State: Running, Role: LEADER
I20260812 06:20:15.673034  8422 sys_catalog.cc:565] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:15.673117  8427 consensus_queue.cc:237] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 1, Majority size: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f8f3d0b8ad2c472e85c12c594765590c" member_type: VOTER }
I20260812 06:20:15.673585  8435 sys_catalog.cc:455] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [sys.catalog]: SysCatalogTable state changed. Reason: New leader f8f3d0b8ad2c472e85c12c594765590c. Latest consensus state: current_term: 1 leader_uuid: "f8f3d0b8ad2c472e85c12c594765590c" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f8f3d0b8ad2c472e85c12c594765590c" member_type: VOTER } }
I20260812 06:20:15.673570  8432 sys_catalog.cc:455] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "f8f3d0b8ad2c472e85c12c594765590c" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f8f3d0b8ad2c472e85c12c594765590c" member_type: VOTER } }
I20260812 06:20:15.673694  8435 sys_catalog.cc:458] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:15.673704  8432 sys_catalog.cc:458] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:15.674342  8441 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:15.675019  8441 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:15.675238  7890 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:15.676870  8441 catalog_manager.cc:1383] Generated new cluster ID: bf02291eb9a749cfaa2b0bcc8264a872
I20260812 06:20:15.676927  8441 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:15.687898  8441 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:15.688381  8441 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:15.694154  8441 catalog_manager.cc:6092] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c: Generated new TSK 0
I20260812 06:20:15.694309  8441 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:15.707475  7890 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:15.709525  8465 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:15.709525  8468 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:15.709647  8466 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:15.709867  7890 server_base.cc:1061] running on GCE node
I20260812 06:20:15.710019  7890 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:15.710115  7890 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:15.710150  7890 hybrid_clock.cc:648] HybridClock initialized: now 1786515615710148 us; error 0 us; skew 500 ppm
I20260812 06:20:15.711054  7890 webserver.cc:533] Webserver started at http://127.7.180.129:45537/ using document root <none> and password file <none>
I20260812 06:20:15.711225  7890 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:15.711283  7890 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:15.711378  7890 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:15.711750  7890 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/instance:
uuid: "7118b238c00e4c309d46c5eecb3aaae3"
format_stamp: "Formatted at 2026-08-12 06:20:15 on dist-test-slave-2p8l"
I20260812 06:20:15.713189  7890 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:20:15.714140  8477 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:15.714395  7890 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:15.714468  7890 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root
uuid: "7118b238c00e4c309d46c5eecb3aaae3"
format_stamp: "Formatted at 2026-08-12 06:20:15 on dist-test-slave-2p8l"
I20260812 06:20:15.714516  7890 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:15.722640  7890 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:15.722900  7890 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:15.723119  7890 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:15.723573  7890 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:15.723635  7890 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:15.723693  7890 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:15.723745  7890 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:15.728416  7890 rpc_server.cc:307] RPC server started. Bound to: 127.7.180.129:36297
I20260812 06:20:15.730154  8600 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.180.129:36297 every 8 connection(s)
I20260812 06:20:15.738415  8602 heartbeater.cc:344] Connected to a master server at 127.7.180.190:36385
I20260812 06:20:15.738517  8602 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:15.738729  8602 heartbeater.cc:507] Master 127.7.180.190:36385 requested a full tablet report, sending...
I20260812 06:20:15.739449  8340 ts_manager.cc:194] Registered new tserver with Master: 7118b238c00e4c309d46c5eecb3aaae3 (127.7.180.129:36297)
I20260812 06:20:15.740046  7890 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.010992546s
I20260812 06:20:15.740339  8340 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:42182
I20260812 06:20:15.746959  8340 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:42184:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:20:15.755000  8526 tablet_service.cc:1511] Processing CreateTablet for tablet 848416d1443a4458ad7b5835f8879506 (DEFAULT_TABLE table=heavy-update-compaction-test [id=ae5ce67e8da34649b985df4d317710b0]), partition=
I20260812 06:20:15.755290  8526 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 848416d1443a4458ad7b5835f8879506. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:15.757130  8620 tablet_bootstrap.cc:492] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Bootstrap starting.
I20260812 06:20:15.758033  8620 tablet_bootstrap.cc:654] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:15.759178  8620 tablet_bootstrap.cc:492] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: No bootstrap required, opened a new log
I20260812 06:20:15.759272  8620 ts_tablet_manager.cc:1403] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:20:15.759682  8620 raft_consensus.cc:359] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7118b238c00e4c309d46c5eecb3aaae3" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 36297 } }
I20260812 06:20:15.759764  8620 raft_consensus.cc:385] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:15.759824  8620 raft_consensus.cc:740] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 7118b238c00e4c309d46c5eecb3aaae3, State: Initialized, Role: FOLLOWER
I20260812 06:20:15.759976  8620 consensus_queue.cc:260] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7118b238c00e4c309d46c5eecb3aaae3" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 36297 } }
I20260812 06:20:15.760071  8620 raft_consensus.cc:399] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:15.760121  8620 raft_consensus.cc:493] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:15.760177  8620 raft_consensus.cc:3060] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:15.761029  8620 raft_consensus.cc:515] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7118b238c00e4c309d46c5eecb3aaae3" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 36297 } }
I20260812 06:20:15.761204  8620 leader_election.cc:304] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: 7118b238c00e4c309d46c5eecb3aaae3; no voters: 
I20260812 06:20:15.761420  8620 leader_election.cc:290] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:15.761544  8622 raft_consensus.cc:2804] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:15.761750  8622 raft_consensus.cc:697] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 1 LEADER]: Becoming Leader. State: Replica: 7118b238c00e4c309d46c5eecb3aaae3, State: Running, Role: LEADER
I20260812 06:20:15.761768  8620 ts_tablet_manager.cc:1434] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Time spent starting tablet: real 0.002s	user 0.000s	sys 0.003s
I20260812 06:20:15.761781  8602 heartbeater.cc:499] Master 127.7.180.190:36385 was elected leader, sending a full tablet report...
I20260812 06:20:15.761940  8622 consensus_queue.cc:237] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 1, Majority size: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7118b238c00e4c309d46c5eecb3aaae3" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 36297 } }
I20260812 06:20:15.763208  8340 catalog_manager.cc:5719] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 reported cstate change: term changed from 0 to 1, leader changed from <none> to 7118b238c00e4c309d46c5eecb3aaae3 (127.7.180.129). New cstate: current_term: 1 leader_uuid: "7118b238c00e4c309d46c5eecb3aaae3" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7118b238c00e4c309d46c5eecb3aaae3" member_type: VOTER last_known_addr { host: "127.7.180.129" port: 36297 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:15.817627  7890 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.051s	user 0.018s	sys 0.004s
I20260812 06:20:15.980641  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushMRSOp(848416d1443a4458ad7b5835f8879506): perf score=23.023690
I20260812 06:20:16.144008  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushMRSOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.163s	user 0.137s	sys 0.024s Metrics: {"bytes_written":12307490,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":77,"dirs.run_cpu_time_us":194,"dirs.run_wall_time_us":856,"drs_written":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4,"lbm_write_time_us":42842,"lbm_writes_lt_1ms":857,"peak_mem_usage":0,"reinsert_count":0,"rows_written":105,"spinlock_wait_cycles":896,"update_count":1500}
I20260812 06:20:16.144668  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling LogGCOp(848416d1443a4458ad7b5835f8879506): free 20743831 bytes of WAL
I20260812 06:20:16.144901  8484 log_reader.cc:385] T 848416d1443a4458ad7b5835f8879506: removed 2 log segments from log reader
I20260812 06:20:16.144948  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000001 (ops 1-6)
I20260812 06:20:16.144977  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000002 (ops 7-11)
I20260812 06:20:16.149338  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: LogGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.004s	user 0.000s	sys 0.001s Metrics: {}
I20260812 06:20:16.149689  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:16.163255  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.013s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4691,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:16.163905  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:16.337997  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.174s	user 0.111s	sys 0.056s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713272,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":562,"lbm_read_time_us":11693,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29490,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2304,"thread_start_us":328,"threads_started":5,"update_count":2000}
I20260812 06:20:16.338733  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506): 20513811 bytes on disk
I20260812 06:20:16.339197  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":42,"lbm_reads_lt_1ms":4}
I20260812 06:20:16.339668  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:16.384589  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.045s	user 0.030s	sys 0.013s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":20213,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:16.385054  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:16.536660  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.151s	user 0.113s	sys 0.032s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20713154,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":321,"lbm_read_time_us":11283,"lbm_reads_lt_1ms":467,"lbm_write_time_us":23543,"lbm_writes_lt_1ms":443,"mutex_wait_us":20,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":7040,"update_count":2000}
I20260812 06:20:16.537362  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:16.585001  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.047s	user 0.025s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19191,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:16.585532  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:16.601091  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.015s	user 0.009s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5866,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:16.601722  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:16.789033  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.187s	user 0.121s	sys 0.059s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":168,"lbm_read_time_us":12130,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28576,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1664,"update_count":2500}
I20260812 06:20:16.789547  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:16.845259  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.056s	user 0.032s	sys 0.020s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":26156,"lbm_writes_lt_1ms":403,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2000}
I20260812 06:20:16.845770  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:16.861922  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.016s	user 0.013s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6092,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:16.862421  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:17.020949  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.158s	user 0.108s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815681,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":233,"lbm_read_time_us":10736,"lbm_reads_lt_1ms":564,"lbm_write_time_us":28843,"lbm_writes_lt_1ms":543,"mutex_wait_us":96,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10240,"update_count":2500}
I20260812 06:20:17.021709  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:17.066423  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.044s	user 0.017s	sys 0.027s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":19806,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:17.067152  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:17.083405  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.015s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6224,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:17.083858  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:17.245631  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.162s	user 0.133s	sys 0.028s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815682,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":233,"lbm_read_time_us":10381,"lbm_reads_lt_1ms":564,"lbm_write_time_us":31195,"lbm_writes_lt_1ms":543,"mutex_wait_us":25,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2500}
I20260812 06:20:17.251088  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:17.311089  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.060s	user 0.027s	sys 0.032s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":26517,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:17.311645  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:17.330387  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.019s	user 0.017s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6738,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:17.330829  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushMRSOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:17.356346  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushMRSOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.025s	user 0.024s	sys 0.000s Metrics: {"bytes_written":1152508,"cfile_init":1,"dirs.queue_time_us":41,"dirs.run_cpu_time_us":189,"dirs.run_wall_time_us":1624,"drs_written":1,"lbm_read_time_us":62,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1488,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:17.356892  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling LogGCOp(848416d1443a4458ad7b5835f8879506): free 112692409 bytes of WAL
I20260812 06:20:17.357106  8484 log_reader.cc:385] T 848416d1443a4458ad7b5835f8879506: removed 11 log segments from log reader
I20260812 06:20:17.357169  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000003 (ops 12-16)
I20260812 06:20:17.357231  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000004 (ops 17-21)
I20260812 06:20:17.357270  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000005 (ops 22-26)
I20260812 06:20:17.357309  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000006 (ops 27-31)
I20260812 06:20:17.357344  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000007 (ops 32-36)
I20260812 06:20:17.357389  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000008 (ops 37-41)
I20260812 06:20:17.357424  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000009 (ops 42-46)
I20260812 06:20:17.357460  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000010 (ops 47-51)
I20260812 06:20:17.357497  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000011 (ops 52-56)
I20260812 06:20:17.357533  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000012 (ops 57-61)
I20260812 06:20:17.357569  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000013 (ops 62-66)
I20260812 06:20:17.384629  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: LogGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.028s	user 0.000s	sys 0.025s Metrics: {}
I20260812 06:20:17.385150  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506): 448 bytes on disk
I20260812 06:20:17.385622  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4}
I20260812 06:20:17.386198  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=3.181125
I20260812 06:20:17.403928  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.018s	user 0.014s	sys 0.001s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":7227,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:17.404309  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:17.413487  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3541,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:17.413858  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:17.678319  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.264s	user 0.153s	sys 0.101s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020733,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":479,"lbm_read_time_us":16419,"lbm_reads_lt_1ms":774,"lbm_write_time_us":47873,"lbm_writes_lt_1ms":743,"mutex_wait_us":25,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":84992,"thread_start_us":77,"threads_started":1,"update_count":3500}
I20260812 06:20:17.679579  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=18.063937
I20260812 06:20:17.751245  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.071s	user 0.023s	sys 0.047s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":27366,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:17.751837  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:17.765154  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4825,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:17.765807  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:17.968634  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.203s	user 0.155s	sys 0.047s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918097,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":272,"lbm_read_time_us":15285,"lbm_reads_lt_1ms":672,"lbm_write_time_us":34832,"lbm_writes_lt_1ms":643,"mutex_wait_us":25,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":10112,"update_count":3000}
I20260812 06:20:17.969269  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:18.023245  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.054s	user 0.022s	sys 0.024s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":21035,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:18.023785  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:18.038807  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.015s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5750,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:18.039394  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:18.218196  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.179s	user 0.099s	sys 0.069s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1038,"lbm_read_time_us":11720,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30273,"lbm_writes_lt_1ms":543,"mutex_wait_us":20,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7552,"update_count":2500}
I20260812 06:20:18.218719  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:18.282585  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.064s	user 0.035s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24262,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:18.283069  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:18.294307  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4420,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:18.294776  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:18.482864  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.188s	user 0.128s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815682,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":572,"lbm_read_time_us":13476,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31963,"lbm_writes_lt_1ms":543,"mutex_wait_us":252,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":18944,"update_count":2500}
I20260812 06:20:18.483510  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:18.537415  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.054s	user 0.019s	sys 0.031s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":18839,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:18.538002  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:18.548991  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4346,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:18.549423  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:18.745290  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.196s	user 0.129s	sys 0.064s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":163,"lbm_read_time_us":13705,"lbm_reads_lt_1ms":572,"lbm_write_time_us":34392,"lbm_writes_lt_1ms":543,"mutex_wait_us":45,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11520,"update_count":2500}
I20260812 06:20:18.746104  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=11.118625
I20260812 06:20:18.778735  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.032s	user 0.023s	sys 0.008s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":14549,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:18.779198  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:18.793759  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5253,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:18.794441  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:18.931986  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.137s	user 0.098s	sys 0.039s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713265,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":168,"lbm_read_time_us":8205,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29488,"lbm_writes_lt_1ms":443,"mutex_wait_us":25,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":4352,"update_count":2000}
I20260812 06:20:18.932902  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=10.126437
I20260812 06:20:18.972939  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.040s	user 0.027s	sys 0.009s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":17083,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:18.973474  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:18.986209  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.013s	user 0.005s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4522,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:18.986716  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushMRSOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:19.018239  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushMRSOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.031s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":224,"dirs.run_wall_time_us":1317,"drs_written":1,"lbm_read_time_us":43,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1563,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:20:19.018880  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling LogGCOp(848416d1443a4458ad7b5835f8879506): free 132571259 bytes of WAL
I20260812 06:20:19.019119  8484 log_reader.cc:385] T 848416d1443a4458ad7b5835f8879506: removed 13 log segments from log reader
I20260812 06:20:19.019164  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000014 (ops 67-71)
I20260812 06:20:19.019194  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000015 (ops 72-76)
I20260812 06:20:19.019212  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000016 (ops 77-81)
I20260812 06:20:19.019272  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000017 (ops 82-86)
I20260812 06:20:19.019315  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000018 (ops 87-91)
I20260812 06:20:19.019356  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000019 (ops 92-96)
I20260812 06:20:19.019392  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000020 (ops 97-101)
I20260812 06:20:19.019436  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000021 (ops 102-106)
I20260812 06:20:19.019476  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000022 (ops 107-110)
I20260812 06:20:19.019516  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000023 (ops 111-115)
I20260812 06:20:19.019564  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000024 (ops 116-120)
I20260812 06:20:19.019603  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000025 (ops 121-124)
I20260812 06:20:19.019644  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000026 (ops 125-129)
I20260812 06:20:19.052524  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: LogGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.033s	user 0.000s	sys 0.031s Metrics: {}
I20260812 06:20:19.053052  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=5.165500
I20260812 06:20:19.072772  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.019s	user 0.009s	sys 0.008s Metrics: {"bytes_written":7097423,"delete_count":0,"lbm_write_time_us":8010,"lbm_writes_lt_1ms":176,"reinsert_count":0,"update_count":865}
I20260812 06:20:19.073287  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:19.246328  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.173s	user 0.121s	sys 0.047s Metrics: {"cfile_cache_miss":606,"cfile_cache_miss_bytes":27810562,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":6194,"dirs.run_cpu_time_us":574,"dirs.run_wall_time_us":3761,"lbm_read_time_us":12199,"lbm_reads_lt_1ms":642,"lbm_write_time_us":36227,"lbm_writes_lt_1ms":616,"mutex_wait_us":3192,"peak_mem_usage":71313375,"reinsert_count":0,"spinlock_wait_cycles":18688,"thread_start_us":89,"threads_started":1,"update_count":2865}
I20260812 06:20:19.246959  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506): 482 bytes on disk
I20260812 06:20:19.247279  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":43,"lbm_reads_lt_1ms":4}
I20260812 06:20:19.247768  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=15.087375
I20260812 06:20:19.300662  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.053s	user 0.041s	sys 0.007s Metrics: {"bytes_written":17517554,"delete_count":0,"lbm_write_time_us":23053,"lbm_writes_lt_1ms":430,"reinsert_count":0,"update_count":2135}
I20260812 06:20:19.301201  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:19.313324  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.012s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4583,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:19.313906  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:19.483703  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.170s	user 0.121s	sys 0.042s Metrics: {"cfile_cache_miss":559,"cfile_cache_miss_bytes":25923336,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":588,"lbm_read_time_us":12729,"lbm_reads_lt_1ms":599,"lbm_write_time_us":33443,"lbm_writes_lt_1ms":570,"mutex_wait_us":41,"peak_mem_usage":66304613,"reinsert_count":0,"spinlock_wait_cycles":2688,"update_count":2635}
I20260812 06:20:19.484169  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:19.534615  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.050s	user 0.034s	sys 0.008s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19044,"lbm_writes_lt_1ms":403,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2000}
I20260812 06:20:19.535125  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:19.547974  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.013s	user 0.002s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4595,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:19.548602  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:19.705279  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.157s	user 0.138s	sys 0.012s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1037,"lbm_read_time_us":11639,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30439,"lbm_writes_lt_1ms":543,"mutex_wait_us":345,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10112,"update_count":2500}
I20260812 06:20:19.705899  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:19.754863  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.049s	user 0.024s	sys 0.023s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":21517,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:19.755353  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:19.767657  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.012s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4534,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:19.768121  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:19.928414  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.160s	user 0.114s	sys 0.031s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":155,"lbm_read_time_us":9223,"lbm_reads_lt_1ms":564,"lbm_write_time_us":31117,"lbm_writes_lt_1ms":543,"mutex_wait_us":54,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3968,"update_count":2500}
I20260812 06:20:19.929105  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:19.981644  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.052s	user 0.023s	sys 0.024s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":22121,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:19.982230  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:19.993204  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.011s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3963,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:19.994422  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:20.166357  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.172s	user 0.124s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":293,"lbm_read_time_us":11955,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28154,"lbm_writes_lt_1ms":543,"mutex_wait_us":41,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2560,"update_count":2500}
I20260812 06:20:20.166939  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:20.220774  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.054s	user 0.029s	sys 0.018s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22223,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:20.221366  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:20.383893  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.162s	user 0.109s	sys 0.043s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20713152,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":322,"lbm_read_time_us":10506,"lbm_reads_lt_1ms":463,"lbm_write_time_us":26230,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":6144,"update_count":2000}
I20260812 06:20:20.384693  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=14.095187
I20260812 06:20:20.436592  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.052s	user 0.016s	sys 0.033s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23685,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:20.437155  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:20.462674  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.025s	user 0.010s	sys 0.012s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6051,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:20.463179  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushMRSOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:20.528934  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushMRSOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.066s	user 0.036s	sys 0.002s Metrics: {"bytes_written":1316417,"cfile_init":1,"dirs.queue_time_us":80,"dirs.run_cpu_time_us":195,"dirs.run_wall_time_us":1315,"drs_written":1,"lbm_read_time_us":49,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2067,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:20:20.529800  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling LogGCOp(848416d1443a4458ad7b5835f8879506): free 133024705 bytes of WAL
I20260812 06:20:20.530035  8484 log_reader.cc:385] T 848416d1443a4458ad7b5835f8879506: removed 13 log segments from log reader
I20260812 06:20:20.530102  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000027 (ops 130-134)
I20260812 06:20:20.530160  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000028 (ops 135-139)
I20260812 06:20:20.530215  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000029 (ops 140-144)
I20260812 06:20:20.530268  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000030 (ops 145-149)
I20260812 06:20:20.530308  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000031 (ops 150-154)
I20260812 06:20:20.530331  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000032 (ops 155-159)
I20260812 06:20:20.530371  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000033 (ops 160-164)
I20260812 06:20:20.530407  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000034 (ops 165-169)
I20260812 06:20:20.530445  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000035 (ops 170-174)
I20260812 06:20:20.530484  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000036 (ops 175-178)
I20260812 06:20:20.530524  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000037 (ops 179-183)
I20260812 06:20:20.530562  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000038 (ops 184-188)
I20260812 06:20:20.530604  8484 log.cc:1079] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: Deleting log segment in path: /tmp/dist-test-taskpPETJq/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515610088790-7890-0/minicluster-data/ts-0-root/wals/848416d1443a4458ad7b5835f8879506/wal-000000039 (ops 189-193)
I20260812 06:20:20.561765  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: LogGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.032s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:20:20.562357  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506): 493 bytes on disk
I20260812 06:20:20.562834  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: UndoDeltaBlockGCOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4}
I20260812 06:20:20.563503  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=7.149875
I20260812 06:20:20.587020  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.023s	user 0.014s	sys 0.008s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":9821,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:20:20.587498  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506): perf score=2.188937
I20260812 06:20:20.601260  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: FlushDeltaMemStoresOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5380,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:20.601733  8603 maintenance_manager.cc:419] P 7118b238c00e4c309d46c5eecb3aaae3: Scheduling MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506): perf score=1.000000
I20260812 06:20:20.679791  7890 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.862s	user 1.787s	sys 0.185s
I20260812 06:20:20.793545  7890 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.113s	user 0.001s	sys 0.000s
I20260812 06:20:20.794081  7890 tablet_server.cc:179] TabletServer@127.7.180.129:0 shutting down...
I20260812 06:20:20.846935  8484 maintenance_manager.cc:643] P 7118b238c00e4c309d46c5eecb3aaae3: MajorDeltaCompactionOp(848416d1443a4458ad7b5835f8879506) complete. Timing: real 0.245s	user 0.158s	sys 0.087s Metrics: {"cfile_cache_miss":834,"cfile_cache_miss_bytes":37123150,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":645,"lbm_read_time_us":17282,"lbm_reads_lt_1ms":870,"lbm_write_time_us":38970,"lbm_writes_lt_1ms":843,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":9856,"thread_start_us":88,"threads_started":1,"update_count":4000}
I20260812 06:20:20.847774  7890 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:20.848210  7890 tablet_replica.cc:333] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3: stopping tablet replica
I20260812 06:20:20.848376  7890 raft_consensus.cc:2243] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:20.848543  7890 raft_consensus.cc:2272] T 848416d1443a4458ad7b5835f8879506 P 7118b238c00e4c309d46c5eecb3aaae3 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:20.854336  7890 tablet_server.cc:196] TabletServer@127.7.180.129:0 shutdown complete.
I20260812 06:20:20.918733  7890 master.cc:562] Master@127.7.180.190:36385 shutting down...
I20260812 06:20:20.922323  7890 raft_consensus.cc:2243] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:20.922531  7890 raft_consensus.cc:2272] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:20.922634  7890 tablet_replica.cc:333] T 00000000000000000000000000000000 P f8f3d0b8ad2c472e85c12c594765590c: stopping tablet replica
I20260812 06:20:20.934842  7890 master.cc:584] Master@127.7.180.190:36385 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5404 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10925 ms total)

[----------] Global test environment tear-down
[==========] 2 tests from 1 test suite ran. (10925 ms total)
[  PASSED  ] 2 tests.
