[==========] 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:19:54.996641  5530 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.5.102.190:42865
I20260812 06:19:54.997639  5530 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:19:54.998270  5530 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:55.004726  5539 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:19:55.004703  5530 server_base.cc:1061] running on GCE node
W20260812 06:19:55.004884  5542 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:19:55.004963  5538 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:19:55.005595  5530 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:55.005730  5530 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:19:55.005784  5530 hybrid_clock.cc:648] HybridClock initialized: now 1786515595005781 us; error 0 us; skew 500 ppm
I20260812 06:19:55.007751  5530 webserver.cc:533] Webserver started at http://127.5.102.190:39243/ using document root <none> and password file <none>
I20260812 06:19:55.008399  5530 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:55.008491  5530 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:55.008751  5530 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:55.010457  5530 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/master-0-root/instance:
uuid: "a9b4321f46de42b2b012b5345fc28265"
format_stamp: "Formatted at 2026-08-12 06:19:55 on dist-test-slave-t3q3"
I20260812 06:19:55.014039  5530 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.001s	sys 0.004s
I20260812 06:19:55.016108  5551 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:19:55.017061  5530 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
I20260812 06:19:55.017199  5530 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/master-0-root
uuid: "a9b4321f46de42b2b012b5345fc28265"
format_stamp: "Formatted at 2026-08-12 06:19:55 on dist-test-slave-t3q3"
I20260812 06:19:55.017307  5530 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-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:19:55.030191  5530 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:55.030808  5530 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:19:55.030998  5530 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:55.038918  5530 rpc_server.cc:307] RPC server started. Bound to: 127.5.102.190:42865
I20260812 06:19:55.038970  5638 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.102.190:42865 every 8 connection(s)
I20260812 06:19:55.041369  5639 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:19:55.047043  5639 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265: Bootstrap starting.
I20260812 06:19:55.049508  5639 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:55.050431  5639 log.cc:826] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265: Log is configured to *not* fsync() on all Append() calls
I20260812 06:19:55.052409  5639 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265: No bootstrap required, opened a new log
I20260812 06:19:55.055306  5639 raft_consensus.cc:359] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a9b4321f46de42b2b012b5345fc28265" member_type: VOTER }
I20260812 06:19:55.055491  5639 raft_consensus.cc:385] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:55.055537  5639 raft_consensus.cc:740] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a9b4321f46de42b2b012b5345fc28265, State: Initialized, Role: FOLLOWER
I20260812 06:19:55.056213  5639 consensus_queue.cc:260] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [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: "a9b4321f46de42b2b012b5345fc28265" member_type: VOTER }
I20260812 06:19:55.056366  5639 raft_consensus.cc:399] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:55.056409  5639 raft_consensus.cc:493] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:55.056514  5639 raft_consensus.cc:3060] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:55.057345  5639 raft_consensus.cc:515] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a9b4321f46de42b2b012b5345fc28265" member_type: VOTER }
I20260812 06:19:55.057744  5639 leader_election.cc:304] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [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: a9b4321f46de42b2b012b5345fc28265; no voters: 
I20260812 06:19:55.058035  5639 leader_election.cc:290] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:55.058198  5647 raft_consensus.cc:2804] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:55.058465  5647 raft_consensus.cc:697] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 1 LEADER]: Becoming Leader. State: Replica: a9b4321f46de42b2b012b5345fc28265, State: Running, Role: LEADER
I20260812 06:19:55.058917  5647 consensus_queue.cc:237] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [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: "a9b4321f46de42b2b012b5345fc28265" member_type: VOTER }
I20260812 06:19:55.059187  5639 sys_catalog.cc:565] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:19:55.060995  5649 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [sys.catalog]: SysCatalogTable state changed. Reason: New leader a9b4321f46de42b2b012b5345fc28265. Latest consensus state: current_term: 1 leader_uuid: "a9b4321f46de42b2b012b5345fc28265" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a9b4321f46de42b2b012b5345fc28265" member_type: VOTER } }
I20260812 06:19:55.061031  5648 sys_catalog.cc:455] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "a9b4321f46de42b2b012b5345fc28265" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a9b4321f46de42b2b012b5345fc28265" member_type: VOTER } }
I20260812 06:19:55.061152  5648 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:55.061151  5649 sys_catalog.cc:458] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:55.061712  5530 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:19:55.061708  5662 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:19:55.064051  5662 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:19:55.069075  5662 catalog_manager.cc:1383] Generated new cluster ID: 6b847bf8f54244e1aab88f92e7c2b738
I20260812 06:19:55.069149  5662 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:19:55.096338  5662 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:19:55.097373  5662 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:19:55.120733  5662 catalog_manager.cc:6092] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265: Generated new TSK 0
I20260812 06:19:55.121552  5662 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:19:55.126932  5530 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:55.129515  5675 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:19:55.129622  5680 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:19:55.129814  5530 server_base.cc:1061] running on GCE node
W20260812 06:19:55.129520  5676 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:19:55.130102  5530 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:55.130164  5530 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:19:55.130190  5530 hybrid_clock.cc:648] HybridClock initialized: now 1786515595130189 us; error 0 us; skew 500 ppm
I20260812 06:19:55.131158  5530 webserver.cc:533] Webserver started at http://127.5.102.129:42803/ using document root <none> and password file <none>
I20260812 06:19:55.131353  5530 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:55.131515  5530 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:55.131621  5530 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:55.132030  5530 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/instance:
uuid: "7be720521b6f4ecfa8540433feccfc17"
format_stamp: "Formatted at 2026-08-12 06:19:55 on dist-test-slave-t3q3"
I20260812 06:19:55.133591  5530 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:55.134591  5690 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:19:55.134845  5530 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:55.134919  5530 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root
uuid: "7be720521b6f4ecfa8540433feccfc17"
format_stamp: "Formatted at 2026-08-12 06:19:55 on dist-test-slave-t3q3"
I20260812 06:19:55.135008  5530 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-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:19:55.143630  5530 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:55.144155  5530 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:55.144723  5530 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:19:55.145622  5530 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:19:55.145674  5530 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:55.145745  5530 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:19:55.145783  5530 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:55.152741  5530 rpc_server.cc:307] RPC server started. Bound to: 127.5.102.129:41437
I20260812 06:19:55.152784  5786 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.102.129:41437 every 8 connection(s)
I20260812 06:19:55.166977  5787 heartbeater.cc:344] Connected to a master server at 127.5.102.190:42865
I20260812 06:19:55.167308  5787 heartbeater.cc:461] Registering TS with master...
I20260812 06:19:55.167816  5787 heartbeater.cc:507] Master 127.5.102.190:42865 requested a full tablet report, sending...
I20260812 06:19:55.169451  5582 ts_manager.cc:194] Registered new tserver with Master: 7be720521b6f4ecfa8540433feccfc17 (127.5.102.129:41437)
I20260812 06:19:55.169531  5530 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.016086628s
I20260812 06:19:55.170964  5582 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:48654
I20260812 06:19:55.179816  5582 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:48666:
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:19:55.194768  5736 tablet_service.cc:1511] Processing CreateTablet for tablet 3e634696fac5438891387fad21c8efca (DEFAULT_TABLE table=heavy-update-compaction-test [id=1c7219b753b04f2dbdbeacaa260e1691]), partition=
I20260812 06:19:55.195271  5736 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 3e634696fac5438891387fad21c8efca. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:55.197749  5806 tablet_bootstrap.cc:492] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Bootstrap starting.
I20260812 06:19:55.198840  5806 tablet_bootstrap.cc:654] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:55.200012  5806 tablet_bootstrap.cc:492] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: No bootstrap required, opened a new log
I20260812 06:19:55.200186  5806 ts_tablet_manager.cc:1403] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:19:55.200671  5806 raft_consensus.cc:359] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7be720521b6f4ecfa8540433feccfc17" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 41437 } }
I20260812 06:19:55.200776  5806 raft_consensus.cc:385] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:55.200799  5806 raft_consensus.cc:740] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 7be720521b6f4ecfa8540433feccfc17, State: Initialized, Role: FOLLOWER
I20260812 06:19:55.200960  5806 consensus_queue.cc:260] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [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: "7be720521b6f4ecfa8540433feccfc17" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 41437 } }
I20260812 06:19:55.201064  5806 raft_consensus.cc:399] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:55.201093  5806 raft_consensus.cc:493] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:55.201177  5806 raft_consensus.cc:3060] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:55.202270  5806 raft_consensus.cc:515] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7be720521b6f4ecfa8540433feccfc17" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 41437 } }
I20260812 06:19:55.202490  5806 leader_election.cc:304] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [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: 7be720521b6f4ecfa8540433feccfc17; no voters: 
I20260812 06:19:55.202780  5806 leader_election.cc:290] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:55.203087  5812 raft_consensus.cc:2804] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:55.203168  5806 ts_tablet_manager.cc:1434] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:19:55.203289  5812 raft_consensus.cc:697] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 1 LEADER]: Becoming Leader. State: Replica: 7be720521b6f4ecfa8540433feccfc17, State: Running, Role: LEADER
I20260812 06:19:55.203459  5812 consensus_queue.cc:237] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [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: "7be720521b6f4ecfa8540433feccfc17" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 41437 } }
I20260812 06:19:55.203586  5787 heartbeater.cc:499] Master 127.5.102.190:42865 was elected leader, sending a full tablet report...
I20260812 06:19:55.206336  5582 catalog_manager.cc:5719] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 reported cstate change: term changed from 0 to 1, leader changed from <none> to 7be720521b6f4ecfa8540433feccfc17 (127.5.102.129). New cstate: current_term: 1 leader_uuid: "7be720521b6f4ecfa8540433feccfc17" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7be720521b6f4ecfa8540433feccfc17" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 41437 } health_report { overall_health: HEALTHY } } }
I20260812 06:19:55.277382  5530 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.061s	user 0.012s	sys 0.016s
I20260812 06:19:55.403951  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushMRSOp(3e634696fac5438891387fad21c8efca): perf score=15.086190
I20260812 06:19:55.573060  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushMRSOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.169s	user 0.113s	sys 0.053s Metrics: {"bytes_written":13333099,"cfile_init":1,"compiler_manager_pool.queue_time_us":219,"delete_count":0,"dirs.queue_time_us":63,"dirs.run_cpu_time_us":240,"dirs.run_wall_time_us":845,"drs_written":1,"lbm_read_time_us":91,"lbm_reads_lt_1ms":4,"lbm_write_time_us":42276,"lbm_writes_lt_1ms":692,"mutex_wait_us":1006,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":131456,"thread_start_us":126,"threads_started":1,"update_count":1625}
I20260812 06:19:55.574283  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling LogGCOp(3e634696fac5438891387fad21c8efca): free 20743880 bytes of WAL
I20260812 06:19:55.574605  5702 log_reader.cc:385] T 3e634696fac5438891387fad21c8efca: removed 2 log segments from log reader
I20260812 06:19:55.574668  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000001 (ops 1-6)
I20260812 06:19:55.574720  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000002 (ops 7-11)
I20260812 06:19:55.580104  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: LogGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.006s	user 0.002s	sys 0.003s Metrics: {}
I20260812 06:19:55.580459  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling UndoDeltaBlockGCOp(3e634696fac5438891387fad21c8efca): 12719216 bytes on disk
I20260812 06:19:55.581076  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: UndoDeltaBlockGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":65,"lbm_reads_lt_1ms":4}
I20260812 06:19:55.581482  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=4.173312
I20260812 06:19:55.601948  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.020s	user 0.010s	sys 0.009s Metrics: {"bytes_written":6071825,"delete_count":0,"lbm_write_time_us":8471,"lbm_writes_lt_1ms":151,"reinsert_count":0,"update_count":740}
I20260812 06:19:55.602500  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:55.751454  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.149s	user 0.109s	sys 0.040s Metrics: {"cfile_cache_miss":505,"cfile_cache_miss_bytes":23667046,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":775,"lbm_read_time_us":11244,"lbm_reads_lt_1ms":537,"lbm_write_time_us":29377,"lbm_writes_lt_1ms":516,"peak_mem_usage":58886803,"reinsert_count":0,"spinlock_wait_cycles":6400,"thread_start_us":317,"threads_started":5,"update_count":2365}
I20260812 06:19:55.752136  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=11.118625
I20260812 06:19:55.798158  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.046s	user 0.023s	sys 0.021s Metrics: {"bytes_written":13004909,"delete_count":0,"lbm_write_time_us":21306,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":319,"reinsert_count":0,"update_count":1585}
I20260812 06:19:55.798754  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:55.813410  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.014s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5297,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:55.813858  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:55.947306  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.133s	user 0.105s	sys 0.028s Metrics: {"cfile_cache_miss":449,"cfile_cache_miss_bytes":21369695,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1114,"lbm_read_time_us":8192,"lbm_reads_lt_1ms":485,"lbm_write_time_us":25858,"lbm_writes_lt_1ms":460,"mutex_wait_us":454,"peak_mem_usage":52427307,"reinsert_count":0,"spinlock_wait_cycles":19584,"update_count":2085}
I20260812 06:19:55.948410  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=10.126437
I20260812 06:19:55.986258  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.038s	user 0.009s	sys 0.026s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17347,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:19:55.986860  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:56.005460  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.018s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5491,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:56.006134  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:56.129665  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.123s	user 0.099s	sys 0.023s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":683,"lbm_read_time_us":7844,"lbm_reads_lt_1ms":464,"lbm_write_time_us":23980,"lbm_writes_lt_1ms":443,"mutex_wait_us":62,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":4992,"update_count":2000}
I20260812 06:19:56.130278  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=11.118625
I20260812 06:19:56.187394  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.057s	user 0.030s	sys 0.019s Metrics: {"bytes_written":12717734,"delete_count":0,"lbm_write_time_us":20874,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":312,"reinsert_count":0,"spinlock_wait_cycles":25984,"update_count":1550}
I20260812 06:19:56.187960  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=3.181125
I20260812 06:19:56.202703  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4923142,"delete_count":0,"lbm_write_time_us":5669,"lbm_writes_lt_1ms":123,"reinsert_count":0,"update_count":600}
I20260812 06:19:56.203227  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=1.196750
I20260812 06:19:56.215350  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.012s	user 0.002s	sys 0.007s Metrics: {"bytes_written":2871905,"delete_count":0,"lbm_write_time_us":4228,"lbm_writes_lt_1ms":73,"reinsert_count":0,"update_count":350}
I20260812 06:19:56.215945  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:56.414783  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.199s	user 0.127s	sys 0.061s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774781,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":155,"lbm_read_time_us":14331,"lbm_reads_lt_1ms":573,"lbm_write_time_us":35006,"lbm_writes_lt_1ms":543,"mutex_wait_us":39,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5120,"update_count":2500}
I20260812 06:19:56.415341  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:19:56.477762  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.062s	user 0.031s	sys 0.023s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":20797,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:56.478332  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:56.488901  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.010s	user 0.004s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4085,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:56.489379  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:56.674140  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.185s	user 0.121s	sys 0.057s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1061,"lbm_read_time_us":12263,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32745,"lbm_writes_lt_1ms":543,"mutex_wait_us":317,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:19:56.674823  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=11.118625
I20260812 06:19:56.718269  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.043s	user 0.025s	sys 0.016s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":18268,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:19:56.718791  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:56.762578  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.043s	user 0.012s	sys 0.010s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5353,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:56.763263  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:56.781153  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.018s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6816,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:56.781716  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:56.972448  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.191s	user 0.138s	sys 0.052s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774799,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":785,"lbm_read_time_us":15126,"lbm_reads_lt_1ms":573,"lbm_write_time_us":32528,"lbm_writes_lt_1ms":543,"mutex_wait_us":350,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":9984,"update_count":2500}
I20260812 06:19:56.973258  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=10.126437
I20260812 06:19:57.024806  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.051s	user 0.038s	sys 0.012s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":25053,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":300,"reinsert_count":0,"update_count":1500}
I20260812 06:19:57.025388  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:57.040475  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.015s	user 0.007s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4538,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:57.041013  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushMRSOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:57.095003  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushMRSOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.054s	user 0.030s	sys 0.002s Metrics: {"bytes_written":1316413,"cfile_init":1,"dirs.queue_time_us":99,"dirs.run_cpu_time_us":280,"dirs.run_wall_time_us":1427,"drs_written":1,"lbm_read_time_us":92,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1788,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:19:57.100760  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling LogGCOp(3e634696fac5438891387fad21c8efca): free 120553401 bytes of WAL
I20260812 06:19:57.101095  5702 log_reader.cc:385] T 3e634696fac5438891387fad21c8efca: removed 12 log segments from log reader
I20260812 06:19:57.101171  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000003 (ops 12-16)
I20260812 06:19:57.101215  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000004 (ops 17-20)
I20260812 06:19:57.101303  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000005 (ops 21-25)
I20260812 06:19:57.101348  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000006 (ops 26-30)
I20260812 06:19:57.101372  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000007 (ops 31-35)
I20260812 06:19:57.101419  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000008 (ops 36-40)
I20260812 06:19:57.101454  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000009 (ops 41-45)
I20260812 06:19:57.101495  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000010 (ops 46-50)
I20260812 06:19:57.101565  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000011 (ops 51-54)
I20260812 06:19:57.101603  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000012 (ops 55-59)
I20260812 06:19:57.101627  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000013 (ops 60-64)
I20260812 06:19:57.101691  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000014 (ops 65-69)
I20260812 06:19:57.131978  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: LogGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.031s	user 0.003s	sys 0.024s Metrics: {}
I20260812 06:19:57.132575  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling UndoDeltaBlockGCOp(3e634696fac5438891387fad21c8efca): 492 bytes on disk
I20260812 06:19:57.133106  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: UndoDeltaBlockGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4}
I20260812 06:19:57.133726  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=6.157687
I20260812 06:19:57.169354  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.035s	user 0.018s	sys 0.004s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":10271,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:19:57.169914  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling LogGCOp(3e634696fac5438891387fad21c8efca): free 12017932 bytes of WAL
I20260812 06:19:57.170190  5702 log_reader.cc:385] T 3e634696fac5438891387fad21c8efca: removed 1 log segments from log reader
I20260812 06:19:57.170256  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000015 (ops 70-74)
I20260812 06:19:57.173418  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: LogGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.003s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:19:57.173983  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:57.185837  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4118,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:57.186379  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:57.426107  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.240s	user 0.138s	sys 0.099s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979752,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":637,"lbm_read_time_us":17409,"lbm_reads_lt_1ms":774,"lbm_write_time_us":40318,"lbm_writes_lt_1ms":743,"mutex_wait_us":33,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":7808,"thread_start_us":93,"threads_started":1,"update_count":3500}
I20260812 06:19:57.426842  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=15.087375
I20260812 06:19:57.481570  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.054s	user 0.033s	sys 0.020s Metrics: {"bytes_written":16820144,"delete_count":0,"lbm_write_time_us":23223,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:19:57.482191  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:57.493788  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3844,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:57.494354  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:57.677825  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.183s	user 0.125s	sys 0.055s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774677,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":507,"lbm_read_time_us":12869,"lbm_reads_lt_1ms":564,"lbm_write_time_us":31363,"lbm_writes_lt_1ms":543,"mutex_wait_us":113,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":14336,"update_count":2500}
I20260812 06:19:57.678499  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:19:57.747349  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.068s	user 0.040s	sys 0.020s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23315,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:57.748116  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:57.760412  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.012s	user 0.011s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4631,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:57.760990  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:57.944103  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.183s	user 0.131s	sys 0.051s 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":826,"lbm_read_time_us":14140,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31213,"lbm_writes_lt_1ms":543,"mutex_wait_us":75,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:19:57.944636  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:19:58.016764  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.072s	user 0.040s	sys 0.031s Metrics: {"bytes_written":16409906,"delete_count":0,"lbm_write_time_us":27951,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:19:58.017490  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:58.036545  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.019s	user 0.010s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7126,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:58.038442  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:58.203889  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.165s	user 0.109s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774693,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1027,"lbm_read_time_us":11617,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27169,"lbm_writes_lt_1ms":543,"mutex_wait_us":20,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11264,"update_count":2500}
I20260812 06:19:58.204504  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=11.118625
I20260812 06:19:58.235100  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.030s	user 0.011s	sys 0.016s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":13566,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:19:58.235800  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:58.267719  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.031s	user 0.001s	sys 0.012s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6799,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":92,"reinsert_count":0,"update_count":450}
I20260812 06:19:58.268286  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:58.289072  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.021s	user 0.005s	sys 0.015s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4079,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:58.289770  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:58.481281  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.191s	user 0.119s	sys 0.060s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774799,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":441,"lbm_read_time_us":14152,"lbm_reads_lt_1ms":573,"lbm_write_time_us":30839,"lbm_writes_lt_1ms":543,"mutex_wait_us":127,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":14720,"update_count":2500}
I20260812 06:19:58.481977  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:19:58.537211  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.055s	user 0.040s	sys 0.012s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":23670,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:58.537748  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:58.549304  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4012,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:58.549957  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:58.734792  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.185s	user 0.116s	sys 0.053s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":136,"lbm_read_time_us":11274,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30210,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2944,"update_count":2500}
I20260812 06:19:58.735561  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:19:58.784956  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.049s	user 0.041s	sys 0.007s Metrics: {"bytes_written":16409906,"delete_count":0,"lbm_write_time_us":21717,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:58.785446  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:58.798174  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.013s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4209,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:58.798864  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushMRSOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:58.832222  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushMRSOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.033s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1316415,"cfile_init":1,"dirs.queue_time_us":65,"dirs.run_cpu_time_us":386,"dirs.run_wall_time_us":1355,"drs_written":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2158,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:19:58.833101  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling LogGCOp(3e634696fac5438891387fad21c8efca): free 133477412 bytes of WAL
I20260812 06:19:58.833376  5702 log_reader.cc:385] T 3e634696fac5438891387fad21c8efca: removed 13 log segments from log reader
I20260812 06:19:58.833451  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000016 (ops 75-79)
I20260812 06:19:58.833508  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000017 (ops 80-84)
I20260812 06:19:58.833567  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000018 (ops 85-89)
I20260812 06:19:58.833611  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000019 (ops 90-94)
I20260812 06:19:58.833647  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000020 (ops 95-99)
I20260812 06:19:58.833684  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000021 (ops 100-104)
I20260812 06:19:58.833721  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000022 (ops 105-109)
I20260812 06:19:58.833758  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000023 (ops 110-114)
I20260812 06:19:58.833803  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000024 (ops 115-119)
I20260812 06:19:58.833856  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000025 (ops 120-124)
I20260812 06:19:58.833892  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000026 (ops 125-129)
I20260812 06:19:58.833931  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000027 (ops 130-134)
I20260812 06:19:58.833967  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000028 (ops 135-139)
I20260812 06:19:58.866660  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: LogGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.033s	user 0.000s	sys 0.031s Metrics: {}
I20260812 06:19:58.867192  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling UndoDeltaBlockGCOp(3e634696fac5438891387fad21c8efca): 491 bytes on disk
I20260812 06:19:58.868010  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: UndoDeltaBlockGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":74,"lbm_reads_lt_1ms":4}
I20260812 06:19:58.868652  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=5.165500
I20260812 06:19:58.892488  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.024s	user 0.020s	sys 0.003s Metrics: {"bytes_written":6687183,"delete_count":0,"lbm_write_time_us":10055,"lbm_writes_lt_1ms":166,"reinsert_count":0,"update_count":815}
I20260812 06:19:58.893217  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:58.898618  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.005s	user 0.000s	sys 0.003s Metrics: {"bytes_written":1518077,"delete_count":0,"lbm_write_time_us":1627,"lbm_writes_lt_1ms":40,"reinsert_count":0,"update_count":185}
I20260812 06:19:58.899030  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:59.140483  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.241s	user 0.157s	sys 0.080s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979692,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1608,"lbm_read_time_us":16540,"lbm_reads_lt_1ms":774,"lbm_write_time_us":43022,"lbm_writes_lt_1ms":743,"mutex_wait_us":1246,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":19968,"thread_start_us":87,"threads_started":1,"update_count":3500}
I20260812 06:19:59.141418  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=16.079562
I20260812 06:19:59.197503  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.056s	user 0.038s	sys 0.016s Metrics: {"bytes_written":18214961,"delete_count":0,"lbm_write_time_us":25004,"lbm_writes_lt_1ms":447,"reinsert_count":0,"update_count":2220}
I20260812 06:19:59.198101  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=1.196750
I20260812 06:19:59.210039  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.012s	user 0.003s	sys 0.003s Metrics: {"bytes_written":2707809,"delete_count":0,"lbm_write_time_us":2894,"lbm_writes_lt_1ms":69,"reinsert_count":0,"update_count":330}
I20260812 06:19:59.210577  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:59.224211  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.013s	user 0.009s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5194,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:59.224794  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:59.425585  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.201s	user 0.124s	sys 0.075s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877175,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":275,"lbm_read_time_us":14348,"lbm_reads_lt_1ms":673,"lbm_write_time_us":34337,"lbm_writes_lt_1ms":643,"mutex_wait_us":60,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":19584,"update_count":3000}
I20260812 06:19:59.426532  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:19:59.476476  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.050s	user 0.039s	sys 0.008s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":20638,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:59.477051  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:59.492623  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.015s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6075,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:59.493216  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:59.667339  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.174s	user 0.095s	sys 0.077s 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":287,"lbm_read_time_us":13775,"lbm_reads_1-10_ms":1,"lbm_reads_lt_1ms":571,"lbm_write_time_us":31625,"lbm_writes_lt_1ms":543,"mutex_wait_us":82,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:19:59.668196  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:19:59.729976  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.062s	user 0.036s	sys 0.020s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":21404,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:59.730605  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:59.742112  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4536,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:59.742620  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:19:59.922215  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.179s	user 0.118s	sys 0.060s 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":573,"lbm_read_time_us":14168,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29875,"lbm_writes_lt_1ms":543,"mutex_wait_us":23,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:19:59.923004  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=11.118625
I20260812 06:19:59.972401  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.049s	user 0.032s	sys 0.015s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":17479,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":312,"reinsert_count":0,"update_count":1550}
I20260812 06:19:59.973225  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:19:59.990494  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.017s	user 0.009s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4572,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:59.990968  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:20:00.000885  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.010s	user 0.008s	sys 0.001s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3622,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:00.001379  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:20:00.179698  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.178s	user 0.122s	sys 0.051s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774799,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":948,"lbm_read_time_us":13165,"lbm_reads_lt_1ms":573,"lbm_write_time_us":28566,"lbm_writes_lt_1ms":543,"mutex_wait_us":28,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":74368,"update_count":2500}
I20260812 06:20:00.180371  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=14.095187
I20260812 06:20:00.241140  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.061s	user 0.009s	sys 0.050s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21644,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:00.241752  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:20:00.252784  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.011s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4341,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:00.253257  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushMRSOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:20:00.296521  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushMRSOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.043s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1152514,"cfile_init":1,"dirs.queue_time_us":77,"dirs.run_cpu_time_us":284,"dirs.run_wall_time_us":1539,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1332,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:00.297236  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling LogGCOp(3e634696fac5438891387fad21c8efca): free 112239619 bytes of WAL
I20260812 06:20:00.297469  5702 log_reader.cc:385] T 3e634696fac5438891387fad21c8efca: removed 11 log segments from log reader
I20260812 06:20:00.297515  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000029 (ops 140-144)
I20260812 06:20:00.297544  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000030 (ops 145-148)
I20260812 06:20:00.297606  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000031 (ops 149-153)
I20260812 06:20:00.297652  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000032 (ops 154-158)
I20260812 06:20:00.297708  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000033 (ops 159-163)
I20260812 06:20:00.297729  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000034 (ops 164-168)
I20260812 06:20:00.297787  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000035 (ops 169-173)
I20260812 06:20:00.297825  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000036 (ops 174-178)
I20260812 06:20:00.297889  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000037 (ops 179-183)
I20260812 06:20:00.297931  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000038 (ops 184-188)
I20260812 06:20:00.297972  5702 log.cc:1079] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/3e634696fac5438891387fad21c8efca/wal-000000039 (ops 189-193)
I20260812 06:20:00.323829  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: LogGCOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.026s	user 0.001s	sys 0.022s Metrics: {}
I20260812 06:20:00.324374  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=3.181125
I20260812 06:20:00.355165  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.031s	user 0.013s	sys 0.005s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":7815,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:00.356746  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca): perf score=2.188937
I20260812 06:20:00.372668  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: FlushDeltaMemStoresOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.016s	user 0.006s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5275,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:00.373584  5788 maintenance_manager.cc:419] P 7be720521b6f4ecfa8540433feccfc17: Scheduling MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca): perf score=1.000000
I20260812 06:20:00.468364  5530 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.191s	user 1.890s	sys 0.170s
I20260812 06:20:00.567364  5530 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.098s	user 0.002s	sys 0.000s
I20260812 06:20:00.568269  5530 tablet_server.cc:179] TabletServer@127.5.102.129:0 shutting down...
I20260812 06:20:00.596872  5702 maintenance_manager.cc:643] P 7be720521b6f4ecfa8540433feccfc17: MajorDeltaCompactionOp(3e634696fac5438891387fad21c8efca) complete. Timing: real 0.223s	user 0.142s	sys 0.079s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979737,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":397,"lbm_read_time_us":16112,"lbm_reads_lt_1ms":770,"lbm_write_time_us":34255,"lbm_writes_lt_1ms":743,"mutex_wait_us":60,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":13312,"thread_start_us":83,"threads_started":1,"update_count":3500}
I20260812 06:20:00.598301  5530 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:00.598734  5530 tablet_replica.cc:333] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17: stopping tablet replica
I20260812 06:20:00.598990  5530 raft_consensus.cc:2243] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:00.599246  5530 raft_consensus.cc:2272] T 3e634696fac5438891387fad21c8efca P 7be720521b6f4ecfa8540433feccfc17 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:00.616428  5530 tablet_server.cc:196] TabletServer@127.5.102.129:0 shutdown complete.
I20260812 06:20:00.655687  5530 master.cc:562] Master@127.5.102.190:42865 shutting down...
I20260812 06:20:00.659917  5530 raft_consensus.cc:2243] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:00.660167  5530 raft_consensus.cc:2272] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:00.660243  5530 tablet_replica.cc:333] T 00000000000000000000000000000000 P a9b4321f46de42b2b012b5345fc28265: stopping tablet replica
I20260812 06:20:00.672808  5530 master.cc:584] Master@127.5.102.190:42865 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5770 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:20:00.766450  5530 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.5.102.190:36829
I20260812 06:20:00.766819  5530 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:00.769141  5834 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:00.769207  5839 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:00.769325  5530 server_base.cc:1061] running on GCE node
W20260812 06:20:00.769213  5835 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:00.769635  5530 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:00.769682  5530 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:00.769698  5530 hybrid_clock.cc:648] HybridClock initialized: now 1786515600769698 us; error 0 us; skew 500 ppm
I20260812 06:20:00.770591  5530 webserver.cc:533] Webserver started at http://127.5.102.190:33407/ using document root <none> and password file <none>
I20260812 06:20:00.770771  5530 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:00.770820  5530 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:00.770901  5530 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:00.771342  5530 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/master-0-root/instance:
uuid: "530d521dca1645c186fa8e1a7bb82e30"
format_stamp: "Formatted at 2026-08-12 06:20:00 on dist-test-slave-t3q3"
I20260812 06:20:00.773068  5530 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:20:00.774060  5848 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:00.774361  5530 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:00.774456  5530 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/master-0-root
uuid: "530d521dca1645c186fa8e1a7bb82e30"
format_stamp: "Formatted at 2026-08-12 06:20:00 on dist-test-slave-t3q3"
I20260812 06:20:00.774546  5530 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-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:00.793083  5530 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:00.793557  5530 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:00.798041  5530 rpc_server.cc:307] RPC server started. Bound to: 127.5.102.190:36829
I20260812 06:20:00.804481  5932 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.102.190:36829 every 8 connection(s)
I20260812 06:20:00.810927  5935 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:00.813019  5935 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30: Bootstrap starting.
I20260812 06:20:00.813900  5935 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:00.815079  5935 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30: No bootstrap required, opened a new log
I20260812 06:20:00.815531  5935 raft_consensus.cc:359] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "530d521dca1645c186fa8e1a7bb82e30" member_type: VOTER }
I20260812 06:20:00.815626  5935 raft_consensus.cc:385] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:00.815651  5935 raft_consensus.cc:740] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 530d521dca1645c186fa8e1a7bb82e30, State: Initialized, Role: FOLLOWER
I20260812 06:20:00.815871  5935 consensus_queue.cc:260] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [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: "530d521dca1645c186fa8e1a7bb82e30" member_type: VOTER }
I20260812 06:20:00.815949  5935 raft_consensus.cc:399] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:00.815994  5935 raft_consensus.cc:493] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:00.816089  5935 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:00.816809  5935 raft_consensus.cc:515] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "530d521dca1645c186fa8e1a7bb82e30" member_type: VOTER }
I20260812 06:20:00.816956  5935 leader_election.cc:304] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [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: 530d521dca1645c186fa8e1a7bb82e30; no voters: 
I20260812 06:20:00.817204  5935 leader_election.cc:290] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:00.817386  5940 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:00.817610  5940 raft_consensus.cc:697] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 1 LEADER]: Becoming Leader. State: Replica: 530d521dca1645c186fa8e1a7bb82e30, State: Running, Role: LEADER
I20260812 06:20:00.817739  5935 sys_catalog.cc:565] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:00.817783  5940 consensus_queue.cc:237] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [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: "530d521dca1645c186fa8e1a7bb82e30" member_type: VOTER }
I20260812 06:20:00.818285  5942 sys_catalog.cc:455] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 530d521dca1645c186fa8e1a7bb82e30. Latest consensus state: current_term: 1 leader_uuid: "530d521dca1645c186fa8e1a7bb82e30" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "530d521dca1645c186fa8e1a7bb82e30" member_type: VOTER } }
I20260812 06:20:00.818248  5941 sys_catalog.cc:455] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "530d521dca1645c186fa8e1a7bb82e30" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "530d521dca1645c186fa8e1a7bb82e30" member_type: VOTER } }
I20260812 06:20:00.818358  5942 sys_catalog.cc:458] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:00.818368  5941 sys_catalog.cc:458] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:00.818727  5950 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:00.819669  5950 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:00.819895  5530 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:00.821676  5950 catalog_manager.cc:1383] Generated new cluster ID: dd86456636f044a4b8dbbb3942246145
I20260812 06:20:00.821736  5950 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:00.829264  5950 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:00.829859  5950 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:00.840490  5950 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30: Generated new TSK 0
I20260812 06:20:00.840713  5950 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:00.852795  5530 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:00.855443  5968 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:00.855448  5972 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:00.855574  5530 server_base.cc:1061] running on GCE node
W20260812 06:20:00.855448  5969 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:00.855978  5530 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:00.856029  5530 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:00.856045  5530 hybrid_clock.cc:648] HybridClock initialized: now 1786515600856045 us; error 0 us; skew 500 ppm
I20260812 06:20:00.857002  5530 webserver.cc:533] Webserver started at http://127.5.102.129:34445/ using document root <none> and password file <none>
I20260812 06:20:00.857232  5530 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:00.857308  5530 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:00.857396  5530 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:00.857854  5530 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/instance:
uuid: "051280004b32446faef97fca95356315"
format_stamp: "Formatted at 2026-08-12 06:20:00 on dist-test-slave-t3q3"
I20260812 06:20:00.859540  5530 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:20:00.860677  5982 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:00.861034  5530 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.000s
I20260812 06:20:00.861102  5530 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root
uuid: "051280004b32446faef97fca95356315"
format_stamp: "Formatted at 2026-08-12 06:20:00 on dist-test-slave-t3q3"
I20260812 06:20:00.861164  5530 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-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:00.877480  5530 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:00.877879  5530 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:00.878186  5530 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:00.878756  5530 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:00.878798  5530 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:00.878861  5530 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:00.878906  5530 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:00.883809  5530 rpc_server.cc:307] RPC server started. Bound to: 127.5.102.129:34133
I20260812 06:20:00.883850  6086 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.102.129:34133 every 8 connection(s)
I20260812 06:20:00.889623  6087 heartbeater.cc:344] Connected to a master server at 127.5.102.190:36829
I20260812 06:20:00.889779  6087 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:00.890065  6087 heartbeater.cc:507] Master 127.5.102.190:36829 requested a full tablet report, sending...
I20260812 06:20:00.890816  5877 ts_manager.cc:194] Registered new tserver with Master: 051280004b32446faef97fca95356315 (127.5.102.129:34133)
I20260812 06:20:00.891075  5530 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.006791797s
I20260812 06:20:00.891723  5877 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:42014
I20260812 06:20:00.899394  5877 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:42026:
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:00.910296  6022 tablet_service.cc:1511] Processing CreateTablet for tablet 82a04fd1f9bd4eeeb5c8687749536a59 (DEFAULT_TABLE table=heavy-update-compaction-test [id=6800dc74d43141c582f9436dd29d4b82]), partition=
I20260812 06:20:00.910565  6022 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 82a04fd1f9bd4eeeb5c8687749536a59. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:00.912632  6105 tablet_bootstrap.cc:492] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Bootstrap starting.
I20260812 06:20:00.913548  6105 tablet_bootstrap.cc:654] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:00.914633  6105 tablet_bootstrap.cc:492] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: No bootstrap required, opened a new log
I20260812 06:20:00.914709  6105 ts_tablet_manager.cc:1403] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:20:00.915077  6105 raft_consensus.cc:359] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "051280004b32446faef97fca95356315" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 34133 } }
I20260812 06:20:00.915174  6105 raft_consensus.cc:385] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:00.915197  6105 raft_consensus.cc:740] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 051280004b32446faef97fca95356315, State: Initialized, Role: FOLLOWER
I20260812 06:20:00.915318  6105 consensus_queue.cc:260] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [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: "051280004b32446faef97fca95356315" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 34133 } }
I20260812 06:20:00.915383  6105 raft_consensus.cc:399] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:00.915406  6105 raft_consensus.cc:493] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:00.915436  6105 raft_consensus.cc:3060] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:00.916456  6105 raft_consensus.cc:515] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "051280004b32446faef97fca95356315" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 34133 } }
I20260812 06:20:00.916599  6105 leader_election.cc:304] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [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: 051280004b32446faef97fca95356315; no voters: 
I20260812 06:20:00.916769  6105 leader_election.cc:290] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:00.916911  6108 raft_consensus.cc:2804] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:00.917158  6108 raft_consensus.cc:697] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 1 LEADER]: Becoming Leader. State: Replica: 051280004b32446faef97fca95356315, State: Running, Role: LEADER
I20260812 06:20:00.917182  6105 ts_tablet_manager.cc:1434] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:20:00.917188  6087 heartbeater.cc:499] Master 127.5.102.190:36829 was elected leader, sending a full tablet report...
I20260812 06:20:00.917313  6108 consensus_queue.cc:237] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [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: "051280004b32446faef97fca95356315" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 34133 } }
I20260812 06:20:00.918608  5877 catalog_manager.cc:5719] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 reported cstate change: term changed from 0 to 1, leader changed from <none> to 051280004b32446faef97fca95356315 (127.5.102.129). New cstate: current_term: 1 leader_uuid: "051280004b32446faef97fca95356315" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "051280004b32446faef97fca95356315" member_type: VOTER last_known_addr { host: "127.5.102.129" port: 34133 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:00.982950  5530 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.056s	user 0.016s	sys 0.007s
I20260812 06:20:01.134905  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=19.054940
I20260812 06:20:01.296689  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.161s	user 0.117s	sys 0.044s Metrics: {"bytes_written":12307491,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":117,"dirs.run_cpu_time_us":204,"dirs.run_wall_time_us":970,"drs_written":1,"lbm_read_time_us":77,"lbm_reads_lt_1ms":4,"lbm_write_time_us":43964,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"update_count":1500}
I20260812 06:20:01.297323  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59): free 20743880 bytes of WAL
I20260812 06:20:01.297564  5990 log_reader.cc:385] T 82a04fd1f9bd4eeeb5c8687749536a59: removed 2 log segments from log reader
I20260812 06:20:01.297611  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000001 (ops 1-6)
I20260812 06:20:01.297641  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000002 (ops 7-11)
I20260812 06:20:01.301846  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.004s	user 0.000s	sys 0.003s Metrics: {}
I20260812 06:20:01.302259  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59): 16411394 bytes on disk
I20260812 06:20:01.302704  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":60,"lbm_reads_lt_1ms":4}
I20260812 06:20:01.303213  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:01.317317  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.014s	user 0.008s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5185,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:01.317940  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:01.480125  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.162s	user 0.117s	sys 0.045s 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":485,"lbm_read_time_us":12208,"lbm_reads_lt_1ms":464,"lbm_write_time_us":28536,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1920,"thread_start_us":339,"threads_started":5,"update_count":2000}
I20260812 06:20:01.480808  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=10.126437
I20260812 06:20:01.517212  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.036s	user 0.016s	sys 0.019s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18497,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:20:01.518003  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:01.547684  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.029s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5288,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:01.548214  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:01.559010  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4109,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:01.559500  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:01.770427  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.211s	user 0.129s	sys 0.067s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774806,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":782,"lbm_read_time_us":15066,"lbm_reads_lt_1ms":573,"lbm_write_time_us":33746,"lbm_writes_lt_1ms":543,"mutex_wait_us":2,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:01.771253  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:01.837858  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.066s	user 0.030s	sys 0.020s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":23504,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:01.838364  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:01.851962  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.013s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4715,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:01.852662  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:02.037298  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.184s	user 0.132s	sys 0.050s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":144,"lbm_read_time_us":16098,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28678,"lbm_writes_lt_1ms":543,"mutex_wait_us":43,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:02.037999  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:02.105423  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.067s	user 0.040s	sys 0.023s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24308,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:02.106017  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:02.117283  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4320,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:02.117883  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:02.301234  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.183s	user 0.107s	sys 0.072s 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":212,"lbm_read_time_us":13201,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30743,"lbm_writes_lt_1ms":543,"mutex_wait_us":2,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2176,"update_count":2500}
I20260812 06:20:02.301954  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:02.361027  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.059s	user 0.039s	sys 0.019s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":21873,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:02.361649  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:02.372747  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4204,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:02.373265  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:02.562028  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.189s	user 0.128s	sys 0.048s 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":886,"lbm_read_time_us":12810,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30841,"lbm_writes_lt_1ms":543,"mutex_wait_us":24,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":18304,"update_count":2500}
I20260812 06:20:02.562808  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:02.631129  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.068s	user 0.026s	sys 0.038s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":25845,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:20:02.631752  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:02.643355  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4303,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:02.644179  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:02.687215  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.043s	user 0.030s	sys 0.001s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":101,"dirs.run_cpu_time_us":362,"dirs.run_wall_time_us":1686,"drs_written":1,"lbm_read_time_us":76,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1968,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:20:02.687887  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59): free 115943173 bytes of WAL
I20260812 06:20:02.688154  5990 log_reader.cc:385] T 82a04fd1f9bd4eeeb5c8687749536a59: removed 11 log segments from log reader
I20260812 06:20:02.688208  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000003 (ops 12-16)
I20260812 06:20:02.688266  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000004 (ops 17-21)
I20260812 06:20:02.688313  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000005 (ops 22-26)
I20260812 06:20:02.688381  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000006 (ops 27-31)
I20260812 06:20:02.688426  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000007 (ops 32-36)
I20260812 06:20:02.688464  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000008 (ops 37-41)
I20260812 06:20:02.688503  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000009 (ops 42-46)
I20260812 06:20:02.688542  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000010 (ops 47-51)
I20260812 06:20:02.688581  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000011 (ops 52-56)
I20260812 06:20:02.688619  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000012 (ops 57-61)
I20260812 06:20:02.688660  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000013 (ops 62-66)
I20260812 06:20:02.714890  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.027s	user 0.001s	sys 0.023s Metrics: {}
I20260812 06:20:02.715523  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:02.735944  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.020s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4225732,"delete_count":0,"lbm_write_time_us":4740,"lbm_writes_lt_1ms":106,"reinsert_count":0,"update_count":515}
I20260812 06:20:02.736514  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59): 463 bytes on disk
I20260812 06:20:02.736974  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":77,"lbm_reads_lt_1ms":4}
I20260812 06:20:02.737473  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:02.753124  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.015s	user 0.011s	sys 0.004s Metrics: {"bytes_written":3979583,"delete_count":0,"lbm_write_time_us":5615,"lbm_writes_lt_1ms":100,"reinsert_count":0,"update_count":485}
I20260812 06:20:02.753894  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:03.018326  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.264s	user 0.191s	sys 0.060s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979748,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":407,"lbm_read_time_us":17483,"lbm_reads_lt_1ms":774,"lbm_write_time_us":42952,"lbm_writes_lt_1ms":743,"mutex_wait_us":359,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":22912,"thread_start_us":112,"threads_started":1,"update_count":3500}
I20260812 06:20:03.019189  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=18.063937
I20260812 06:20:03.092007  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.073s	user 0.035s	sys 0.027s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":29826,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:03.092808  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:03.105458  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.012s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4283,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:03.105999  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:03.317878  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.212s	user 0.132s	sys 0.079s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877103,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":684,"lbm_read_time_us":16210,"lbm_reads_lt_1ms":672,"lbm_write_time_us":34713,"lbm_writes_lt_1ms":643,"mutex_wait_us":24,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":22784,"update_count":3000}
I20260812 06:20:03.318499  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:03.373271  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.055s	user 0.034s	sys 0.020s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":24670,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:03.373888  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:03.388154  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.014s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5583,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:03.388743  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:03.580415  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.191s	user 0.131s	sys 0.057s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1407,"lbm_read_time_us":14650,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29921,"lbm_writes_lt_1ms":543,"mutex_wait_us":383,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6912,"update_count":2500}
I20260812 06:20:03.581126  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:03.648206  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.067s	user 0.019s	sys 0.047s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":26705,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:20:03.649097  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:03.662925  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.014s	user 0.002s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4796,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:03.663708  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:03.867334  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.203s	user 0.145s	sys 0.047s 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":1049,"lbm_read_time_us":14440,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32392,"lbm_writes_lt_1ms":543,"mutex_wait_us":71,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":17024,"update_count":2500}
I20260812 06:20:03.868196  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:03.936909  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.069s	user 0.040s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23374,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:03.937572  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:03.949007  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	user 0.001s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4511,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:03.949532  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:04.143850  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.194s	user 0.121s	sys 0.066s 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":303,"lbm_read_time_us":13190,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32136,"lbm_writes_lt_1ms":543,"mutex_wait_us":78,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1664,"update_count":2500}
I20260812 06:20:04.144553  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:04.198150  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.053s	user 0.020s	sys 0.023s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":20119,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:04.198731  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:04.222878  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.024s	user 0.007s	sys 0.014s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4888,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:04.223698  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:04.259984  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.036s	user 0.034s	sys 0.000s Metrics: {"bytes_written":1152514,"cfile_init":1,"dirs.queue_time_us":34,"dirs.run_cpu_time_us":193,"dirs.run_wall_time_us":1380,"drs_written":1,"lbm_read_time_us":102,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2085,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:04.261023  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59): free 112692325 bytes of WAL
I20260812 06:20:04.261255  5990 log_reader.cc:385] T 82a04fd1f9bd4eeeb5c8687749536a59: removed 11 log segments from log reader
I20260812 06:20:04.261296  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000014 (ops 67-71)
I20260812 06:20:04.261354  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000015 (ops 72-76)
I20260812 06:20:04.261397  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000016 (ops 77-81)
I20260812 06:20:04.261438  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000017 (ops 82-86)
I20260812 06:20:04.261476  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000018 (ops 87-91)
I20260812 06:20:04.261520  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000019 (ops 92-96)
I20260812 06:20:04.261559  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000020 (ops 97-101)
I20260812 06:20:04.261597  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000021 (ops 102-106)
I20260812 06:20:04.261636  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000022 (ops 107-111)
I20260812 06:20:04.261718  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000023 (ops 112-116)
I20260812 06:20:04.261761  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000024 (ops 117-121)
I20260812 06:20:04.288179  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.027s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:20:04.288650  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59): 447 bytes on disk
I20260812 06:20:04.289120  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":58,"lbm_reads_lt_1ms":4}
I20260812 06:20:04.289817  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:04.313251  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.023s	user 0.014s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6550,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:04.313814  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59): free 12017981 bytes of WAL
I20260812 06:20:04.314054  5990 log_reader.cc:385] T 82a04fd1f9bd4eeeb5c8687749536a59: removed 1 log segments from log reader
I20260812 06:20:04.314103  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000025 (ops 122-126)
I20260812 06:20:04.316609  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.003s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:20:04.316965  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:04.328763  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.012s	user 0.009s	sys 0.002s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4371,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:04.329567  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:04.579210  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.249s	user 0.158s	sys 0.084s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979751,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":879,"lbm_read_time_us":18073,"lbm_reads_lt_1ms":774,"lbm_write_time_us":38769,"lbm_writes_lt_1ms":743,"mutex_wait_us":328,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":25088,"thread_start_us":114,"threads_started":1,"update_count":3500}
I20260812 06:20:04.580300  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=18.063937
I20260812 06:20:04.655316  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.075s	user 0.026s	sys 0.038s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":29983,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:04.655951  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:04.667498  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4281,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:04.668386  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:04.914341  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.246s	user 0.152s	sys 0.088s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877103,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1009,"lbm_read_time_us":15515,"lbm_reads_lt_1ms":672,"lbm_write_time_us":41346,"lbm_writes_lt_1ms":643,"mutex_wait_us":610,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":30208,"update_count":3000}
I20260812 06:20:04.915236  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=18.063937
I20260812 06:20:04.989990  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.075s	user 0.061s	sys 0.011s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":34050,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":501,"reinsert_count":0,"update_count":2500}
I20260812 06:20:04.990569  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:05.003158  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.012s	user 0.009s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4148,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:05.004037  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:05.231623  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.227s	user 0.138s	sys 0.089s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877102,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":605,"lbm_read_time_us":15842,"lbm_reads_lt_1ms":672,"lbm_write_time_us":41547,"lbm_writes_lt_1ms":643,"mutex_wait_us":28,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":37120,"update_count":3000}
I20260812 06:20:05.232244  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=15.087375
I20260812 06:20:05.272706  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.040s	user 0.031s	sys 0.008s Metrics: {"bytes_written":16820139,"delete_count":0,"lbm_write_time_us":18177,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:20:05.273285  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:05.287604  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4975,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:05.288206  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:05.470986  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.183s	user 0.094s	sys 0.088s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774672,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":381,"lbm_read_time_us":11865,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31694,"lbm_writes_lt_1ms":543,"mutex_wait_us":36,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":12416,"update_count":2500}
I20260812 06:20:05.471797  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:05.524396  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.052s	user 0.029s	sys 0.023s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23629,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:05.524998  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:05.543094  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.018s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6127,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:05.544117  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:05.728802  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.184s	user 0.127s	sys 0.057s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":256,"lbm_read_time_us":14380,"lbm_reads_lt_1ms":564,"lbm_write_time_us":31613,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:05.729550  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=14.095187
I20260812 06:20:05.793895  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.064s	user 0.028s	sys 0.035s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":24277,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:05.794552  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:05.805923  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4461,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:05.806435  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:05.849771  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushMRSOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.043s	user 0.027s	sys 0.004s Metrics: {"bytes_written":1193505,"cfile_init":1,"dirs.queue_time_us":52,"dirs.run_cpu_time_us":192,"dirs.run_wall_time_us":1430,"drs_written":1,"lbm_read_time_us":42,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1496,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:20:05.850579  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59): free 112239544 bytes of WAL
I20260812 06:20:05.850824  5990 log_reader.cc:385] T 82a04fd1f9bd4eeeb5c8687749536a59: removed 11 log segments from log reader
I20260812 06:20:05.850872  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000026 (ops 127-131)
I20260812 06:20:05.850903  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000027 (ops 132-136)
I20260812 06:20:05.850971  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000028 (ops 137-141)
I20260812 06:20:05.851024  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000029 (ops 142-146)
I20260812 06:20:05.851069  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000030 (ops 147-150)
I20260812 06:20:05.851135  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000031 (ops 151-155)
I20260812 06:20:05.851173  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000032 (ops 156-160)
I20260812 06:20:05.851234  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000033 (ops 161-165)
I20260812 06:20:05.851275  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000034 (ops 166-170)
I20260812 06:20:05.851316  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000035 (ops 171-175)
I20260812 06:20:05.851358  5990 log.cc:1079] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: Deleting log segment in path: /tmp/dist-test-taskGFudJj/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515594986070-5530-0/minicluster-data/ts-0-root/wals/82a04fd1f9bd4eeeb5c8687749536a59/wal-000000036 (ops 176-180)
I20260812 06:20:05.875465  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: LogGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.025s	user 0.000s	sys 0.021s Metrics: {}
I20260812 06:20:05.875998  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59): 463 bytes on disk
I20260812 06:20:05.876598  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: UndoDeltaBlockGCOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":74,"lbm_reads_lt_1ms":4}
I20260812 06:20:05.877453  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:05.899737  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.022s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4143684,"delete_count":0,"lbm_write_time_us":6659,"lbm_writes_lt_1ms":104,"reinsert_count":0,"update_count":505}
I20260812 06:20:05.900328  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:05.916414  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.016s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4061633,"delete_count":0,"lbm_write_time_us":5895,"lbm_writes_lt_1ms":102,"reinsert_count":0,"update_count":495}
I20260812 06:20:05.917037  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:06.155719  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.238s	user 0.148s	sys 0.076s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979751,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":348,"lbm_read_time_us":16514,"lbm_reads_lt_1ms":774,"lbm_write_time_us":40916,"lbm_writes_lt_1ms":743,"mutex_wait_us":43,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":11520,"thread_start_us":85,"threads_started":1,"update_count":3500}
I20260812 06:20:06.156591  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=18.063937
I20260812 06:20:06.228480  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.072s	user 0.057s	sys 0.011s Metrics: {"bytes_written":20512313,"delete_count":0,"lbm_write_time_us":32106,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:20:06.229246  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:06.255602  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.026s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4704,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:06.256170  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=2.188937
I20260812 06:20:06.267405  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: FlushDeltaMemStoresOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.011s	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:06.267938  6088 maintenance_manager.cc:419] P 051280004b32446faef97fca95356315: Scheduling MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59): perf score=1.000000
I20260812 06:20:06.310421  5530 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.327s	user 1.884s	sys 0.247s
I20260812 06:20:06.394510  5530 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.084s	user 0.001s	sys 0.000s
I20260812 06:20:06.395089  5530 tablet_server.cc:179] TabletServer@127.5.102.129:0 shutting down...
I20260812 06:20:06.464155  5990 maintenance_manager.cc:643] P 051280004b32446faef97fca95356315: MajorDeltaCompactionOp(82a04fd1f9bd4eeeb5c8687749536a59) complete. Timing: real 0.196s	user 0.152s	sys 0.044s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979631,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":1487,"lbm_read_time_us":17343,"lbm_reads_lt_1ms":769,"lbm_write_time_us":34836,"lbm_writes_lt_1ms":743,"mutex_wait_us":401,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":27904,"update_count":3500}
I20260812 06:20:06.464744  5530 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:06.464967  5530 tablet_replica.cc:333] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315: stopping tablet replica
I20260812 06:20:06.465147  5530 raft_consensus.cc:2243] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:06.465358  5530 raft_consensus.cc:2272] T 82a04fd1f9bd4eeeb5c8687749536a59 P 051280004b32446faef97fca95356315 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:06.470357  5530 tablet_server.cc:196] TabletServer@127.5.102.129:0 shutdown complete.
I20260812 06:20:06.525532  5530 master.cc:562] Master@127.5.102.190:36829 shutting down...
I20260812 06:20:06.529203  5530 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:06.529413  5530 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:06.529464  5530 tablet_replica.cc:333] T 00000000000000000000000000000000 P 530d521dca1645c186fa8e1a7bb82e30: stopping tablet replica
I20260812 06:20:06.542198  5530 master.cc:584] Master@127.5.102.190:36829 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5872 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (11643 ms total)

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