[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:20:23.653457  8474 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.8.70.190:45705
I20260812 06:20:23.654398  8474 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:20:23.654996  8474 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:23.661191  8479 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:23.661265  8480 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:23.661442  8474 server_base.cc:1061] running on GCE node
W20260812 06:20:23.661473  8482 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:23.662036  8474 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:23.662130  8474 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:23.662159  8474 hybrid_clock.cc:648] HybridClock initialized: now 1786515623662157 us; error 0 us; skew 500 ppm
I20260812 06:20:23.663820  8474 webserver.cc:533] Webserver started at http://127.8.70.190:46057/ using document root <none> and password file <none>
I20260812 06:20:23.664284  8474 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:23.664337  8474 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:23.664513  8474 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:23.666060  8474 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/master-0-root/instance:
uuid: "c14eb528b6b349cfb3fa19ab5b98e37e"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-6nmv"
I20260812 06:20:23.669159  8474 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:20:23.671006  8488 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:23.671882  8474 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:20:23.671972  8474 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/master-0-root
uuid: "c14eb528b6b349cfb3fa19ab5b98e37e"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-6nmv"
I20260812 06:20:23.672039  8474 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-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:23.679517  8474 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:23.679977  8474 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:20:23.680092  8474 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:23.686687  8474 rpc_server.cc:307] RPC server started. Bound to: 127.8.70.190:45705
I20260812 06:20:23.686694  8551 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.8.70.190:45705 every 8 connection(s)
I20260812 06:20:23.688621  8552 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:23.693320  8552 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e: Bootstrap starting.
I20260812 06:20:23.695394  8552 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:23.696177  8552 log.cc:826] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e: Log is configured to *not* fsync() on all Append() calls
I20260812 06:20:23.697676  8552 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e: No bootstrap required, opened a new log
I20260812 06:20:23.700019  8552 raft_consensus.cc:359] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c14eb528b6b349cfb3fa19ab5b98e37e" member_type: VOTER }
I20260812 06:20:23.700148  8552 raft_consensus.cc:385] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:23.700181  8552 raft_consensus.cc:740] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c14eb528b6b349cfb3fa19ab5b98e37e, State: Initialized, Role: FOLLOWER
I20260812 06:20:23.700691  8552 consensus_queue.cc:260] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [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: "c14eb528b6b349cfb3fa19ab5b98e37e" member_type: VOTER }
I20260812 06:20:23.700835  8552 raft_consensus.cc:399] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:23.700899  8552 raft_consensus.cc:493] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:23.701031  8552 raft_consensus.cc:3060] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:23.701690  8552 raft_consensus.cc:515] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c14eb528b6b349cfb3fa19ab5b98e37e" member_type: VOTER }
I20260812 06:20:23.702124  8552 leader_election.cc:304] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [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: c14eb528b6b349cfb3fa19ab5b98e37e; no voters: 
I20260812 06:20:23.702370  8552 leader_election.cc:290] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:23.702507  8556 raft_consensus.cc:2804] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:23.702739  8556 raft_consensus.cc:697] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 1 LEADER]: Becoming Leader. State: Replica: c14eb528b6b349cfb3fa19ab5b98e37e, State: Running, Role: LEADER
I20260812 06:20:23.703145  8556 consensus_queue.cc:237] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [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: "c14eb528b6b349cfb3fa19ab5b98e37e" member_type: VOTER }
I20260812 06:20:23.703336  8552 sys_catalog.cc:565] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:23.704762  8559 sys_catalog.cc:455] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [sys.catalog]: SysCatalogTable state changed. Reason: New leader c14eb528b6b349cfb3fa19ab5b98e37e. Latest consensus state: current_term: 1 leader_uuid: "c14eb528b6b349cfb3fa19ab5b98e37e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c14eb528b6b349cfb3fa19ab5b98e37e" member_type: VOTER } }
I20260812 06:20:23.704771  8557 sys_catalog.cc:455] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "c14eb528b6b349cfb3fa19ab5b98e37e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c14eb528b6b349cfb3fa19ab5b98e37e" member_type: VOTER } }
I20260812 06:20:23.704865  8559 sys_catalog.cc:458] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:23.704880  8557 sys_catalog.cc:458] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:23.705171  8572 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:23.705381  8474 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:23.707508  8572 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:23.711933  8572 catalog_manager.cc:1383] Generated new cluster ID: c0f21ece28c3497ab911bedca87e20cf
I20260812 06:20:23.711999  8572 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:23.720929  8572 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:23.721594  8572 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:23.730643  8572 catalog_manager.cc:6092] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e: Generated new TSK 0
I20260812 06:20:23.731084  8572 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:23.737633  8474 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:23.740108  8583 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:23.740203  8582 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:23.740236  8585 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:23.740458  8474 server_base.cc:1061] running on GCE node
I20260812 06:20:23.740612  8474 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:23.740653  8474 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:23.740672  8474 hybrid_clock.cc:648] HybridClock initialized: now 1786515623740672 us; error 0 us; skew 500 ppm
I20260812 06:20:23.741514  8474 webserver.cc:533] Webserver started at http://127.8.70.129:41055/ using document root <none> and password file <none>
I20260812 06:20:23.741655  8474 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:23.741703  8474 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:23.741796  8474 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:23.742199  8474 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/instance:
uuid: "9a370145cb2f4bfbac8c5e7772dad112"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-6nmv"
I20260812 06:20:23.743862  8474 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:20:23.744834  8591 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:23.745106  8474 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:20:23.745180  8474 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root
uuid: "9a370145cb2f4bfbac8c5e7772dad112"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-6nmv"
I20260812 06:20:23.745241  8474 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-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:23.763875  8474 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:23.764644  8474 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:23.765069  8474 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:23.765990  8474 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:23.766052  8474 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:23.766103  8474 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:23.766136  8474 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:23.772778  8474 rpc_server.cc:307] RPC server started. Bound to: 127.8.70.129:45463
I20260812 06:20:23.772799  8664 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.8.70.129:45463 every 8 connection(s)
I20260812 06:20:23.786664  8665 heartbeater.cc:344] Connected to a master server at 127.8.70.190:45705
I20260812 06:20:23.786898  8665 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:23.787324  8665 heartbeater.cc:507] Master 127.8.70.190:45705 requested a full tablet report, sending...
I20260812 06:20:23.788628  8509 ts_manager.cc:194] Registered new tserver with Master: 9a370145cb2f4bfbac8c5e7772dad112 (127.8.70.129:45463)
I20260812 06:20:23.788947  8474 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.01553921s
I20260812 06:20:23.789757  8509 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:47172
I20260812 06:20:23.797974  8509 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:47186:
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:23.810927  8621 tablet_service.cc:1511] Processing CreateTablet for tablet 9e13ef8385aa4797bb0d10fe064157d1 (DEFAULT_TABLE table=heavy-update-compaction-test [id=50d495c47be643ec88f63367041ed42a]), partition=
I20260812 06:20:23.811318  8621 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9e13ef8385aa4797bb0d10fe064157d1. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:23.813520  8677 tablet_bootstrap.cc:492] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Bootstrap starting.
I20260812 06:20:23.814538  8677 tablet_bootstrap.cc:654] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:23.815608  8677 tablet_bootstrap.cc:492] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: No bootstrap required, opened a new log
I20260812 06:20:23.815697  8677 ts_tablet_manager.cc:1403] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:20:23.816150  8677 raft_consensus.cc:359] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a370145cb2f4bfbac8c5e7772dad112" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 45463 } }
I20260812 06:20:23.816254  8677 raft_consensus.cc:385] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:23.816293  8677 raft_consensus.cc:740] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 9a370145cb2f4bfbac8c5e7772dad112, State: Initialized, Role: FOLLOWER
I20260812 06:20:23.816452  8677 consensus_queue.cc:260] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [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: "9a370145cb2f4bfbac8c5e7772dad112" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 45463 } }
I20260812 06:20:23.816547  8677 raft_consensus.cc:399] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:23.816584  8677 raft_consensus.cc:493] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:23.816625  8677 raft_consensus.cc:3060] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:23.817489  8677 raft_consensus.cc:515] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a370145cb2f4bfbac8c5e7772dad112" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 45463 } }
I20260812 06:20:23.817642  8677 leader_election.cc:304] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [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: 9a370145cb2f4bfbac8c5e7772dad112; no voters: 
I20260812 06:20:23.817839  8677 leader_election.cc:290] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:23.817961  8679 raft_consensus.cc:2804] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:23.818186  8677 ts_tablet_manager.cc:1434] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Time spent starting tablet: real 0.002s	user 0.000s	sys 0.003s
I20260812 06:20:23.818248  8679 raft_consensus.cc:697] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 1 LEADER]: Becoming Leader. State: Replica: 9a370145cb2f4bfbac8c5e7772dad112, State: Running, Role: LEADER
I20260812 06:20:23.818584  8665 heartbeater.cc:499] Master 127.8.70.190:45705 was elected leader, sending a full tablet report...
I20260812 06:20:23.819151  8679 consensus_queue.cc:237] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [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: "9a370145cb2f4bfbac8c5e7772dad112" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 45463 } }
I20260812 06:20:23.821421  8509 catalog_manager.cc:5719] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 reported cstate change: term changed from 0 to 1, leader changed from <none> to 9a370145cb2f4bfbac8c5e7772dad112 (127.8.70.129). New cstate: current_term: 1 leader_uuid: "9a370145cb2f4bfbac8c5e7772dad112" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a370145cb2f4bfbac8c5e7772dad112" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 45463 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:23.893254  8474 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.064s	user 0.022s	sys 0.008s
I20260812 06:20:24.023885  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=19.054940
I20260812 06:20:24.200436  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.176s	user 0.134s	sys 0.036s Metrics: {"bytes_written":14686886,"cfile_init":1,"compiler_manager_pool.queue_time_us":198,"delete_count":0,"dirs.queue_time_us":71,"dirs.run_cpu_time_us":223,"dirs.run_wall_time_us":1079,"drs_written":1,"lbm_read_time_us":70,"lbm_reads_lt_1ms":4,"lbm_write_time_us":42554,"lbm_writes_lt_1ms":815,"mutex_wait_us":1206,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":119680,"thread_start_us":125,"threads_started":1,"update_count":1790}
I20260812 06:20:24.201581  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling LogGCOp(9e13ef8385aa4797bb0d10fe064157d1): free 20743880 bytes of WAL
I20260812 06:20:24.201869  8597 log_reader.cc:385] T 9e13ef8385aa4797bb0d10fe064157d1: removed 2 log segments from log reader
I20260812 06:20:24.201931  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000001 (ops 1-6)
I20260812 06:20:24.202033  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000002 (ops 7-11)
I20260812 06:20:24.207661  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: LogGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.006s	user 0.001s	sys 0.003s Metrics: {}
I20260812 06:20:24.207935  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.196750
I20260812 06:20:24.229555  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.021s	user 0.000s	sys 0.015s Metrics: {"bytes_written":2543712,"delete_count":0,"lbm_write_time_us":3660,"lbm_writes_lt_1ms":65,"reinsert_count":0,"update_count":310}
I20260812 06:20:24.229965  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1): 16411394 bytes on disk
I20260812 06:20:24.230536  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1) 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:20:24.230904  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:24.243074  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.012s	user 0.010s	sys 0.000s Metrics: {"bytes_written":3282155,"delete_count":0,"lbm_write_time_us":4540,"lbm_writes_lt_1ms":83,"reinsert_count":0,"update_count":400}
I20260812 06:20:24.243527  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:24.405220  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.162s	user 0.089s	sys 0.072s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774753,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":509,"lbm_read_time_us":12338,"lbm_reads_lt_1ms":569,"lbm_write_time_us":26907,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"thread_start_us":342,"threads_started":5,"update_count":2500}
I20260812 06:20:24.405704  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=10.126437
I20260812 06:20:24.439517  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.034s	user 0.011s	sys 0.021s Metrics: {"bytes_written":12389541,"delete_count":0,"lbm_write_time_us":14459,"lbm_writes_lt_1ms":305,"reinsert_count":0,"update_count":1510}
I20260812 06:20:24.440013  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:24.457015  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.017s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4020609,"delete_count":0,"lbm_write_time_us":6011,"lbm_writes_lt_1ms":101,"reinsert_count":0,"update_count":490}
I20260812 06:20:24.457584  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:24.589948  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.132s	user 0.112s	sys 0.017s 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":537,"lbm_read_time_us":8623,"lbm_reads_lt_1ms":464,"lbm_write_time_us":25065,"lbm_writes_lt_1ms":443,"mutex_wait_us":26,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":45440,"update_count":2000}
I20260812 06:20:24.590502  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=10.126437
I20260812 06:20:24.632640  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.042s	user 0.013s	sys 0.026s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":19045,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:24.633296  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:24.649322  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.016s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4714,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.649775  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:24.767335  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.117s	user 0.104s	sys 0.012s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672276,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":170,"lbm_read_time_us":9017,"lbm_reads_lt_1ms":464,"lbm_write_time_us":23693,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":5120,"update_count":2000}
I20260812 06:20:24.767891  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=10.126437
I20260812 06:20:24.798187  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.030s	user 0.014s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":13185,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:24.798647  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:24.813391  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.015s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5540,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.813843  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:24.934327  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.120s	user 0.100s	sys 0.020s 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":206,"lbm_read_time_us":8879,"lbm_reads_lt_1ms":464,"lbm_write_time_us":24061,"lbm_writes_lt_1ms":443,"mutex_wait_us":20,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2432,"update_count":2000}
I20260812 06:20:24.934804  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=10.126437
I20260812 06:20:24.982548  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.048s	user 0.019s	sys 0.028s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17222,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:24.983045  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:24.993254  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3943,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.996109  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:25.149086  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.153s	user 0.111s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1825,"lbm_read_time_us":11025,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24549,"lbm_writes_lt_1ms":443,"mutex_wait_us":1135,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2944,"update_count":2000}
I20260812 06:20:25.149683  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=10.126437
I20260812 06:20:25.187093  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.037s	user 0.019s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":14583,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:25.187534  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:25.203414  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.016s	user 0.001s	sys 0.013s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6303,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.203861  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:25.322558  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.119s	user 0.094s	sys 0.023s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":208,"lbm_read_time_us":9137,"lbm_reads_lt_1ms":468,"lbm_write_time_us":23025,"lbm_writes_lt_1ms":443,"mutex_wait_us":36,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":14208,"update_count":2000}
I20260812 06:20:25.323100  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=11.118625
I20260812 06:20:25.363639  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.040s	user 0.019s	sys 0.020s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":17379,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:25.364108  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:25.379897  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.016s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4050,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:25.380358  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:25.428983  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.047s	user 0.029s	sys 0.000s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":45,"dirs.run_cpu_time_us":220,"dirs.run_wall_time_us":1247,"drs_written":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2401,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:20:25.429762  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling LogGCOp(9e13ef8385aa4797bb0d10fe064157d1): free 112239285 bytes of WAL
I20260812 06:20:25.430015  8597 log_reader.cc:385] T 9e13ef8385aa4797bb0d10fe064157d1: removed 11 log segments from log reader
I20260812 06:20:25.430063  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000003 (ops 12-16)
I20260812 06:20:25.430091  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000004 (ops 17-21)
I20260812 06:20:25.430150  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000005 (ops 22-26)
I20260812 06:20:25.430190  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000006 (ops 27-30)
I20260812 06:20:25.430229  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000007 (ops 31-35)
I20260812 06:20:25.430279  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000008 (ops 36-40)
I20260812 06:20:25.430313  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000009 (ops 41-45)
I20260812 06:20:25.430349  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000010 (ops 46-50)
I20260812 06:20:25.430385  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000011 (ops 51-55)
I20260812 06:20:25.430420  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000012 (ops 56-60)
I20260812 06:20:25.430464  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000013 (ops 61-65)
I20260812 06:20:25.454509  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: LogGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.025s	user 0.000s	sys 0.024s Metrics: {}
I20260812 06:20:25.454849  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=7.149875
I20260812 06:20:25.473786  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.019s	user 0.004s	sys 0.012s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":8275,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:20:25.474236  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling LogGCOp(9e13ef8385aa4797bb0d10fe064157d1): free 12017983 bytes of WAL
I20260812 06:20:25.474431  8597 log_reader.cc:385] T 9e13ef8385aa4797bb0d10fe064157d1: removed 1 log segments from log reader
I20260812 06:20:25.474476  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000014 (ops 66-70)
I20260812 06:20:25.476853  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: LogGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.002s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:20:25.477149  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:25.491840  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.015s	user 0.010s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4498,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:25.492326  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1): 472 bytes on disk
I20260812 06:20:25.492839  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4}
I20260812 06:20:25.493317  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:25.681689  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.188s	user 0.126s	sys 0.054s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979734,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":3605,"lbm_read_time_us":12495,"lbm_reads_lt_1ms":766,"lbm_write_time_us":37462,"lbm_writes_lt_1ms":743,"mutex_wait_us":3050,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":1280,"thread_start_us":78,"threads_started":1,"update_count":3500}
I20260812 06:20:25.682204  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=18.063937
I20260812 06:20:25.754009  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.072s	user 0.041s	sys 0.030s Metrics: {"bytes_written":20512318,"delete_count":0,"lbm_write_time_us":27409,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:25.754468  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:25.764067  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3952,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.764458  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:25.953593  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.189s	user 0.126s	sys 0.061s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877105,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":845,"lbm_read_time_us":14202,"lbm_reads_lt_1ms":672,"lbm_write_time_us":31379,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":6528,"update_count":3000}
I20260812 06:20:25.954030  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=14.095187
I20260812 06:20:26.024645  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.070s	user 0.029s	sys 0.039s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":28258,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:26.025099  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:26.035607  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.010s	user 0.002s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4215,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.036064  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:26.219916  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.184s	user 0.132s	sys 0.048s 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":554,"lbm_read_time_us":14585,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30295,"lbm_writes_lt_1ms":543,"mutex_wait_us":290,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3712,"update_count":2500}
I20260812 06:20:26.220444  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=14.095187
I20260812 06:20:26.292346  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.072s	user 0.028s	sys 0.041s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":27174,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:26.292858  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:26.303686  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4331,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.304081  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:26.472797  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.169s	user 0.125s	sys 0.040s 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":151,"lbm_read_time_us":12386,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27363,"lbm_writes_lt_1ms":543,"mutex_wait_us":60,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2500}
I20260812 06:20:26.473315  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=14.095187
I20260812 06:20:26.520301  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.047s	user 0.036s	sys 0.007s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19383,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:26.520820  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:26.530650  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3928,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.531297  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:26.707186  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.176s	user 0.130s	sys 0.037s 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":160,"lbm_read_time_us":10479,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28392,"lbm_writes_lt_1ms":543,"mutex_wait_us":25,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5376,"update_count":2500}
I20260812 06:20:26.707890  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=14.095187
I20260812 06:20:26.762810  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.055s	user 0.020s	sys 0.032s Metrics: {"bytes_written":16409906,"delete_count":0,"lbm_write_time_us":23687,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:26.763345  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:26.774468  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4066,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.774945  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:26.799511  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.024s	user 0.018s	sys 0.003s Metrics: {"bytes_written":1152514,"cfile_init":1,"dirs.queue_time_us":54,"dirs.run_cpu_time_us":222,"dirs.run_wall_time_us":1290,"drs_written":1,"lbm_read_time_us":32,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1545,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:26.800330  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling LogGCOp(9e13ef8385aa4797bb0d10fe064157d1): free 112239316 bytes of WAL
I20260812 06:20:26.800561  8597 log_reader.cc:385] T 9e13ef8385aa4797bb0d10fe064157d1: removed 11 log segments from log reader
I20260812 06:20:26.800606  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000015 (ops 71-75)
I20260812 06:20:26.800654  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000016 (ops 76-80)
I20260812 06:20:26.800670  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000017 (ops 81-85)
I20260812 06:20:26.800727  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000018 (ops 86-90)
I20260812 06:20:26.800765  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000019 (ops 91-95)
I20260812 06:20:26.800779  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000020 (ops 96-100)
I20260812 06:20:26.800822  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000021 (ops 101-105)
I20260812 06:20:26.800865  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000022 (ops 106-110)
I20260812 06:20:26.800901  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000023 (ops 111-115)
I20260812 06:20:26.800954  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000024 (ops 116-120)
I20260812 06:20:26.800987  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000025 (ops 121-124)
I20260812 06:20:26.825361  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: LogGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.025s	user 0.000s	sys 0.021s Metrics: {}
I20260812 06:20:26.831195  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1): 448 bytes on disk
I20260812 06:20:26.832067  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":210,"lbm_reads_lt_1ms":4}
I20260812 06:20:26.832804  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:26.855463  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.022s	user 0.003s	sys 0.012s Metrics: {"bytes_written":4184708,"delete_count":0,"lbm_write_time_us":6900,"lbm_writes_lt_1ms":105,"reinsert_count":0,"update_count":510}
I20260812 06:20:26.855880  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:26.866726  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4020609,"delete_count":0,"lbm_write_time_us":4290,"lbm_writes_lt_1ms":101,"reinsert_count":0,"update_count":490}
I20260812 06:20:26.867215  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:27.096933  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.230s	user 0.133s	sys 0.085s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979754,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":2149,"lbm_read_time_us":16151,"lbm_reads_lt_1ms":774,"lbm_write_time_us":35482,"lbm_writes_lt_1ms":743,"mutex_wait_us":1567,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":6144,"thread_start_us":76,"threads_started":1,"update_count":3500}
I20260812 06:20:27.097620  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=18.063937
I20260812 06:20:27.167271  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.069s	user 0.030s	sys 0.036s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":31662,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":500,"reinsert_count":0,"update_count":2500}
I20260812 06:20:27.167763  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:27.178189  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.010s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3880,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.178973  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:27.369935  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.191s	user 0.131s	sys 0.059s 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":852,"lbm_read_time_us":13197,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32543,"lbm_writes_lt_1ms":643,"mutex_wait_us":52,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":13184,"update_count":3000}
I20260812 06:20:27.370713  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=14.095187
I20260812 06:20:27.428085  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.057s	user 0.027s	sys 0.021s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":22441,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:27.428617  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:27.441350  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.013s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4432,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.441864  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:27.617286  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.175s	user 0.122s	sys 0.049s 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":330,"lbm_read_time_us":13142,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30878,"lbm_writes_lt_1ms":543,"mutex_wait_us":45,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5760,"update_count":2500}
I20260812 06:20:27.617969  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=15.087375
I20260812 06:20:27.673269  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.055s	user 0.038s	sys 0.013s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":24944,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:20:27.673938  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:27.688060  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.014s	user 0.006s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5144,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:27.688618  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:27.853955  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.165s	user 0.103s	sys 0.061s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774675,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":710,"lbm_read_time_us":13652,"lbm_reads_lt_1ms":564,"lbm_write_time_us":27363,"lbm_writes_lt_1ms":543,"mutex_wait_us":34,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":97280,"update_count":2500}
I20260812 06:20:27.854662  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=14.095187
I20260812 06:20:27.911542  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.057s	user 0.039s	sys 0.015s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":18958,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:27.912068  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:27.922058  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.010s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3962,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.922452  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:28.102468  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.180s	user 0.115s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774691,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":225,"lbm_read_time_us":11965,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31140,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:28.103186  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=11.118625
I20260812 06:20:28.141378  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.038s	user 0.030s	sys 0.004s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":16284,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:28.142094  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:28.175930  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.033s	user 0.007s	sys 0.014s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4704,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:28.176405  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:28.186316  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3843,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.186672  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:28.217595  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushMRSOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.031s	user 0.027s	sys 0.000s Metrics: {"bytes_written":1152510,"cfile_init":1,"dirs.queue_time_us":58,"dirs.run_cpu_time_us":188,"dirs.run_wall_time_us":1539,"drs_written":1,"lbm_read_time_us":43,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1481,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:28.218516  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1): 447 bytes on disk
I20260812 06:20:28.219049  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: UndoDeltaBlockGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":45,"lbm_reads_lt_1ms":4}
I20260812 06:20:28.219767  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:28.384244  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.164s	user 0.104s	sys 0.060s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774799,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":157,"lbm_read_time_us":10219,"lbm_reads_lt_1ms":565,"lbm_write_time_us":28058,"lbm_writes_lt_1ms":543,"mutex_wait_us":35,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7040,"update_count":2500}
I20260812 06:20:28.386025  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling LogGCOp(9e13ef8385aa4797bb0d10fe064157d1): free 121006629 bytes of WAL
I20260812 06:20:28.386420  8597 log_reader.cc:385] T 9e13ef8385aa4797bb0d10fe064157d1: removed 12 log segments from log reader
I20260812 06:20:28.386529  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000026 (ops 125-129)
I20260812 06:20:28.386644  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000027 (ops 130-134)
I20260812 06:20:28.386731  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000028 (ops 135-139)
I20260812 06:20:28.386844  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000029 (ops 140-144)
I20260812 06:20:28.386952  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000030 (ops 145-149)
I20260812 06:20:28.387044  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000031 (ops 150-154)
I20260812 06:20:28.387135  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000032 (ops 155-158)
I20260812 06:20:28.387230  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000033 (ops 159-163)
I20260812 06:20:28.387323  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000034 (ops 164-168)
I20260812 06:20:28.387427  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000035 (ops 169-173)
I20260812 06:20:28.388401  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000036 (ops 174-178)
I20260812 06:20:28.388515  8597 log.cc:1079] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/9e13ef8385aa4797bb0d10fe064157d1/wal-000000037 (ops 179-183)
I20260812 06:20:28.424510  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: LogGCOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.038s	user 0.000s	sys 0.034s Metrics: {}
I20260812 06:20:28.425472  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=18.063937
I20260812 06:20:28.499651  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.074s	user 0.053s	sys 0.019s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":29987,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:28.500274  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:28.528390  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.028s	user 0.006s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6255,"lbm_writes_lt_1ms":103,"mutex_wait_us":2,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.529129  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=2.188937
I20260812 06:20:28.539934  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.010s	user 0.002s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4246,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.540337  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=1.000000
I20260812 06:20:28.671177  8474 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.778s	user 1.673s	sys 0.189s
I20260812 06:20:28.778662  8474 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.107s	user 0.007s	sys 0.000s
I20260812 06:20:28.780274  8474 tablet_server.cc:179] TabletServer@127.8.70.129:0 shutting down...
I20260812 06:20:28.781286  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: MajorDeltaCompactionOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.241s	user 0.148s	sys 0.092s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979634,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":836,"lbm_read_time_us":17296,"lbm_reads_lt_1ms":769,"lbm_write_time_us":42470,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"update_count":3500}
I20260812 06:20:28.782002  8666 maintenance_manager.cc:419] P 9a370145cb2f4bfbac8c5e7772dad112: Scheduling FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1): perf score=10.126437
I20260812 06:20:28.812283  8597 maintenance_manager.cc:643] P 9a370145cb2f4bfbac8c5e7772dad112: FlushDeltaMemStoresOp(9e13ef8385aa4797bb0d10fe064157d1) complete. Timing: real 0.030s	user 0.018s	sys 0.009s Metrics: {"bytes_written":12307492,"delete_count":0,"lbm_write_time_us":13644,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:28.812897  8474 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:28.813271  8474 tablet_replica.cc:333] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112: stopping tablet replica
I20260812 06:20:28.813503  8474 raft_consensus.cc:2243] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:28.813757  8474 raft_consensus.cc:2272] T 9e13ef8385aa4797bb0d10fe064157d1 P 9a370145cb2f4bfbac8c5e7772dad112 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:28.832960  8474 tablet_server.cc:196] TabletServer@127.8.70.129:0 shutdown complete.
I20260812 06:20:28.841037  8474 master.cc:562] Master@127.8.70.190:45705 shutting down...
I20260812 06:20:28.844168  8474 raft_consensus.cc:2243] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:28.844331  8474 raft_consensus.cc:2272] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:28.844408  8474 tablet_replica.cc:333] T 00000000000000000000000000000000 P c14eb528b6b349cfb3fa19ab5b98e37e: stopping tablet replica
I20260812 06:20:28.856360  8474 master.cc:584] Master@127.8.70.190:45705 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5295 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:20:28.949067  8474 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.8.70.190:33087
I20260812 06:20:28.949501  8474 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:28.951624  8697 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:28.951690  8698 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:28.951761  8700 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:28.951783  8474 server_base.cc:1061] running on GCE node
I20260812 06:20:28.952055  8474 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:28.952113  8474 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:28.952139  8474 hybrid_clock.cc:648] HybridClock initialized: now 1786515628952138 us; error 0 us; skew 500 ppm
I20260812 06:20:28.953044  8474 webserver.cc:533] Webserver started at http://127.8.70.190:35111/ using document root <none> and password file <none>
I20260812 06:20:28.953217  8474 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:28.953275  8474 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:28.953351  8474 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:28.953763  8474 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/master-0-root/instance:
uuid: "c26f2f59315046b3b431f2b93e2f7ba0"
format_stamp: "Formatted at 2026-08-12 06:20:28 on dist-test-slave-6nmv"
I20260812 06:20:28.955230  8474 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:28.956086  8706 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:28.956302  8474 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:28.956384  8474 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/master-0-root
uuid: "c26f2f59315046b3b431f2b93e2f7ba0"
format_stamp: "Formatted at 2026-08-12 06:20:28 on dist-test-slave-6nmv"
I20260812 06:20:28.956478  8474 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-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:28.972203  8474 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:28.972532  8474 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:28.976518  8474 rpc_server.cc:307] RPC server started. Bound to: 127.8.70.190:33087
I20260812 06:20:28.979625  8769 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:28.979701  8768 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.8.70.190:33087 every 8 connection(s)
I20260812 06:20:28.988997  8769 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0: Bootstrap starting.
I20260812 06:20:28.989763  8769 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:28.990720  8769 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0: No bootstrap required, opened a new log
I20260812 06:20:28.991102  8769 raft_consensus.cc:359] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c26f2f59315046b3b431f2b93e2f7ba0" member_type: VOTER }
I20260812 06:20:28.991200  8769 raft_consensus.cc:385] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:28.991248  8769 raft_consensus.cc:740] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c26f2f59315046b3b431f2b93e2f7ba0, State: Initialized, Role: FOLLOWER
I20260812 06:20:28.991400  8769 consensus_queue.cc:260] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [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: "c26f2f59315046b3b431f2b93e2f7ba0" member_type: VOTER }
I20260812 06:20:28.991494  8769 raft_consensus.cc:399] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:28.991542  8769 raft_consensus.cc:493] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:28.991608  8769 raft_consensus.cc:3060] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:28.992308  8769 raft_consensus.cc:515] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c26f2f59315046b3b431f2b93e2f7ba0" member_type: VOTER }
I20260812 06:20:28.992473  8769 leader_election.cc:304] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [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: c26f2f59315046b3b431f2b93e2f7ba0; no voters: 
I20260812 06:20:28.992667  8769 leader_election.cc:290] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:28.992740  8772 raft_consensus.cc:2804] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:28.992972  8772 raft_consensus.cc:697] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 1 LEADER]: Becoming Leader. State: Replica: c26f2f59315046b3b431f2b93e2f7ba0, State: Running, Role: LEADER
I20260812 06:20:28.993122  8769 sys_catalog.cc:565] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:28.993142  8772 consensus_queue.cc:237] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [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: "c26f2f59315046b3b431f2b93e2f7ba0" member_type: VOTER }
I20260812 06:20:28.993546  8774 sys_catalog.cc:455] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "c26f2f59315046b3b431f2b93e2f7ba0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c26f2f59315046b3b431f2b93e2f7ba0" member_type: VOTER } }
I20260812 06:20:28.993649  8774 sys_catalog.cc:458] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:28.993558  8775 sys_catalog.cc:455] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [sys.catalog]: SysCatalogTable state changed. Reason: New leader c26f2f59315046b3b431f2b93e2f7ba0. Latest consensus state: current_term: 1 leader_uuid: "c26f2f59315046b3b431f2b93e2f7ba0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c26f2f59315046b3b431f2b93e2f7ba0" member_type: VOTER } }
I20260812 06:20:28.993767  8775 sys_catalog.cc:458] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:28.993966  8779 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:28.994624  8779 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:28.995057  8474 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:28.996201  8779 catalog_manager.cc:1383] Generated new cluster ID: 3f6bc2e8399b4f81a5c1e461a1009bbb
I20260812 06:20:28.996282  8779 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:29.008791  8779 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:29.009204  8779 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:29.021525  8779 catalog_manager.cc:6092] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0: Generated new TSK 0
I20260812 06:20:29.021680  8779 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:29.027137  8474 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:29.029023  8794 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:29.029227  8797 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:29.029237  8474 server_base.cc:1061] running on GCE node
W20260812 06:20:29.029237  8795 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:29.029606  8474 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:29.029666  8474 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:29.029688  8474 hybrid_clock.cc:648] HybridClock initialized: now 1786515629029688 us; error 0 us; skew 500 ppm
I20260812 06:20:29.030535  8474 webserver.cc:533] Webserver started at http://127.8.70.129:45559/ using document root <none> and password file <none>
I20260812 06:20:29.030699  8474 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:29.030756  8474 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:29.030829  8474 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:29.031184  8474 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/instance:
uuid: "bfe036c55bfd406794f59509980726de"
format_stamp: "Formatted at 2026-08-12 06:20:29 on dist-test-slave-6nmv"
I20260812 06:20:29.032611  8474 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:20:29.033489  8803 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:29.033689  8474 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:29.033766  8474 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root
uuid: "bfe036c55bfd406794f59509980726de"
format_stamp: "Formatted at 2026-08-12 06:20:29 on dist-test-slave-6nmv"
I20260812 06:20:29.033864  8474 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-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:29.044716  8474 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:29.045028  8474 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:29.045293  8474 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:29.045707  8474 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:29.045761  8474 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:29.045814  8474 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:29.045869  8474 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:29.049866  8474 rpc_server.cc:307] RPC server started. Bound to: 127.8.70.129:34653
I20260812 06:20:29.049870  8878 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.8.70.129:34653 every 8 connection(s)
I20260812 06:20:29.058056  8879 heartbeater.cc:344] Connected to a master server at 127.8.70.190:33087
I20260812 06:20:29.058184  8879 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:29.058370  8879 heartbeater.cc:507] Master 127.8.70.190:33087 requested a full tablet report, sending...
I20260812 06:20:29.058947  8728 ts_manager.cc:194] Registered new tserver with Master: bfe036c55bfd406794f59509980726de (127.8.70.129:34653)
I20260812 06:20:29.059176  8474 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.008896041s
I20260812 06:20:29.059697  8728 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:56734
I20260812 06:20:29.066040  8728 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:56744:
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:29.074126  8835 tablet_service.cc:1511] Processing CreateTablet for tablet e235131687f34482950de10736b0d18d (DEFAULT_TABLE table=heavy-update-compaction-test [id=8a33369607294ae490cba228043a5f03]), partition=
I20260812 06:20:29.074419  8835 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet e235131687f34482950de10736b0d18d. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:29.076218  8894 tablet_bootstrap.cc:492] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Bootstrap starting.
I20260812 06:20:29.077060  8894 tablet_bootstrap.cc:654] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:29.078009  8894 tablet_bootstrap.cc:492] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: No bootstrap required, opened a new log
I20260812 06:20:29.078121  8894 ts_tablet_manager.cc:1403] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:20:29.078447  8894 raft_consensus.cc:359] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfe036c55bfd406794f59509980726de" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 34653 } }
I20260812 06:20:29.078531  8894 raft_consensus.cc:385] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:29.078572  8894 raft_consensus.cc:740] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: bfe036c55bfd406794f59509980726de, State: Initialized, Role: FOLLOWER
I20260812 06:20:29.078728  8894 consensus_queue.cc:260] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [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: "bfe036c55bfd406794f59509980726de" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 34653 } }
I20260812 06:20:29.078799  8894 raft_consensus.cc:399] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:29.078850  8894 raft_consensus.cc:493] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:29.078912  8894 raft_consensus.cc:3060] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:29.079731  8894 raft_consensus.cc:515] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfe036c55bfd406794f59509980726de" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 34653 } }
I20260812 06:20:29.079898  8894 leader_election.cc:304] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [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: bfe036c55bfd406794f59509980726de; no voters: 
I20260812 06:20:29.080091  8894 leader_election.cc:290] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:29.080201  8896 raft_consensus.cc:2804] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:29.080452  8896 raft_consensus.cc:697] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 1 LEADER]: Becoming Leader. State: Replica: bfe036c55bfd406794f59509980726de, State: Running, Role: LEADER
I20260812 06:20:29.080453  8879 heartbeater.cc:499] Master 127.8.70.190:33087 was elected leader, sending a full tablet report...
I20260812 06:20:29.080533  8894 ts_tablet_manager.cc:1434] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:20:29.080615  8896 consensus_queue.cc:237] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [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: "bfe036c55bfd406794f59509980726de" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 34653 } }
I20260812 06:20:29.081987  8728 catalog_manager.cc:5719] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de reported cstate change: term changed from 0 to 1, leader changed from <none> to bfe036c55bfd406794f59509980726de (127.8.70.129). New cstate: current_term: 1 leader_uuid: "bfe036c55bfd406794f59509980726de" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bfe036c55bfd406794f59509980726de" member_type: VOTER last_known_addr { host: "127.8.70.129" port: 34653 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:29.134158  8474 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.049s	user 0.008s	sys 0.012s
I20260812 06:20:29.300753  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushMRSOp(e235131687f34482950de10736b0d18d): perf score=23.023690
I20260812 06:20:29.465011  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushMRSOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.164s	user 0.113s	sys 0.048s Metrics: {"bytes_written":12307490,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":52,"dirs.run_cpu_time_us":225,"dirs.run_wall_time_us":772,"drs_written":1,"lbm_read_time_us":36,"lbm_reads_lt_1ms":4,"lbm_write_time_us":45872,"lbm_writes_lt_1ms":857,"peak_mem_usage":0,"reinsert_count":0,"rows_written":105,"update_count":1500}
I20260812 06:20:29.466132  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling LogGCOp(e235131687f34482950de10736b0d18d): free 20743880 bytes of WAL
I20260812 06:20:29.466392  8808 log_reader.cc:385] T e235131687f34482950de10736b0d18d: removed 2 log segments from log reader
I20260812 06:20:29.466454  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000001 (ops 1-6)
I20260812 06:20:29.466511  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000002 (ops 7-11)
I20260812 06:20:29.471724  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: LogGCOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.005s	user 0.000s	sys 0.004s Metrics: {}
I20260812 06:20:29.472167  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:29.496789  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.024s	user 0.005s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4240,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.497221  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d): 20513814 bytes on disk
I20260812 06:20:29.497593  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4}
I20260812 06:20:29.498051  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:29.508153  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3845,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.508773  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:29.685765  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.177s	user 0.123s	sys 0.053s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24815802,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":604,"lbm_read_time_us":13258,"lbm_reads_lt_1ms":569,"lbm_write_time_us":29297,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"thread_start_us":271,"threads_started":5,"update_count":2500}
I20260812 06:20:29.686370  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:29.735095  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.049s	user 0.032s	sys 0.011s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":19744,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:29.735478  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:29.744900  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3658,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.745522  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:29.919282  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.173s	user 0.112s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":187,"lbm_read_time_us":10947,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33486,"lbm_writes_lt_1ms":543,"mutex_wait_us":32,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":45952,"update_count":2500}
I20260812 06:20:29.920032  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:29.961789  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.042s	user 0.027s	sys 0.011s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":19349,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:29.962420  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:30.114869  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.152s	user 0.111s	sys 0.036s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20713153,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":332,"lbm_read_time_us":9721,"lbm_reads_lt_1ms":467,"lbm_write_time_us":26362,"lbm_writes_lt_1ms":443,"mutex_wait_us":72,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":3712,"update_count":2000}
I20260812 06:20:30.115623  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=11.118625
I20260812 06:20:30.145125  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.029s	user 0.026s	sys 0.000s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":12797,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:30.145557  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:30.156862  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4330,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:30.157608  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:30.289216  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.131s	user 0.109s	sys 0.020s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713263,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":351,"lbm_read_time_us":8721,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24137,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1664,"update_count":2000}
I20260812 06:20:30.289968  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=10.126437
I20260812 06:20:30.331220  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.041s	user 0.020s	sys 0.020s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":19002,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:20:30.331812  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:30.356024  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.024s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5199,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.356511  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:30.371025  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.014s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5636,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.371512  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:30.526342  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.155s	user 0.122s	sys 0.032s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24815801,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":570,"lbm_read_time_us":11365,"lbm_reads_lt_1ms":573,"lbm_write_time_us":28964,"lbm_writes_lt_1ms":543,"mutex_wait_us":50,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":20608,"update_count":2500}
I20260812 06:20:30.527102  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=10.126437
I20260812 06:20:30.561712  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.033s	user 0.022s	sys 0.007s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":14080,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:30.562254  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:30.576903  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.014s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5403,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.577489  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:30.702337  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.125s	user 0.105s	sys 0.019s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713273,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":806,"lbm_read_time_us":8677,"lbm_reads_lt_1ms":472,"lbm_write_time_us":22093,"lbm_writes_lt_1ms":443,"mutex_wait_us":45,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":18560,"update_count":2000}
I20260812 06:20:30.702935  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=10.126437
I20260812 06:20:30.755122  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.052s	user 0.021s	sys 0.028s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16325,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:30.755759  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:30.767141  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4525,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.767622  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushMRSOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:30.812664  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushMRSOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.045s	user 0.029s	sys 0.004s Metrics: {"bytes_written":1275444,"cfile_init":1,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":205,"dirs.run_wall_time_us":1264,"drs_written":1,"lbm_read_time_us":50,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1567,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:20:30.813237  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling LogGCOp(e235131687f34482950de10736b0d18d): free 132571301 bytes of WAL
I20260812 06:20:30.813453  8808 log_reader.cc:385] T e235131687f34482950de10736b0d18d: removed 13 log segments from log reader
I20260812 06:20:30.813495  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000003 (ops 12-16)
I20260812 06:20:30.813522  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000004 (ops 17-20)
I20260812 06:20:30.813571  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000005 (ops 21-25)
I20260812 06:20:30.813608  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000006 (ops 26-30)
I20260812 06:20:30.813645  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000007 (ops 31-35)
I20260812 06:20:30.813681  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000008 (ops 36-40)
I20260812 06:20:30.813715  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000009 (ops 41-45)
I20260812 06:20:30.813753  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000010 (ops 46-50)
I20260812 06:20:30.813800  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000011 (ops 51-55)
I20260812 06:20:30.813858  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000012 (ops 56-60)
I20260812 06:20:30.813891  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000013 (ops 61-64)
I20260812 06:20:30.813928  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000014 (ops 65-69)
I20260812 06:20:30.813962  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000015 (ops 70-74)
I20260812 06:20:30.844617  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: LogGCOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.031s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:20:30.845108  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d): 482 bytes on disk
I20260812 06:20:30.845629  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":68,"lbm_reads_lt_1ms":4}
I20260812 06:20:30.846238  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=3.181125
I20260812 06:20:30.858858  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.012s	user 0.002s	sys 0.009s Metrics: {"bytes_written":4800074,"delete_count":0,"lbm_write_time_us":4967,"lbm_writes_lt_1ms":120,"reinsert_count":0,"update_count":585}
I20260812 06:20:30.859272  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:30.868399  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.009s	user 0.006s	sys 0.001s Metrics: {"bytes_written":3405230,"delete_count":0,"lbm_write_time_us":3396,"lbm_writes_lt_1ms":86,"reinsert_count":0,"update_count":415}
I20260812 06:20:30.868767  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:31.086228  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.217s	user 0.148s	sys 0.068s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28918320,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":933,"lbm_read_time_us":14760,"lbm_reads_lt_1ms":674,"lbm_write_time_us":37538,"lbm_writes_lt_1ms":643,"mutex_wait_us":644,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":28416,"thread_start_us":95,"threads_started":1,"update_count":3000}
I20260812 06:20:31.087031  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:31.141387  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.054s	user 0.025s	sys 0.021s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":22554,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:31.141944  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:31.151734  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3886,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.152288  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:31.322780  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.170s	user 0.101s	sys 0.068s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":169,"lbm_read_time_us":11418,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29126,"lbm_writes_lt_1ms":543,"mutex_wait_us":1,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":2500}
I20260812 06:20:31.323305  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:31.386801  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.063s	user 0.041s	sys 0.012s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22867,"lbm_writes_lt_1ms":403,"reinsert_count":0,"spinlock_wait_cycles":6528,"update_count":2000}
I20260812 06:20:31.387318  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:31.398417  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4364,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.398813  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:31.585577  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.187s	user 0.118s	sys 0.064s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":749,"lbm_read_time_us":12984,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30304,"lbm_writes_lt_1ms":543,"mutex_wait_us":313,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10240,"update_count":2500}
I20260812 06:20:31.586107  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:31.647699  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.061s	user 0.028s	sys 0.030s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21154,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:31.648145  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:31.657603  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.009s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3775,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.658066  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:31.833850  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.176s	user 0.103s	sys 0.072s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":150,"lbm_read_time_us":12336,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29106,"lbm_writes_lt_1ms":543,"mutex_wait_us":64,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10880,"update_count":2500}
I20260812 06:20:31.834654  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:31.889225  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.054s	user 0.028s	sys 0.019s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23128,"lbm_writes_lt_1ms":403,"mutex_wait_us":44,"reinsert_count":0,"update_count":2000}
I20260812 06:20:31.889710  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:31.910231  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.020s	user 0.012s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4050,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.910791  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:32.083384  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.172s	user 0.107s	sys 0.065s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815682,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":266,"lbm_read_time_us":11595,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29106,"lbm_writes_lt_1ms":543,"mutex_wait_us":80,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:32.083966  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:32.131608  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.047s	user 0.026s	sys 0.015s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":19047,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:32.132105  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:32.143177  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4088,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.143859  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:32.330117  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.186s	user 0.098s	sys 0.079s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815685,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":716,"lbm_read_time_us":10662,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28242,"lbm_writes_lt_1ms":543,"mutex_wait_us":19,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":17792,"update_count":2500}
I20260812 06:20:32.330771  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=14.095187
I20260812 06:20:32.381537  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.051s	user 0.021s	sys 0.020s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":20104,"lbm_writes_lt_1ms":403,"reinsert_count":0,"spinlock_wait_cycles":1920,"update_count":2000}
I20260812 06:20:32.382083  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:32.393335  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3903,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.394011  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushMRSOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:32.428716  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushMRSOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.034s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1316415,"cfile_init":1,"dirs.queue_time_us":61,"dirs.run_cpu_time_us":233,"dirs.run_wall_time_us":1332,"drs_written":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2256,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:20:32.429329  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling LogGCOp(e235131687f34482950de10736b0d18d): free 133024416 bytes of WAL
I20260812 06:20:32.429561  8808 log_reader.cc:385] T e235131687f34482950de10736b0d18d: removed 13 log segments from log reader
I20260812 06:20:32.429605  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000016 (ops 75-79)
I20260812 06:20:32.429633  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000017 (ops 80-84)
I20260812 06:20:32.429688  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000018 (ops 85-89)
I20260812 06:20:32.429744  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000019 (ops 90-94)
I20260812 06:20:32.429788  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000020 (ops 95-99)
I20260812 06:20:32.429844  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000021 (ops 100-104)
I20260812 06:20:32.429888  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000022 (ops 105-109)
I20260812 06:20:32.429944  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000023 (ops 110-114)
I20260812 06:20:32.430002  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000024 (ops 115-118)
I20260812 06:20:32.430040  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000025 (ops 119-123)
I20260812 06:20:32.430078  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000026 (ops 124-128)
I20260812 06:20:32.430115  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000027 (ops 129-133)
I20260812 06:20:32.430152  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000028 (ops 134-138)
I20260812 06:20:32.459955  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: LogGCOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.030s	user 0.001s	sys 0.027s Metrics: {}
I20260812 06:20:32.460355  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=3.181125
I20260812 06:20:32.479463  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.019s	user 0.004s	sys 0.012s Metrics: {"bytes_written":4512899,"delete_count":0,"lbm_write_time_us":7112,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:32.479902  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:32.489319  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.009s	user 0.004s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3806,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:32.490023  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:32.739593  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.249s	user 0.156s	sys 0.080s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020730,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":561,"lbm_read_time_us":14230,"lbm_reads_lt_1ms":774,"lbm_write_time_us":40705,"lbm_writes_lt_1ms":743,"mutex_wait_us":64,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":79616,"thread_start_us":90,"threads_started":1,"update_count":3500}
I20260812 06:20:32.740432  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=18.063937
I20260812 06:20:32.814106  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.073s	user 0.045s	sys 0.020s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":30086,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:32.814568  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:32.825519  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4126,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.826299  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d): 493 bytes on disk
I20260812 06:20:32.826838  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":88,"lbm_reads_lt_1ms":4}
I20260812 06:20:32.827484  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:33.040395  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.213s	user 0.120s	sys 0.086s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918099,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":720,"lbm_read_time_us":14619,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32880,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3200,"update_count":3000}
I20260812 06:20:33.041078  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=18.063937
I20260812 06:20:33.105903  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.065s	user 0.019s	sys 0.043s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":28876,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:20:33.106331  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:33.117623  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3927,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.118219  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:33.330770  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.212s	user 0.123s	sys 0.085s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918099,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":573,"lbm_read_time_us":14313,"lbm_reads_lt_1ms":672,"lbm_write_time_us":34997,"lbm_writes_lt_1ms":643,"mutex_wait_us":297,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":31104,"update_count":3000}
I20260812 06:20:33.331420  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=18.063937
I20260812 06:20:33.393030  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.061s	user 0.030s	sys 0.025s Metrics: {"bytes_written":20512318,"delete_count":0,"lbm_write_time_us":26019,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:33.393523  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:33.408645  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.015s	user 0.010s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5711,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.409219  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:33.611302  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.202s	user 0.111s	sys 0.079s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918100,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":273,"lbm_read_time_us":14077,"lbm_reads_lt_1ms":672,"lbm_write_time_us":31220,"lbm_writes_lt_1ms":643,"mutex_wait_us":25,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":10496,"update_count":3000}
I20260812 06:20:33.612073  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=18.063937
I20260812 06:20:33.677001  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.065s	user 0.025s	sys 0.029s Metrics: {"bytes_written":20512318,"delete_count":0,"lbm_write_time_us":26580,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:33.677488  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:33.687386  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3688,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.687996  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:33.881218  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.193s	user 0.137s	sys 0.056s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918100,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":265,"lbm_read_time_us":13894,"lbm_reads_lt_1ms":672,"lbm_write_time_us":33393,"lbm_writes_lt_1ms":643,"mutex_wait_us":32,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":54528,"update_count":3000}
I20260812 06:20:33.881997  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=15.087375
I20260812 06:20:33.930262  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.048s	user 0.018s	sys 0.029s Metrics: {"bytes_written":16820144,"delete_count":0,"lbm_write_time_us":21848,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:20:33.930899  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:33.947655  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.017s	user 0.010s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6604,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:33.948205  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushMRSOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:33.970186  8474 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.836s	user 1.761s	sys 0.187s
I20260812 06:20:33.976107  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushMRSOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.028s	user 0.026s	sys 0.000s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":71,"dirs.run_cpu_time_us":233,"dirs.run_wall_time_us":1194,"drs_written":1,"lbm_read_time_us":48,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2012,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31,"spinlock_wait_cycles":7936}
I20260812 06:20:33.976861  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling LogGCOp(e235131687f34482950de10736b0d18d): free 121006640 bytes of WAL
I20260812 06:20:33.977090  8808 log_reader.cc:385] T e235131687f34482950de10736b0d18d: removed 12 log segments from log reader
I20260812 06:20:33.977154  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000029 (ops 139-143)
I20260812 06:20:33.977226  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000030 (ops 144-148)
I20260812 06:20:33.977270  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000031 (ops 149-153)
I20260812 06:20:33.977319  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000032 (ops 154-158)
I20260812 06:20:33.977360  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000033 (ops 159-163)
I20260812 06:20:33.977403  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000034 (ops 164-168)
I20260812 06:20:33.977445  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000035 (ops 169-173)
I20260812 06:20:33.977488  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000036 (ops 174-178)
I20260812 06:20:33.977530  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000037 (ops 179-182)
I20260812 06:20:33.977572  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000038 (ops 183-187)
I20260812 06:20:33.977614  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000039 (ops 188-192)
I20260812 06:20:33.977658  8808 log.cc:1079] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: Deleting log segment in path: /tmp/dist-test-taskJCLsi_/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623643572-8474-0/minicluster-data/ts-0-root/wals/e235131687f34482950de10736b0d18d/wal-000000040 (ops 193-197)
I20260812 06:20:34.001317  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: LogGCOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.024s	user 0.000s	sys 0.021s Metrics: {}
I20260812 06:20:34.001943  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d): 482 bytes on disk
I20260812 06:20:34.002424  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: UndoDeltaBlockGCOp(e235131687f34482950de10736b0d18d) 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:34.003006  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d): perf score=2.188937
I20260812 06:20:34.013737  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: FlushDeltaMemStoresOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.011s	user 0.001s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4510,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:34.014195  8880 maintenance_manager.cc:419] P bfe036c55bfd406794f59509980726de: Scheduling MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d): perf score=1.000000
I20260812 06:20:34.040745  8474 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.070s	user 0.001s	sys 0.000s
I20260812 06:20:34.041246  8474 tablet_server.cc:179] TabletServer@127.8.70.129:0 shutting down...
I20260812 06:20:34.144977  8808 maintenance_manager.cc:643] P bfe036c55bfd406794f59509980726de: MajorDeltaCompactionOp(e235131687f34482950de10736b0d18d) complete. Timing: real 0.131s	user 0.098s	sys 0.032s Metrics: {"cfile_cache_hit":526,"cfile_cache_hit_bytes":23954995,"cfile_cache_miss":107,"cfile_cache_miss_bytes":4963208,"cfile_init":4,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":607,"lbm_read_time_us":1840,"lbm_reads_lt_1ms":123,"lbm_write_time_us":28621,"lbm_writes_lt_1ms":643,"mutex_wait_us":125,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1920,"thread_start_us":101,"threads_started":1,"update_count":3000}
I20260812 06:20:34.146303  8474 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:34.146564  8474 tablet_replica.cc:333] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de: stopping tablet replica
I20260812 06:20:34.146718  8474 raft_consensus.cc:2243] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:34.146900  8474 raft_consensus.cc:2272] T e235131687f34482950de10736b0d18d P bfe036c55bfd406794f59509980726de [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:34.160307  8474 tablet_server.cc:196] TabletServer@127.8.70.129:0 shutdown complete.
I20260812 06:20:34.196593  8474 master.cc:562] Master@127.8.70.190:33087 shutting down...
I20260812 06:20:34.199956  8474 raft_consensus.cc:2243] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:34.200127  8474 raft_consensus.cc:2272] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:34.200201  8474 tablet_replica.cc:333] T 00000000000000000000000000000000 P c26f2f59315046b3b431f2b93e2f7ba0: stopping tablet replica
I20260812 06:20:34.212330  8474 master.cc:584] Master@127.8.70.190:33087 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5348 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10644 ms total)

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