[==========] 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:17:42.669786 16374 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.15.253.190:32939
I20260812 06:17:42.670807 16374 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:17:42.671471 16374 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:42.678200 16380 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:17:42.678182 16382 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:17:42.678459 16379 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:17:42.678454 16374 server_base.cc:1061] running on GCE node
I20260812 06:17:42.679016 16374 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:42.679136 16374 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:17:42.679180 16374 hybrid_clock.cc:648] HybridClock initialized: now 1786515462679177 us; error 0 us; skew 500 ppm
I20260812 06:17:42.680919 16374 webserver.cc:533] Webserver started at http://127.15.253.190:34745/ using document root <none> and password file <none>
I20260812 06:17:42.681458 16374 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:42.681545 16374 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:42.681831 16374 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:42.683439 16374 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/master-0-root/instance:
uuid: "6ca8bc1345134feca84eadaf6fb8375f"
format_stamp: "Formatted at 2026-08-12 06:17:42 on dist-test-slave-cbbz"
I20260812 06:17:42.686895 16374 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.003s	sys 0.003s
I20260812 06:17:42.688979 16389 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:17:42.689972 16374 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:42.690104 16374 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/master-0-root
uuid: "6ca8bc1345134feca84eadaf6fb8375f"
format_stamp: "Formatted at 2026-08-12 06:17:42 on dist-test-slave-cbbz"
I20260812 06:17:42.690204 16374 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-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:17:42.699458 16374 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:42.700007 16374 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:17:42.700233 16374 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:42.707687 16374 rpc_server.cc:307] RPC server started. Bound to: 127.15.253.190:32939
I20260812 06:17:42.707710 16450 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.15.253.190:32939 every 8 connection(s)
I20260812 06:17:42.709961 16451 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:17:42.715279 16451 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f: Bootstrap starting.
I20260812 06:17:42.717710 16451 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:42.718614 16451 log.cc:826] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f: Log is configured to *not* fsync() on all Append() calls
I20260812 06:17:42.720280 16451 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f: No bootstrap required, opened a new log
I20260812 06:17:42.722955 16451 raft_consensus.cc:359] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6ca8bc1345134feca84eadaf6fb8375f" member_type: VOTER }
I20260812 06:17:42.723120 16451 raft_consensus.cc:385] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:42.723251 16451 raft_consensus.cc:740] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 6ca8bc1345134feca84eadaf6fb8375f, State: Initialized, Role: FOLLOWER
I20260812 06:17:42.723912 16451 consensus_queue.cc:260] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [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: "6ca8bc1345134feca84eadaf6fb8375f" member_type: VOTER }
I20260812 06:17:42.724088 16451 raft_consensus.cc:399] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:42.724192 16451 raft_consensus.cc:493] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:42.724308 16451 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:42.725018 16451 raft_consensus.cc:515] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6ca8bc1345134feca84eadaf6fb8375f" member_type: VOTER }
I20260812 06:17:42.725386 16451 leader_election.cc:304] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [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: 6ca8bc1345134feca84eadaf6fb8375f; no voters: 
I20260812 06:17:42.725654 16451 leader_election.cc:290] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:42.725826 16455 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:42.726052 16455 raft_consensus.cc:697] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 1 LEADER]: Becoming Leader. State: Replica: 6ca8bc1345134feca84eadaf6fb8375f, State: Running, Role: LEADER
I20260812 06:17:42.726478 16455 consensus_queue.cc:237] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [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: "6ca8bc1345134feca84eadaf6fb8375f" member_type: VOTER }
I20260812 06:17:42.726648 16451 sys_catalog.cc:565] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:42.728315 16456 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "6ca8bc1345134feca84eadaf6fb8375f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6ca8bc1345134feca84eadaf6fb8375f" member_type: VOTER } }
I20260812 06:17:42.728433 16456 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:42.728699 16457 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [sys.catalog]: SysCatalogTable state changed. Reason: New leader 6ca8bc1345134feca84eadaf6fb8375f. Latest consensus state: current_term: 1 leader_uuid: "6ca8bc1345134feca84eadaf6fb8375f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6ca8bc1345134feca84eadaf6fb8375f" member_type: VOTER } }
I20260812 06:17:42.728796 16457 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:42.729151 16374 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
W20260812 06:17:42.730924 16474 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:17:42.730988 16474 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:17:42.731070 16467 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:42.731773 16467 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:42.736397 16467 catalog_manager.cc:1383] Generated new cluster ID: 012fd2f497874eb29306a255409da353
I20260812 06:17:42.736461 16467 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:42.754630 16467 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:42.755769 16467 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:42.767459 16467 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f: Generated new TSK 0
I20260812 06:17:42.768275 16467 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:42.794081 16374 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:42.796677 16478 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:17:42.796806 16479 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:17:42.796964 16481 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:17:42.797470 16374 server_base.cc:1061] running on GCE node
I20260812 06:17:42.797734 16374 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:42.797793 16374 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:17:42.797822 16374 hybrid_clock.cc:648] HybridClock initialized: now 1786515462797821 us; error 0 us; skew 500 ppm
I20260812 06:17:42.798940 16374 webserver.cc:533] Webserver started at http://127.15.253.129:41755/ using document root <none> and password file <none>
I20260812 06:17:42.799125 16374 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:42.799187 16374 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:42.799278 16374 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:42.799850 16374 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/instance:
uuid: "85ec0eea9cda401681deb327b56e9d3f"
format_stamp: "Formatted at 2026-08-12 06:17:42 on dist-test-slave-cbbz"
I20260812 06:17:42.802202 16374 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:42.803357 16486 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:17:42.803602 16374 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:42.803738 16374 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root
uuid: "85ec0eea9cda401681deb327b56e9d3f"
format_stamp: "Formatted at 2026-08-12 06:17:42 on dist-test-slave-cbbz"
I20260812 06:17:42.803833 16374 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-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:17:42.815845 16374 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:42.816360 16374 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:42.816859 16374 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:42.817821 16374 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:42.817895 16374 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:42.817966 16374 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:42.818019 16374 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:42.825198 16374 rpc_server.cc:307] RPC server started. Bound to: 127.15.253.129:46133
I20260812 06:17:42.825237 16558 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.15.253.129:46133 every 8 connection(s)
I20260812 06:17:42.835062 16559 heartbeater.cc:344] Connected to a master server at 127.15.253.190:32939
I20260812 06:17:42.835320 16559 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:42.835762 16559 heartbeater.cc:507] Master 127.15.253.190:32939 requested a full tablet report, sending...
I20260812 06:17:42.837352 16408 ts_manager.cc:194] Registered new tserver with Master: 85ec0eea9cda401681deb327b56e9d3f (127.15.253.129:46133)
I20260812 06:17:42.837594 16374 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.011711794s
I20260812 06:17:42.838843 16408 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:44318
I20260812 06:17:42.846484 16408 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:44334:
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:17:42.859511 16519 tablet_service.cc:1511] Processing CreateTablet for tablet 48157dee62eb46eda79a8aee1fa26ffb (DEFAULT_TABLE table=heavy-update-compaction-test [id=ef4462a76fdc433ab59a2ef1745a941f]), partition=
I20260812 06:17:42.859939 16519 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 48157dee62eb46eda79a8aee1fa26ffb. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:42.862313 16572 tablet_bootstrap.cc:492] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Bootstrap starting.
I20260812 06:17:42.863479 16572 tablet_bootstrap.cc:654] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:42.864818 16572 tablet_bootstrap.cc:492] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: No bootstrap required, opened a new log
I20260812 06:17:42.864918 16572 ts_tablet_manager.cc:1403] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Time spent bootstrapping tablet: real 0.003s	user 0.000s	sys 0.002s
I20260812 06:17:42.865430 16572 raft_consensus.cc:359] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "85ec0eea9cda401681deb327b56e9d3f" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 46133 } }
I20260812 06:17:42.865552 16572 raft_consensus.cc:385] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:42.865586 16572 raft_consensus.cc:740] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 85ec0eea9cda401681deb327b56e9d3f, State: Initialized, Role: FOLLOWER
I20260812 06:17:42.865734 16572 consensus_queue.cc:260] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [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: "85ec0eea9cda401681deb327b56e9d3f" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 46133 } }
I20260812 06:17:42.865821 16572 raft_consensus.cc:399] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:42.865856 16572 raft_consensus.cc:493] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:42.865897 16572 raft_consensus.cc:3060] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:42.866844 16572 raft_consensus.cc:515] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "85ec0eea9cda401681deb327b56e9d3f" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 46133 } }
I20260812 06:17:42.866989 16572 leader_election.cc:304] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [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: 85ec0eea9cda401681deb327b56e9d3f; no voters: 
I20260812 06:17:42.867204 16572 leader_election.cc:290] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:42.867648 16572 ts_tablet_manager.cc:1434] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:17:42.867756 16575 raft_consensus.cc:2804] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:42.868069 16575 raft_consensus.cc:697] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 1 LEADER]: Becoming Leader. State: Replica: 85ec0eea9cda401681deb327b56e9d3f, State: Running, Role: LEADER
I20260812 06:17:42.868156 16559 heartbeater.cc:499] Master 127.15.253.190:32939 was elected leader, sending a full tablet report...
I20260812 06:17:42.868307 16575 consensus_queue.cc:237] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [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: "85ec0eea9cda401681deb327b56e9d3f" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 46133 } }
I20260812 06:17:42.870893 16408 catalog_manager.cc:5719] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f reported cstate change: term changed from 0 to 1, leader changed from <none> to 85ec0eea9cda401681deb327b56e9d3f (127.15.253.129). New cstate: current_term: 1 leader_uuid: "85ec0eea9cda401681deb327b56e9d3f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "85ec0eea9cda401681deb327b56e9d3f" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 46133 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:42.935528 16374 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.055s	user 0.018s	sys 0.009s
I20260812 06:17:43.076345 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=19.054940
I20260812 06:17:43.252310 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.176s	user 0.116s	sys 0.048s Metrics: {"bytes_written":12307490,"cfile_init":1,"compiler_manager_pool.queue_time_us":264,"delete_count":0,"dirs.queue_time_us":88,"dirs.run_cpu_time_us":175,"dirs.run_wall_time_us":954,"drs_written":1,"lbm_read_time_us":94,"lbm_reads_lt_1ms":4,"lbm_write_time_us":40698,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"thread_start_us":155,"threads_started":1,"update_count":1500}
I20260812 06:17:43.253543 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling LogGCOp(48157dee62eb46eda79a8aee1fa26ffb): free 20290830 bytes of WAL
I20260812 06:17:43.253846 16491 log_reader.cc:385] T 48157dee62eb46eda79a8aee1fa26ffb: removed 2 log segments from log reader
I20260812 06:17:43.253911 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000001 (ops 1-6)
I20260812 06:17:43.253965 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000002 (ops 7-10)
I20260812 06:17:43.259281 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: LogGCOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.006s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:17:43.259585 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:43.277709 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.018s	user 0.007s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6108,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:43.278398 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb): 16411393 bytes on disk
I20260812 06:17:43.279134 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":103,"lbm_reads_lt_1ms":4}
I20260812 06:17:43.279729 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:43.414626 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.135s	user 0.107s	sys 0.024s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":817,"lbm_read_time_us":9164,"lbm_reads_lt_1ms":460,"lbm_write_time_us":23236,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":59520,"thread_start_us":276,"threads_started":5,"update_count":2000}
I20260812 06:17:43.415285 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:43.460892 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.045s	user 0.029s	sys 0.011s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17975,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:43.461323 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:43.471570 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3861,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:43.472288 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:43.596908 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.124s	user 0.116s	sys 0.009s 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":302,"lbm_read_time_us":7575,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24954,"lbm_writes_lt_1ms":443,"mutex_wait_us":51,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8320,"update_count":2000}
I20260812 06:17:43.597482 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:43.637356 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.040s	user 0.019s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15267,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:43.637948 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:43.648654 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.010s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4033,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:43.649456 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:43.775815 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.126s	user 0.092s	sys 0.033s 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":848,"lbm_read_time_us":8854,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25653,"lbm_writes_lt_1ms":443,"mutex_wait_us":266,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2560,"update_count":2000}
I20260812 06:17:43.776434 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:43.827760 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.051s	user 0.026s	sys 0.012s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":14819,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:43.828341 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:43.838945 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4106,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:43.839385 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:43.990890 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.151s	user 0.099s	sys 0.052s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":150,"lbm_read_time_us":10723,"lbm_reads_lt_1ms":472,"lbm_write_time_us":23673,"lbm_writes_lt_1ms":443,"mutex_wait_us":24,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2432,"update_count":2000}
I20260812 06:17:43.991328 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:44.029860 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.038s	user 0.020s	sys 0.016s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16748,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:44.030318 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:44.132689 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.102s	user 0.074s	sys 0.028s Metrics: {"cfile_cache_miss":331,"cfile_cache_miss_bytes":16569747,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":264,"lbm_read_time_us":5891,"lbm_reads_lt_1ms":363,"lbm_write_time_us":19190,"lbm_writes_lt_1ms":343,"mutex_wait_us":37,"peak_mem_usage":38262756,"reinsert_count":0,"update_count":1500}
I20260812 06:17:44.133226 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:44.179193 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.046s	user 0.021s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15519,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:44.179808 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:44.190205 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.010s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4026,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:44.190791 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:44.321226 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.130s	user 0.097s	sys 0.032s 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":293,"lbm_read_time_us":9650,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26204,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1664,"update_count":2000}
I20260812 06:17:44.321913 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:44.378521 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.056s	user 0.021s	sys 0.033s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":23673,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:17:44.379143 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:44.390389 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4350,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:44.390877 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:44.551517 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.160s	user 0.120s	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":822,"lbm_read_time_us":12978,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26636,"lbm_writes_lt_1ms":443,"mutex_wait_us":68,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2432,"update_count":2000}
I20260812 06:17:44.552270 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:44.590477 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.038s	user 0.020s	sys 0.013s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15088,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:44.590978 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:44.601895 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4142,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:44.602581 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:44.633476 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.031s	user 0.029s	sys 0.000s Metrics: {"bytes_written":1275443,"cfile_init":1,"dirs.queue_time_us":66,"dirs.run_cpu_time_us":202,"dirs.run_wall_time_us":1479,"drs_written":1,"lbm_read_time_us":64,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1544,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:17:44.634274 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling LogGCOp(48157dee62eb46eda79a8aee1fa26ffb): free 129320441 bytes of WAL
I20260812 06:17:44.634495 16491 log_reader.cc:385] T 48157dee62eb46eda79a8aee1fa26ffb: removed 13 log segments from log reader
I20260812 06:17:44.634553 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000003 (ops 11-15)
I20260812 06:17:44.634605 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000004 (ops 16-20)
I20260812 06:17:44.634662 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000005 (ops 21-25)
I20260812 06:17:44.634702 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000006 (ops 26-30)
I20260812 06:17:44.634737 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000007 (ops 31-35)
I20260812 06:17:44.634773 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000008 (ops 36-40)
I20260812 06:17:44.634809 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000009 (ops 41-45)
I20260812 06:17:44.634845 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000010 (ops 46-50)
I20260812 06:17:44.634879 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000011 (ops 51-54)
I20260812 06:17:44.634932 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000012 (ops 55-59)
I20260812 06:17:44.634970 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000013 (ops 60-64)
I20260812 06:17:44.635006 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000014 (ops 65-68)
I20260812 06:17:44.635043 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000015 (ops 69-73)
I20260812 06:17:44.665705 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: LogGCOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.031s	user 0.005s	sys 0.023s Metrics: {}
I20260812 06:17:44.666206 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=6.157687
I20260812 06:17:44.694077 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.028s	user 0.018s	sys 0.009s Metrics: {"bytes_written":7917909,"delete_count":0,"lbm_write_time_us":8264,"lbm_writes_lt_1ms":196,"reinsert_count":0,"update_count":965}
I20260812 06:17:44.694624 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:44.883070 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.188s	user 0.120s	sys 0.067s Metrics: {"cfile_cache_miss":626,"cfile_cache_miss_bytes":28590052,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":661,"lbm_read_time_us":12657,"lbm_reads_lt_1ms":658,"lbm_write_time_us":31225,"lbm_writes_lt_1ms":636,"peak_mem_usage":74214843,"reinsert_count":0,"spinlock_wait_cycles":896,"thread_start_us":91,"threads_started":1,"update_count":2965}
I20260812 06:17:44.883785 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=15.087375
I20260812 06:17:44.944339 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.060s	user 0.031s	sys 0.028s Metrics: {"bytes_written":16697074,"delete_count":0,"lbm_write_time_us":22806,"lbm_writes_lt_1ms":410,"reinsert_count":0,"update_count":2035}
I20260812 06:17:44.944780 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb): 482 bytes on disk
I20260812 06:17:44.945191 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":50,"lbm_reads_lt_1ms":4}
I20260812 06:17:44.945640 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=3.181125
I20260812 06:17:44.969135 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.023s	user 0.008s	sys 0.005s Metrics: {"bytes_written":4512902,"delete_count":0,"lbm_write_time_us":5565,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:44.969625 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:44.978941 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.009s	user 0.007s	sys 0.001s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3531,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:44.979365 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:45.175922 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.196s	user 0.140s	sys 0.056s Metrics: {"cfile_cache_miss":640,"cfile_cache_miss_bytes":29164381,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":891,"lbm_read_time_us":13067,"lbm_reads_lt_1ms":680,"lbm_write_time_us":35853,"lbm_writes_lt_1ms":650,"mutex_wait_us":330,"peak_mem_usage":75829717,"reinsert_count":0,"spinlock_wait_cycles":30720,"update_count":3035}
I20260812 06:17:45.176668 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=14.095187
I20260812 06:17:45.230919 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.053s	user 0.039s	sys 0.011s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23332,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:45.231527 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:45.248836 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.017s	user 0.016s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6607,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:45.249439 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:45.423655 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.174s	user 0.122s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":301,"lbm_read_time_us":12224,"lbm_reads_lt_1ms":568,"lbm_write_time_us":29369,"lbm_writes_lt_1ms":543,"mutex_wait_us":57,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2048,"update_count":2500}
I20260812 06:17:45.424369 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=14.095187
I20260812 06:17:45.482113 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.056s	user 0.032s	sys 0.022s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21162,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:45.482646 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:45.493147 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4032,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:45.493742 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:45.667632 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.174s	user 0.098s	sys 0.072s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":765,"dirs.run_cpu_time_us":750,"dirs.run_wall_time_us":3126,"lbm_read_time_us":12350,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31342,"lbm_writes_lt_1ms":543,"mutex_wait_us":322,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7936,"update_count":2500}
I20260812 06:17:45.668401 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=11.118625
I20260812 06:17:45.703032 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.034s	user 0.027s	sys 0.005s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":14713,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:17:45.703588 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:45.732491 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.029s	user 0.007s	sys 0.014s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4793,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:45.733107 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:45.749452 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.016s	user 0.014s	sys 0.001s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6094,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:45.749993 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:45.941679 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.191s	user 0.128s	sys 0.054s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774800,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":645,"lbm_read_time_us":13626,"lbm_reads_lt_1ms":573,"lbm_write_time_us":32552,"lbm_writes_lt_1ms":543,"mutex_wait_us":3,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1664,"thread_start_us":84,"threads_started":1,"update_count":2500}
I20260812 06:17:45.942425 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=14.095187
I20260812 06:17:45.991389 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.049s	user 0.027s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21052,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:45.991851 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:46.003662 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.012s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4382,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:46.004119 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:46.040696 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.036s	user 0.023s	sys 0.005s Metrics: {"bytes_written":1152514,"cfile_init":1,"dirs.queue_time_us":66,"dirs.run_cpu_time_us":201,"dirs.run_wall_time_us":1273,"drs_written":1,"lbm_read_time_us":37,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1618,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:17:46.041491 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling LogGCOp(48157dee62eb46eda79a8aee1fa26ffb): free 112239373 bytes of WAL
I20260812 06:17:46.041803 16491 log_reader.cc:385] T 48157dee62eb46eda79a8aee1fa26ffb: removed 11 log segments from log reader
I20260812 06:17:46.041863 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000016 (ops 74-78)
I20260812 06:17:46.041903 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000017 (ops 79-83)
I20260812 06:17:46.041939 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000018 (ops 84-88)
I20260812 06:17:46.041968 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000019 (ops 89-92)
I20260812 06:17:46.041993 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000020 (ops 93-97)
I20260812 06:17:46.042023 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000021 (ops 98-102)
I20260812 06:17:46.042052 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000022 (ops 103-107)
I20260812 06:17:46.042083 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000023 (ops 108-112)
I20260812 06:17:46.042112 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000024 (ops 113-117)
I20260812 06:17:46.042140 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000025 (ops 118-122)
I20260812 06:17:46.042162 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000026 (ops 123-127)
I20260812 06:17:46.069938 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: LogGCOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.028s	user 0.000s	sys 0.026s Metrics: {}
I20260812 06:17:46.070412 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:46.084087 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.013s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4338,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:46.084740 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb): 447 bytes on disk
I20260812 06:17:46.085143 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4}
I20260812 06:17:46.085872 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:46.280844 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.195s	user 0.132s	sys 0.060s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877219,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":1084,"lbm_read_time_us":14367,"lbm_reads_lt_1ms":665,"lbm_write_time_us":34111,"lbm_writes_lt_1ms":643,"mutex_wait_us":56,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3456,"thread_start_us":76,"threads_started":1,"update_count":3000}
I20260812 06:17:46.281529 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=15.087375
I20260812 06:17:46.349237 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.068s	user 0.027s	sys 0.023s Metrics: {"bytes_written":16820144,"delete_count":0,"lbm_write_time_us":22121,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:17:46.349774 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=6.157687
I20260812 06:17:46.372207 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.022s	user 0.012s	sys 0.009s Metrics: {"bytes_written":7794836,"delete_count":0,"lbm_write_time_us":8606,"lbm_writes_lt_1ms":193,"reinsert_count":0,"update_count":950}
I20260812 06:17:46.372771 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:46.594462 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.221s	user 0.141s	sys 0.069s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877102,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":2511,"lbm_read_time_us":13601,"lbm_reads_lt_1ms":668,"lbm_write_time_us":37625,"lbm_writes_lt_1ms":643,"mutex_wait_us":881,"peak_mem_usage":75542472,"reinsert_count":0,"update_count":3000}
I20260812 06:17:46.595223 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=18.063937
I20260812 06:17:46.663705 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.068s	user 0.008s	sys 0.046s Metrics: {"bytes_written":20512314,"delete_count":0,"lbm_write_time_us":26226,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:46.664279 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:46.675668 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.011s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4072,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:46.676227 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:46.875005 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.199s	user 0.150s	sys 0.049s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877101,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":366,"lbm_read_time_us":14375,"lbm_reads_lt_1ms":672,"lbm_write_time_us":35655,"lbm_writes_lt_1ms":643,"mutex_wait_us":29,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":14976,"update_count":3000}
I20260812 06:17:46.875697 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=14.095187
I20260812 06:17:46.918684 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.043s	user 0.037s	sys 0.004s Metrics: {"bytes_written":16409898,"delete_count":0,"lbm_write_time_us":19175,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:46.919615 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:46.936949 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.017s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6079,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:46.937407 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:47.112087 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.175s	user 0.135s	sys 0.039s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774685,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":646,"lbm_read_time_us":12525,"lbm_reads_lt_1ms":564,"lbm_write_time_us":31601,"lbm_writes_lt_1ms":543,"mutex_wait_us":253,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":8576,"update_count":2500}
I20260812 06:17:47.112846 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=14.095187
I20260812 06:17:47.175503 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.062s	user 0.032s	sys 0.026s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24240,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:47.176069 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:47.186676 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.010s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4144,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:47.187116 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:47.374019 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.187s	user 0.107s	sys 0.074s 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":510,"lbm_read_time_us":13294,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30147,"lbm_writes_lt_1ms":543,"mutex_wait_us":21,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":28032,"update_count":2500}
I20260812 06:17:47.374783 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=14.095187
I20260812 06:17:47.436215 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.061s	user 0.036s	sys 0.019s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":21068,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:47.436769 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=2.188937
I20260812 06:17:47.447365 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4134,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:47.447911 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:47.475741 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushMRSOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.027s	user 0.024s	sys 0.000s Metrics: {"bytes_written":1152510,"cfile_init":1,"dirs.queue_time_us":65,"dirs.run_cpu_time_us":286,"dirs.run_wall_time_us":1449,"drs_written":1,"lbm_read_time_us":38,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1395,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:17:47.476574 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb): 447 bytes on disk
I20260812 06:17:47.477030 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: UndoDeltaBlockGCOp(48157dee62eb46eda79a8aee1fa26ffb) 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:17:47.477547 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:47.647837 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.170s	user 0.125s	sys 0.044s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774692,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":697,"lbm_read_time_us":11804,"lbm_reads_lt_1ms":564,"lbm_write_time_us":28882,"lbm_writes_lt_1ms":543,"mutex_wait_us":44,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10368,"update_count":2500}
I20260812 06:17:47.648670 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling LogGCOp(48157dee62eb46eda79a8aee1fa26ffb): free 120100577 bytes of WAL
I20260812 06:17:47.649022 16491 log_reader.cc:385] T 48157dee62eb46eda79a8aee1fa26ffb: removed 12 log segments from log reader
I20260812 06:17:47.649101 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000027 (ops 128-132)
I20260812 06:17:47.649148 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000028 (ops 133-137)
I20260812 06:17:47.649214 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000029 (ops 138-142)
I20260812 06:17:47.649263 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000030 (ops 143-146)
I20260812 06:17:47.649299 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000031 (ops 147-151)
I20260812 06:17:47.649365 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000032 (ops 152-156)
I20260812 06:17:47.649410 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000033 (ops 157-161)
I20260812 06:17:47.649466 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000034 (ops 162-166)
I20260812 06:17:47.649509 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000035 (ops 167-170)
I20260812 06:17:47.649581 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000036 (ops 171-175)
I20260812 06:17:47.649631 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000037 (ops 176-180)
I20260812 06:17:47.649688 16491 log.cc:1079] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/48157dee62eb46eda79a8aee1fa26ffb/wal-000000038 (ops 181-184)
I20260812 06:17:47.679279 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: LogGCOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.030s	user 0.000s	sys 0.026s Metrics: {}
I20260812 06:17:47.679771 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=15.087375
I20260812 06:17:47.747156 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.067s	user 0.027s	sys 0.029s Metrics: {"bytes_written":17312435,"delete_count":0,"lbm_write_time_us":22504,"lbm_writes_lt_1ms":425,"reinsert_count":0,"update_count":2110}
I20260812 06:17:47.747658 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=6.157687
I20260812 06:17:47.766582 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.019s	user 0.009s	sys 0.008s Metrics: {"bytes_written":7302552,"delete_count":0,"lbm_write_time_us":7292,"lbm_writes_lt_1ms":181,"reinsert_count":0,"update_count":890}
I20260812 06:17:47.767194 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=1.000000
I20260812 06:17:47.923710 16374 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.988s	user 1.823s	sys 0.165s
I20260812 06:17:47.966823 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: MajorDeltaCompactionOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.199s	user 0.126s	sys 0.071s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877109,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"lbm_read_time_us":14229,"lbm_reads_lt_1ms":668,"lbm_write_time_us":37038,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":3000}
I20260812 06:17:47.967357 16560 maintenance_manager.cc:419] P 85ec0eea9cda401681deb327b56e9d3f: Scheduling FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb): perf score=10.126437
I20260812 06:17:47.993150 16374 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.069s	user 0.003s	sys 0.000s
I20260812 06:17:47.993839 16374 tablet_server.cc:179] TabletServer@127.15.253.129:0 shutting down...
I20260812 06:17:48.002058 16491 maintenance_manager.cc:643] P 85ec0eea9cda401681deb327b56e9d3f: FlushDeltaMemStoresOp(48157dee62eb46eda79a8aee1fa26ffb) complete. Timing: real 0.035s	user 0.019s	sys 0.013s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":14648,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:48.002578 16374 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:48.002990 16374 tablet_replica.cc:333] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f: stopping tablet replica
I20260812 06:17:48.003204 16374 raft_consensus.cc:2243] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:48.011675 16374 raft_consensus.cc:2272] T 48157dee62eb46eda79a8aee1fa26ffb P 85ec0eea9cda401681deb327b56e9d3f [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:48.016927 16374 tablet_server.cc:196] TabletServer@127.15.253.129:0 shutdown complete.
I20260812 06:17:48.029222 16374 master.cc:562] Master@127.15.253.190:32939 shutting down...
I20260812 06:17:48.033047 16374 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:48.033242 16374 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:48.033346 16374 tablet_replica.cc:333] T 00000000000000000000000000000000 P 6ca8bc1345134feca84eadaf6fb8375f: stopping tablet replica
I20260812 06:17:48.045634 16374 master.cc:584] Master@127.15.253.190:32939 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5469 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:17:48.139058 16374 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.15.253.190:34335
I20260812 06:17:48.139472 16374 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:48.141604 16595 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:17:48.141680 16600 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:17:48.141782 16374 server_base.cc:1061] running on GCE node
W20260812 06:17:48.141610 16597 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:17:48.142035 16374 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:48.142077 16374 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:17:48.142092 16374 hybrid_clock.cc:648] HybridClock initialized: now 1786515468142092 us; error 0 us; skew 500 ppm
I20260812 06:17:48.142917 16374 webserver.cc:533] Webserver started at http://127.15.253.190:37247/ using document root <none> and password file <none>
I20260812 06:17:48.143081 16374 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:48.143136 16374 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:48.143234 16374 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:48.143633 16374 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/master-0-root/instance:
uuid: "c17a90e194414ebd93f5eb84ff282500"
format_stamp: "Formatted at 2026-08-12 06:17:48 on dist-test-slave-cbbz"
I20260812 06:17:48.145211 16374 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:17:48.146076 16606 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:17:48.146342 16374 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:48.146409 16374 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/master-0-root
uuid: "c17a90e194414ebd93f5eb84ff282500"
format_stamp: "Formatted at 2026-08-12 06:17:48 on dist-test-slave-cbbz"
I20260812 06:17:48.146459 16374 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-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:17:48.152443 16374 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:48.152710 16374 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:48.156601 16374 rpc_server.cc:307] RPC server started. Bound to: 127.15.253.190:34335
I20260812 06:17:48.158113 16661 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.15.253.190:34335 every 8 connection(s)
I20260812 06:17:48.159426 16662 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:17:48.165606 16662 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500: Bootstrap starting.
I20260812 06:17:48.166374 16662 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:48.167351 16662 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500: No bootstrap required, opened a new log
I20260812 06:17:48.167745 16662 raft_consensus.cc:359] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c17a90e194414ebd93f5eb84ff282500" member_type: VOTER }
I20260812 06:17:48.167851 16662 raft_consensus.cc:385] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:48.167902 16662 raft_consensus.cc:740] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c17a90e194414ebd93f5eb84ff282500, State: Initialized, Role: FOLLOWER
I20260812 06:17:48.168089 16662 consensus_queue.cc:260] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [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: "c17a90e194414ebd93f5eb84ff282500" member_type: VOTER }
I20260812 06:17:48.168221 16662 raft_consensus.cc:399] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:48.168272 16662 raft_consensus.cc:493] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:48.168329 16662 raft_consensus.cc:3060] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:48.168982 16662 raft_consensus.cc:515] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c17a90e194414ebd93f5eb84ff282500" member_type: VOTER }
I20260812 06:17:48.169139 16662 leader_election.cc:304] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [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: c17a90e194414ebd93f5eb84ff282500; no voters: 
I20260812 06:17:48.169329 16662 leader_election.cc:290] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:48.169456 16665 raft_consensus.cc:2804] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:48.169688 16665 raft_consensus.cc:697] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 1 LEADER]: Becoming Leader. State: Replica: c17a90e194414ebd93f5eb84ff282500, State: Running, Role: LEADER
I20260812 06:17:48.169788 16662 sys_catalog.cc:565] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:48.169849 16665 consensus_queue.cc:237] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [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: "c17a90e194414ebd93f5eb84ff282500" member_type: VOTER }
I20260812 06:17:48.170311 16667 sys_catalog.cc:455] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [sys.catalog]: SysCatalogTable state changed. Reason: New leader c17a90e194414ebd93f5eb84ff282500. Latest consensus state: current_term: 1 leader_uuid: "c17a90e194414ebd93f5eb84ff282500" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c17a90e194414ebd93f5eb84ff282500" member_type: VOTER } }
I20260812 06:17:48.170296 16666 sys_catalog.cc:455] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "c17a90e194414ebd93f5eb84ff282500" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c17a90e194414ebd93f5eb84ff282500" member_type: VOTER } }
I20260812 06:17:48.170410 16667 sys_catalog.cc:458] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:48.170423 16666 sys_catalog.cc:458] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:48.171089 16671 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:48.171742 16671 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:48.171918 16374 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:48.173619 16671 catalog_manager.cc:1383] Generated new cluster ID: b43ed953446a4f8bb078a330a6ca49a9
I20260812 06:17:48.173666 16671 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:48.183259 16671 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:48.183723 16671 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:48.195459 16671 catalog_manager.cc:6092] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500: Generated new TSK 0
I20260812 06:17:48.195590 16671 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:48.204321 16374 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:48.206267 16685 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:17:48.206305 16687 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:17:48.206327 16689 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:17:48.206563 16374 server_base.cc:1061] running on GCE node
I20260812 06:17:48.206719 16374 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:48.206756 16374 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:17:48.206771 16374 hybrid_clock.cc:648] HybridClock initialized: now 1786515468206772 us; error 0 us; skew 500 ppm
I20260812 06:17:48.207620 16374 webserver.cc:533] Webserver started at http://127.15.253.129:36175/ using document root <none> and password file <none>
I20260812 06:17:48.207793 16374 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:48.207839 16374 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:48.207949 16374 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:48.208410 16374 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/instance:
uuid: "266a1dbb441746158f936e844d833b32"
format_stamp: "Formatted at 2026-08-12 06:17:48 on dist-test-slave-cbbz"
I20260812 06:17:48.209935 16374 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:48.210843 16696 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:17:48.211086 16374 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:48.211175 16374 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root
uuid: "266a1dbb441746158f936e844d833b32"
format_stamp: "Formatted at 2026-08-12 06:17:48 on dist-test-slave-cbbz"
I20260812 06:17:48.211261 16374 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-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:17:48.243892 16374 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:48.244419 16374 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:48.244767 16374 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:48.245267 16374 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:48.245326 16374 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:48.245404 16374 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:48.245451 16374 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:48.249950 16374 rpc_server.cc:307] RPC server started. Bound to: 127.15.253.129:35125
I20260812 06:17:48.250470 16765 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.15.253.129:35125 every 8 connection(s)
I20260812 06:17:48.260574 16766 heartbeater.cc:344] Connected to a master server at 127.15.253.190:34335
I20260812 06:17:48.260681 16766 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:48.260949 16766 heartbeater.cc:507] Master 127.15.253.190:34335 requested a full tablet report, sending...
I20260812 06:17:48.261652 16625 ts_manager.cc:194] Registered new tserver with Master: 266a1dbb441746158f936e844d833b32 (127.15.253.129:35125)
I20260812 06:17:48.261727 16374 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.011088207s
I20260812 06:17:48.262432 16625 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:38594
I20260812 06:17:48.268918 16625 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:38600:
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:17:48.277926 16726 tablet_service.cc:1511] Processing CreateTablet for tablet c2ecfb0180344a9386d56fd36e71e774 (DEFAULT_TABLE table=heavy-update-compaction-test [id=28b4a6a34a2d451d8443e96ab094490d]), partition=
I20260812 06:17:48.278211 16726 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c2ecfb0180344a9386d56fd36e71e774. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:48.280284 16780 tablet_bootstrap.cc:492] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Bootstrap starting.
I20260812 06:17:48.281141 16780 tablet_bootstrap.cc:654] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:48.282348 16780 tablet_bootstrap.cc:492] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: No bootstrap required, opened a new log
I20260812 06:17:48.282456 16780 ts_tablet_manager.cc:1403] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.001s
I20260812 06:17:48.282891 16780 raft_consensus.cc:359] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "266a1dbb441746158f936e844d833b32" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 35125 } }
I20260812 06:17:48.283004 16780 raft_consensus.cc:385] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:48.283051 16780 raft_consensus.cc:740] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 266a1dbb441746158f936e844d833b32, State: Initialized, Role: FOLLOWER
I20260812 06:17:48.283205 16780 consensus_queue.cc:260] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [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: "266a1dbb441746158f936e844d833b32" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 35125 } }
I20260812 06:17:48.283313 16780 raft_consensus.cc:399] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:48.283365 16780 raft_consensus.cc:493] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:48.283413 16780 raft_consensus.cc:3060] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:48.284185 16780 raft_consensus.cc:515] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "266a1dbb441746158f936e844d833b32" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 35125 } }
I20260812 06:17:48.284318 16780 leader_election.cc:304] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [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: 266a1dbb441746158f936e844d833b32; no voters: 
I20260812 06:17:48.284469 16780 leader_election.cc:290] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:48.284603 16782 raft_consensus.cc:2804] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:48.284861 16766 heartbeater.cc:499] Master 127.15.253.190:34335 was elected leader, sending a full tablet report...
I20260812 06:17:48.284843 16782 raft_consensus.cc:697] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 1 LEADER]: Becoming Leader. State: Replica: 266a1dbb441746158f936e844d833b32, State: Running, Role: LEADER
I20260812 06:17:48.284824 16780 ts_tablet_manager.cc:1434] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Time spent starting tablet: real 0.002s	user 0.002s	sys 0.001s
I20260812 06:17:48.285025 16782 consensus_queue.cc:237] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [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: "266a1dbb441746158f936e844d833b32" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 35125 } }
I20260812 06:17:48.286290 16625 catalog_manager.cc:5719] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 reported cstate change: term changed from 0 to 1, leader changed from <none> to 266a1dbb441746158f936e844d833b32 (127.15.253.129). New cstate: current_term: 1 leader_uuid: "266a1dbb441746158f936e844d833b32" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "266a1dbb441746158f936e844d833b32" member_type: VOTER last_known_addr { host: "127.15.253.129" port: 35125 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:48.345393 16374 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.052s	user 0.014s	sys 0.008s
I20260812 06:17:48.501102 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774): perf score=19.054940
I20260812 06:17:48.660318 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.159s	user 0.107s	sys 0.052s Metrics: {"bytes_written":12307491,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":57,"dirs.run_cpu_time_us":203,"dirs.run_wall_time_us":901,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":40426,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"update_count":1500}
I20260812 06:17:48.660965 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling LogGCOp(c2ecfb0180344a9386d56fd36e71e774): free 20290830 bytes of WAL
I20260812 06:17:48.661219 16701 log_reader.cc:385] T c2ecfb0180344a9386d56fd36e71e774: removed 2 log segments from log reader
I20260812 06:17:48.661263 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000001 (ops 1-6)
I20260812 06:17:48.661294 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000002 (ops 7-10)
I20260812 06:17:48.666335 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: LogGCOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.005s	user 0.000s	sys 0.003s Metrics: {}
I20260812 06:17:48.666787 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:48.682142 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.015s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6018,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:48.682598 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:48.842237 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.159s	user 0.115s	sys 0.040s 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":647,"lbm_read_time_us":10798,"lbm_reads_lt_1ms":468,"lbm_write_time_us":27949,"lbm_writes_lt_1ms":443,"mutex_wait_us":32,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2688,"thread_start_us":412,"threads_started":5,"update_count":2000}
I20260812 06:17:48.842787 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774): 16411400 bytes on disk
I20260812 06:17:48.843226 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":49,"lbm_reads_lt_1ms":4}
I20260812 06:17:48.843719 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:48.901425 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.057s	user 0.037s	sys 0.011s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22472,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:48.901976 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:48.917127 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.015s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5557,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:48.917654 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:49.084630 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.167s	user 0.107s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":227,"lbm_read_time_us":11696,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27254,"lbm_writes_lt_1ms":543,"mutex_wait_us":22,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":14336,"update_count":2500}
I20260812 06:17:49.085312 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:49.145819 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.060s	user 0.036s	sys 0.019s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":21364,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:49.146391 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:49.157310 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.011s	user 0.009s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4256,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:49.157727 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:49.333123 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.175s	user 0.118s	sys 0.054s 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":594,"lbm_read_time_us":12274,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29290,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2500}
I20260812 06:17:49.333647 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:49.399811 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.066s	user 0.030s	sys 0.023s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":20930,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:49.400453 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:49.411262 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4208,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:49.411702 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:49.603006 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.191s	user 0.122s	sys 0.064s 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":924,"lbm_read_time_us":12923,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31547,"lbm_writes_lt_1ms":543,"mutex_wait_us":69,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6784,"update_count":2500}
I20260812 06:17:49.603700 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:49.659468 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.056s	user 0.024s	sys 0.024s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":23068,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:49.659929 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:49.681151 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.021s	user 0.009s	sys 0.011s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4207,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:49.681829 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:49.863291 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.181s	user 0.102s	sys 0.077s 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":301,"lbm_read_time_us":14684,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27635,"lbm_writes_lt_1ms":543,"mutex_wait_us":61,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13568,"update_count":2500}
I20260812 06:17:49.863879 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:49.918593 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.055s	user 0.017s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21378,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:49.919135 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:49.930418 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4053,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:49.930917 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:49.967630 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.037s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":55,"dirs.run_cpu_time_us":211,"dirs.run_wall_time_us":1284,"drs_written":1,"lbm_read_time_us":64,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2397,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:17:49.968327 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling LogGCOp(c2ecfb0180344a9386d56fd36e71e774): free 120553374 bytes of WAL
I20260812 06:17:49.968540 16701 log_reader.cc:385] T c2ecfb0180344a9386d56fd36e71e774: removed 12 log segments from log reader
I20260812 06:17:49.968600 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000003 (ops 11-15)
I20260812 06:17:49.968652 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000004 (ops 16-20)
I20260812 06:17:49.968708 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000005 (ops 21-25)
I20260812 06:17:49.968747 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000006 (ops 26-30)
I20260812 06:17:49.968784 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000007 (ops 31-35)
I20260812 06:17:49.968835 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000008 (ops 36-40)
I20260812 06:17:49.968870 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000009 (ops 41-44)
I20260812 06:17:49.968905 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000010 (ops 45-49)
I20260812 06:17:49.968941 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000011 (ops 50-54)
I20260812 06:17:49.968994 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000012 (ops 55-59)
I20260812 06:17:49.969033 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000013 (ops 60-64)
I20260812 06:17:49.969067 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000014 (ops 65-68)
I20260812 06:17:49.998076 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: LogGCOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.030s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:17:49.998502 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774): 462 bytes on disk
I20260812 06:17:49.998908 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774) 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:17:49.999393 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=3.181125
I20260812 06:17:50.015865 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.016s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4512902,"delete_count":0,"lbm_write_time_us":4179,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:50.016407 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:50.025915 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3576,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:50.026631 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:50.270184 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.243s	user 0.145s	sys 0.088s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979739,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1249,"lbm_read_time_us":13813,"lbm_reads_lt_1ms":774,"lbm_write_time_us":41737,"lbm_writes_lt_1ms":743,"mutex_wait_us":329,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":5632,"thread_start_us":82,"threads_started":1,"update_count":3500}
I20260812 06:17:50.271004 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=18.063937
I20260812 06:17:50.339283 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.068s	user 0.025s	sys 0.032s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":28552,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:17:50.339723 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:50.351058 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3999,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:50.351497 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:50.567514 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.216s	user 0.140s	sys 0.076s 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":389,"lbm_read_time_us":14071,"lbm_reads_lt_1ms":672,"lbm_write_time_us":36795,"lbm_writes_lt_1ms":643,"mutex_wait_us":22,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":16128,"update_count":3000}
I20260812 06:17:50.568248 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:50.621928 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.053s	user 0.015s	sys 0.033s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":21033,"lbm_writes_lt_1ms":403,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":2000}
I20260812 06:17:50.622450 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=3.181125
I20260812 06:17:50.640904 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.018s	user 0.005s	sys 0.012s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":7438,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:50.641557 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:50.653234 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4630,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:50.653846 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:50.892288 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.238s	user 0.146s	sys 0.090s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877211,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":438,"lbm_read_time_us":14636,"lbm_reads_lt_1ms":673,"lbm_write_time_us":43312,"lbm_writes_lt_1ms":643,"mutex_wait_us":52,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":6528,"update_count":3000}
I20260812 06:17:50.892910 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=16.079562
I20260812 06:17:50.946432 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.053s	user 0.038s	sys 0.013s Metrics: {"bytes_written":17558580,"delete_count":0,"lbm_write_time_us":23203,"lbm_writes_lt_1ms":431,"reinsert_count":0,"update_count":2140}
I20260812 06:17:50.946995 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:50.968720 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.022s	user 0.010s	sys 0.004s Metrics: {"bytes_written":3364209,"delete_count":0,"lbm_write_time_us":5436,"lbm_writes_lt_1ms":85,"reinsert_count":0,"update_count":410}
I20260812 06:17:50.969194 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:50.978840 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3707,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:50.979233 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:51.196471 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.217s	user 0.126s	sys 0.091s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877194,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":241,"lbm_read_time_us":14088,"lbm_reads_lt_1ms":673,"lbm_write_time_us":35749,"lbm_writes_lt_1ms":643,"mutex_wait_us":43,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":33024,"update_count":3000}
I20260812 06:17:51.197121 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:51.254467 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.057s	user 0.035s	sys 0.017s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24065,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:51.254978 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:51.265208 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3982,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:51.265654 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:51.442798 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.177s	user 0.123s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":301,"lbm_read_time_us":13396,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29492,"lbm_writes_lt_1ms":543,"mutex_wait_us":60,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":20096,"update_count":2500}
I20260812 06:17:51.443537 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:51.504382 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.061s	user 0.020s	sys 0.031s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21867,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:51.505110 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:51.522159 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.017s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":7105,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:51.522835 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:51.568339 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.045s	user 0.042s	sys 0.003s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":54,"dirs.run_cpu_time_us":160,"dirs.run_wall_time_us":1192,"drs_written":1,"lbm_read_time_us":68,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2168,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:17:51.569151 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling LogGCOp(c2ecfb0180344a9386d56fd36e71e774): free 121006389 bytes of WAL
I20260812 06:17:51.569416 16701 log_reader.cc:385] T c2ecfb0180344a9386d56fd36e71e774: removed 12 log segments from log reader
I20260812 06:17:51.569490 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000015 (ops 69-73)
I20260812 06:17:51.569554 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000016 (ops 74-78)
I20260812 06:17:51.569598 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000017 (ops 79-83)
I20260812 06:17:51.569648 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000018 (ops 84-88)
I20260812 06:17:51.569689 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000019 (ops 89-93)
I20260812 06:17:51.569730 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000020 (ops 94-98)
I20260812 06:17:51.569772 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000021 (ops 99-103)
I20260812 06:17:51.569813 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000022 (ops 104-108)
I20260812 06:17:51.569854 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000023 (ops 109-112)
I20260812 06:17:51.569895 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000024 (ops 113-117)
I20260812 06:17:51.569936 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000025 (ops 118-122)
I20260812 06:17:51.569977 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000026 (ops 123-127)
I20260812 06:17:51.601382 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: LogGCOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.032s	user 0.004s	sys 0.027s Metrics: {}
I20260812 06:17:51.601836 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774): 472 bytes on disk
I20260812 06:17:51.602299 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":54,"lbm_reads_lt_1ms":4}
I20260812 06:17:51.602871 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=5.165500
I20260812 06:17:51.627436 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.024s	user 0.010s	sys 0.011s Metrics: {"bytes_written":7179474,"delete_count":0,"lbm_write_time_us":10148,"lbm_writes_lt_1ms":178,"reinsert_count":0,"update_count":875}
I20260812 06:17:51.628072 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:51.859163 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.231s	user 0.174s	sys 0.045s Metrics: {"cfile_cache_miss":708,"cfile_cache_miss_bytes":31954027,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":666,"lbm_read_time_us":17104,"lbm_reads_lt_1ms":744,"lbm_write_time_us":40562,"lbm_writes_lt_1ms":718,"mutex_wait_us":66,"peak_mem_usage":84862401,"reinsert_count":0,"spinlock_wait_cycles":4608,"thread_start_us":83,"threads_started":1,"update_count":3375}
I20260812 06:17:51.859939 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=19.056125
I20260812 06:17:51.926738 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.066s	user 0.051s	sys 0.012s Metrics: {"bytes_written":21537926,"delete_count":0,"lbm_write_time_us":28683,"lbm_writes_lt_1ms":528,"reinsert_count":0,"update_count":2625}
I20260812 06:17:51.927208 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:51.951143 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.024s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5107,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:51.951632 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:51.961680 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3937,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:51.962158 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:52.160449 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.198s	user 0.158s	sys 0.039s Metrics: {"cfile_cache_miss":758,"cfile_cache_miss_bytes":34005244,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":171,"lbm_read_time_us":15981,"lbm_reads_lt_1ms":798,"lbm_write_time_us":39629,"lbm_writes_lt_1ms":768,"mutex_wait_us":29,"peak_mem_usage":91075687,"reinsert_count":0,"spinlock_wait_cycles":3328,"update_count":3625}
I20260812 06:17:52.161072 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=15.087375
I20260812 06:17:52.209954 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.049s	user 0.031s	sys 0.016s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":21517,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:17:52.210489 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:52.235451 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.025s	user 0.003s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4660,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:52.235972 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:52.246507 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4117,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:52.247083 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:52.411232 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.164s	user 0.134s	sys 0.028s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877206,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":2380,"lbm_read_time_us":11920,"lbm_reads_lt_1ms":673,"lbm_write_time_us":32405,"lbm_writes_lt_1ms":643,"mutex_wait_us":1522,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":3000}
I20260812 06:17:52.412017 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:52.465416 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.053s	user 0.027s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22909,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:17:52.466004 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:52.489356 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.023s	user 0.013s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5571,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:52.489799 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:52.499959 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3953,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:52.500432 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:52.673627 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.173s	user 0.134s	sys 0.039s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877218,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":273,"lbm_read_time_us":13415,"lbm_reads_lt_1ms":673,"lbm_write_time_us":35038,"lbm_writes_lt_1ms":643,"mutex_wait_us":85,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":8320,"update_count":3000}
I20260812 06:17:52.674201 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:52.721705 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.047s	user 0.029s	sys 0.012s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":19044,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:52.722291 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:52.737244 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.015s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5440,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:52.737725 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:52.895879 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.158s	user 0.124s	sys 0.031s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1329,"lbm_read_time_us":9436,"lbm_reads_lt_1ms":564,"lbm_write_time_us":29993,"lbm_writes_lt_1ms":543,"mutex_wait_us":450,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":12288,"update_count":2500}
I20260812 06:17:52.896489 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:52.946404 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.050s	user 0.032s	sys 0.011s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":19763,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:52.947041 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:52.993495 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushMRSOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.046s	user 0.032s	sys 0.004s Metrics: {"bytes_written":1275447,"cfile_init":1,"dirs.queue_time_us":70,"dirs.run_cpu_time_us":264,"dirs.run_wall_time_us":1744,"drs_written":1,"lbm_read_time_us":38,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1582,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:17:52.994334 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774): 482 bytes on disk
I20260812 06:17:52.994859 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: UndoDeltaBlockGCOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4}
I20260812 06:17:52.995503 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=3.181125
I20260812 06:17:53.009286 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.014s	user 0.009s	sys 0.001s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":4247,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:53.009748 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling LogGCOp(c2ecfb0180344a9386d56fd36e71e774): free 133024699 bytes of WAL
I20260812 06:17:53.009984 16701 log_reader.cc:385] T c2ecfb0180344a9386d56fd36e71e774: removed 13 log segments from log reader
I20260812 06:17:53.010030 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000027 (ops 128-132)
I20260812 06:17:53.010058 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000028 (ops 133-137)
I20260812 06:17:53.010121 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000029 (ops 138-142)
I20260812 06:17:53.010165 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000030 (ops 143-147)
I20260812 06:17:53.010205 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000031 (ops 148-152)
I20260812 06:17:53.010255 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000032 (ops 153-157)
I20260812 06:17:53.010295 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000033 (ops 158-162)
I20260812 06:17:53.010344 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000034 (ops 163-166)
I20260812 06:17:53.010383 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000035 (ops 167-171)
I20260812 06:17:53.010421 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000036 (ops 172-176)
I20260812 06:17:53.010459 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000037 (ops 177-181)
I20260812 06:17:53.010497 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000038 (ops 182-186)
I20260812 06:17:53.010536 16701 log.cc:1079] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: Deleting log segment in path: /tmp/dist-test-taskU33rL9/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515462659193-16374-0/minicluster-data/ts-0-root/wals/c2ecfb0180344a9386d56fd36e71e774/wal-000000039 (ops 187-191)
I20260812 06:17:53.040371 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: LogGCOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.030s	user 0.000s	sys 0.030s Metrics: {}
I20260812 06:17:53.041016 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:53.055034 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.014s	user 0.007s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4124,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:53.055455 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=2.188937
I20260812 06:17:53.065009 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.009s	user 0.004s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3608,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:53.065424 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774): perf score=1.000000
I20260812 06:17:53.224004 16374 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.879s	user 1.773s	sys 0.169s
I20260812 06:17:53.278612 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: MajorDeltaCompactionOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.213s	user 0.137s	sys 0.074s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979742,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"lbm_read_time_us":15916,"lbm_reads_lt_1ms":770,"lbm_write_time_us":38112,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":2176,"update_count":3500}
I20260812 06:17:53.279089 16767 maintenance_manager.cc:419] P 266a1dbb441746158f936e844d833b32: Scheduling FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774): perf score=14.095187
I20260812 06:17:53.307035 16374 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.083s	user 0.000s	sys 0.000s
I20260812 06:17:53.307591 16374 tablet_server.cc:179] TabletServer@127.15.253.129:0 shutting down...
I20260812 06:17:53.324783 16701 maintenance_manager.cc:643] P 266a1dbb441746158f936e844d833b32: FlushDeltaMemStoresOp(c2ecfb0180344a9386d56fd36e71e774) complete. Timing: real 0.046s	user 0.033s	sys 0.011s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":20304,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:53.325767 16374 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:53.325975 16374 tablet_replica.cc:333] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32: stopping tablet replica
I20260812 06:17:53.326148 16374 raft_consensus.cc:2243] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:53.326335 16374 raft_consensus.cc:2272] T c2ecfb0180344a9386d56fd36e71e774 P 266a1dbb441746158f936e844d833b32 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:53.340047 16374 tablet_server.cc:196] TabletServer@127.15.253.129:0 shutdown complete.
I20260812 06:17:53.343840 16374 master.cc:562] Master@127.15.253.190:34335 shutting down...
I20260812 06:17:53.347191 16374 raft_consensus.cc:2243] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:53.347371 16374 raft_consensus.cc:2272] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:53.347462 16374 tablet_replica.cc:333] T 00000000000000000000000000000000 P c17a90e194414ebd93f5eb84ff282500: stopping tablet replica
I20260812 06:17:53.359707 16374 master.cc:584] Master@127.15.253.190:34335 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5311 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10782 ms total)

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