[==========] 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:13.521696 10799 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.10.139.254:34257
I20260812 06:17:13.522758 10799 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:13.523362 10799 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:13.529865 10811 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:13.529955 10799 server_base.cc:1061] running on GCE node
W20260812 06:17:13.529870 10809 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:13.530128 10808 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:13.530658 10799 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:13.530742 10799 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:13.530771 10799 hybrid_clock.cc:648] HybridClock initialized: now 1786515433530770 us; error 0 us; skew 500 ppm
I20260812 06:17:13.532445 10799 webserver.cc:533] Webserver started at http://127.10.139.254:40737/ using document root <none> and password file <none>
I20260812 06:17:13.532974 10799 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:13.533030 10799 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:13.533219 10799 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:13.534716 10799 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/master-0-root/instance:
uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87"
format_stamp: "Formatted at 2026-08-12 06:17:13 on dist-test-slave-04bb"
I20260812 06:17:13.538157 10799 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
I20260812 06:17:13.540235 10820 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:13.541286 10799 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:13.541412 10799 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/master-0-root
uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87"
format_stamp: "Formatted at 2026-08-12 06:17:13 on dist-test-slave-04bb"
I20260812 06:17:13.541512 10799 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-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:13.559894 10799 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:13.560495 10799 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:13.560715 10799 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:13.568516 10913 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.10.139.254:34257 every 8 connection(s)
I20260812 06:17:13.568513 10799 rpc_server.cc:307] RPC server started. Bound to: 127.10.139.254:34257
I20260812 06:17:13.570830 10915 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:13.576087 10915 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87: Bootstrap starting.
I20260812 06:17:13.578460 10915 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:13.579340 10915 log.cc:826] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87: Log is configured to *not* fsync() on all Append() calls
I20260812 06:17:13.581240 10915 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87: No bootstrap required, opened a new log
I20260812 06:17:13.583865 10915 raft_consensus.cc:359] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87" member_type: VOTER }
I20260812 06:17:13.584048 10915 raft_consensus.cc:385] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:13.584146 10915 raft_consensus.cc:740] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d4a9b2d0965a4f4ba77bb61f5977cb87, State: Initialized, Role: FOLLOWER
I20260812 06:17:13.584784 10915 consensus_queue.cc:260] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [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: "d4a9b2d0965a4f4ba77bb61f5977cb87" member_type: VOTER }
I20260812 06:17:13.584959 10915 raft_consensus.cc:399] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:13.585052 10915 raft_consensus.cc:493] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:13.585184 10915 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:13.585911 10915 raft_consensus.cc:515] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87" member_type: VOTER }
I20260812 06:17:13.586340 10915 leader_election.cc:304] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [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: d4a9b2d0965a4f4ba77bb61f5977cb87; no voters: 
I20260812 06:17:13.586645 10915 leader_election.cc:290] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:13.586789 10919 raft_consensus.cc:2804] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:13.587025 10919 raft_consensus.cc:697] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 1 LEADER]: Becoming Leader. State: Replica: d4a9b2d0965a4f4ba77bb61f5977cb87, State: Running, Role: LEADER
I20260812 06:17:13.587404 10919 consensus_queue.cc:237] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [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: "d4a9b2d0965a4f4ba77bb61f5977cb87" member_type: VOTER }
I20260812 06:17:13.587580 10915 sys_catalog.cc:565] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:13.589287 10921 sys_catalog.cc:455] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87" member_type: VOTER } }
I20260812 06:17:13.589324 10922 sys_catalog.cc:455] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [sys.catalog]: SysCatalogTable state changed. Reason: New leader d4a9b2d0965a4f4ba77bb61f5977cb87. Latest consensus state: current_term: 1 leader_uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4a9b2d0965a4f4ba77bb61f5977cb87" member_type: VOTER } }
I20260812 06:17:13.589412 10921 sys_catalog.cc:458] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:13.589412 10922 sys_catalog.cc:458] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:13.589751 10933 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:13.589936 10799 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:13.591892 10933 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:13.596031 10933 catalog_manager.cc:1383] Generated new cluster ID: 0365c56b7c134b3ab0d7ccd596e9bab0
I20260812 06:17:13.596094 10933 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:13.608827 10933 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:13.609897 10933 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:13.619657 10933 catalog_manager.cc:6092] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87: Generated new TSK 0
I20260812 06:17:13.620311 10933 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:13.622897 10799 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:13.625681 10948 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:13.625874 10955 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:13.625907 10799 server_base.cc:1061] running on GCE node
W20260812 06:17:13.625722 10947 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:13.626159 10799 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:13.626219 10799 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:13.626245 10799 hybrid_clock.cc:648] HybridClock initialized: now 1786515433626244 us; error 0 us; skew 500 ppm
I20260812 06:17:13.627203 10799 webserver.cc:533] Webserver started at http://127.10.139.193:44119/ using document root <none> and password file <none>
I20260812 06:17:13.627377 10799 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:13.627444 10799 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:13.627523 10799 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:13.627911 10799 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/instance:
uuid: "ee95059347334025a35d5ebf1d94f462"
format_stamp: "Formatted at 2026-08-12 06:17:13 on dist-test-slave-04bb"
I20260812 06:17:13.629482 10799 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:17:13.630437 10963 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:13.630686 10799 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:13.630752 10799 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root
uuid: "ee95059347334025a35d5ebf1d94f462"
format_stamp: "Formatted at 2026-08-12 06:17:13 on dist-test-slave-04bb"
I20260812 06:17:13.630836 10799 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-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:13.635725 10799 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:13.636088 10799 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:13.636482 10799 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:13.637305 10799 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:13.637356 10799 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:13.637419 10799 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:13.637459 10799 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:13.644104 10799 rpc_server.cc:307] RPC server started. Bound to: 127.10.139.193:44809
I20260812 06:17:13.644140 11067 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.10.139.193:44809 every 8 connection(s)
I20260812 06:17:13.653446 11068 heartbeater.cc:344] Connected to a master server at 127.10.139.254:34257
I20260812 06:17:13.653684 11068 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:13.654119 11068 heartbeater.cc:507] Master 127.10.139.254:34257 requested a full tablet report, sending...
I20260812 06:17:13.655449 10858 ts_manager.cc:194] Registered new tserver with Master: ee95059347334025a35d5ebf1d94f462 (127.10.139.193:44809)
I20260812 06:17:13.655946 10799 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.011220918s
I20260812 06:17:13.656718 10858 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:49564
I20260812 06:17:13.665040 10858 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:49578:
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:13.678577 11007 tablet_service.cc:1511] Processing CreateTablet for tablet bbaf26275ccf4934a341f920f1a55e0f (DEFAULT_TABLE table=heavy-update-compaction-test [id=bf5eeb8bd86a43e9b00d8dad6c2ab113]), partition=
I20260812 06:17:13.679008 11007 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet bbaf26275ccf4934a341f920f1a55e0f. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:13.681116 11087 tablet_bootstrap.cc:492] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Bootstrap starting.
I20260812 06:17:13.682155 11087 tablet_bootstrap.cc:654] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:13.683342 11087 tablet_bootstrap.cc:492] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: No bootstrap required, opened a new log
I20260812 06:17:13.683439 11087 ts_tablet_manager.cc:1403] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:13.683923 11087 raft_consensus.cc:359] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ee95059347334025a35d5ebf1d94f462" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 44809 } }
I20260812 06:17:13.684038 11087 raft_consensus.cc:385] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:13.684082 11087 raft_consensus.cc:740] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: ee95059347334025a35d5ebf1d94f462, State: Initialized, Role: FOLLOWER
I20260812 06:17:13.684212 11087 consensus_queue.cc:260] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [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: "ee95059347334025a35d5ebf1d94f462" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 44809 } }
I20260812 06:17:13.684304 11087 raft_consensus.cc:399] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:13.684347 11087 raft_consensus.cc:493] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:13.684396 11087 raft_consensus.cc:3060] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:13.685333 11087 raft_consensus.cc:515] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ee95059347334025a35d5ebf1d94f462" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 44809 } }
I20260812 06:17:13.685474 11087 leader_election.cc:304] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [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: ee95059347334025a35d5ebf1d94f462; no voters: 
I20260812 06:17:13.685722 11087 leader_election.cc:290] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:13.685966 11090 raft_consensus.cc:2804] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:13.686022 11087 ts_tablet_manager.cc:1434] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:17:13.686221 11090 raft_consensus.cc:697] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 1 LEADER]: Becoming Leader. State: Replica: ee95059347334025a35d5ebf1d94f462, State: Running, Role: LEADER
I20260812 06:17:13.686425 11068 heartbeater.cc:499] Master 127.10.139.254:34257 was elected leader, sending a full tablet report...
I20260812 06:17:13.686425 11090 consensus_queue.cc:237] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [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: "ee95059347334025a35d5ebf1d94f462" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 44809 } }
I20260812 06:17:13.688975 10858 catalog_manager.cc:5719] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 reported cstate change: term changed from 0 to 1, leader changed from <none> to ee95059347334025a35d5ebf1d94f462 (127.10.139.193). New cstate: current_term: 1 leader_uuid: "ee95059347334025a35d5ebf1d94f462" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ee95059347334025a35d5ebf1d94f462" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 44809 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:13.755692 10799 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.058s	user 0.010s	sys 0.016s
I20260812 06:17:13.895231 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=19.054940
I20260812 06:17:14.079923 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.184s	user 0.115s	sys 0.060s Metrics: {"bytes_written":13415136,"cfile_init":1,"compiler_manager_pool.queue_time_us":239,"delete_count":0,"dirs.queue_time_us":73,"dirs.run_cpu_time_us":272,"dirs.run_wall_time_us":916,"drs_written":1,"lbm_read_time_us":71,"lbm_reads_lt_1ms":4,"lbm_write_time_us":48699,"lbm_writes_lt_1ms":784,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":137472,"thread_start_us":147,"threads_started":1,"update_count":1635}
I20260812 06:17:14.081104 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=3.181125
I20260812 06:17:14.096495 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.015s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4348810,"delete_count":0,"lbm_write_time_us":6407,"lbm_writes_lt_1ms":109,"reinsert_count":0,"update_count":530}
I20260812 06:17:14.097044 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling LogGCOp(bbaf26275ccf4934a341f920f1a55e0f): free 20290830 bytes of WAL
I20260812 06:17:14.097411 10968 log_reader.cc:385] T bbaf26275ccf4934a341f920f1a55e0f: removed 2 log segments from log reader
I20260812 06:17:14.097513 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000001 (ops 1-6)
I20260812 06:17:14.097607 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000002 (ops 7-10)
I20260812 06:17:14.103583 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: LogGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.006s	user 0.000s	sys 0.003s Metrics: {}
I20260812 06:17:14.103899 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f): 16411395 bytes on disk
I20260812 06:17:14.104423 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4}
I20260812 06:17:14.104990 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.196750
I20260812 06:17:14.112875 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.008s	user 0.001s	sys 0.006s Metrics: {"bytes_written":2748830,"delete_count":0,"lbm_write_time_us":2846,"lbm_writes_lt_1ms":70,"reinsert_count":0,"update_count":335}
I20260812 06:17:14.113279 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:14.314463 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.201s	user 0.152s	sys 0.048s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774776,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":542,"lbm_read_time_us":12144,"lbm_reads_lt_1ms":569,"lbm_write_time_us":34560,"lbm_writes_lt_1ms":543,"mutex_wait_us":27,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11392,"thread_start_us":295,"threads_started":5,"update_count":2500}
I20260812 06:17:14.314947 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:14.369261 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.054s	user 0.027s	sys 0.020s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":24020,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:14.369803 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:14.385372 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.015s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6000,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:14.385876 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:14.521844 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.136s	user 0.113s	sys 0.021s 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":257,"lbm_read_time_us":9389,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25705,"lbm_writes_lt_1ms":443,"mutex_wait_us":47,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":10624,"update_count":2000}
I20260812 06:17:14.522500 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:14.566432 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.044s	user 0.020s	sys 0.019s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18592,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:14.566891 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:14.578482 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.011s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4339,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:14.579181 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:14.697554 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.118s	user 0.106s	sys 0.012s 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":629,"lbm_read_time_us":8887,"lbm_reads_lt_1ms":472,"lbm_write_time_us":23474,"lbm_writes_lt_1ms":443,"mutex_wait_us":28,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:17:14.698081 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:14.743209 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.045s	user 0.021s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17714,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:14.743667 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:14.754669 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4357,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:14.755189 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:14.883407 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.128s	user 0.104s	sys 0.024s 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":1029,"lbm_read_time_us":9346,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26498,"lbm_writes_lt_1ms":443,"mutex_wait_us":339,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":7040,"update_count":2000}
I20260812 06:17:14.884016 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:14.936234 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.052s	user 0.023s	sys 0.016s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":15158,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:14.936798 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:14.952833 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.016s	user 0.003s	sys 0.012s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6316,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:14.953310 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:15.099028 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.144s	user 0.104s	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":304,"lbm_read_time_us":11006,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25725,"lbm_writes_lt_1ms":443,"mutex_wait_us":71,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:17:15.099756 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:15.138725 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.039s	user 0.011s	sys 0.021s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15330,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:15.139212 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:15.154320 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.015s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5889,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:15.154923 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:15.282868 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.128s	user 0.107s	sys 0.020s 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":789,"lbm_read_time_us":10854,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25003,"lbm_writes_lt_1ms":443,"mutex_wait_us":294,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2000}
I20260812 06:17:15.283588 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:15.327620 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.044s	user 0.020s	sys 0.015s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18134,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:17:15.328177 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:15.343123 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.015s	user 0.010s	sys 0.003s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6209,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:15.343679 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:15.376420 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.033s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":61,"dirs.run_cpu_time_us":206,"dirs.run_wall_time_us":1257,"drs_written":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1666,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:17:15.377317 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling LogGCOp(bbaf26275ccf4934a341f920f1a55e0f): free 121006399 bytes of WAL
I20260812 06:17:15.377581 10968 log_reader.cc:385] T bbaf26275ccf4934a341f920f1a55e0f: removed 12 log segments from log reader
I20260812 06:17:15.377642 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000003 (ops 11-15)
I20260812 06:17:15.377681 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000004 (ops 16-20)
I20260812 06:17:15.377719 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000005 (ops 21-25)
I20260812 06:17:15.377744 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000006 (ops 26-30)
I20260812 06:17:15.377772 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000007 (ops 31-35)
I20260812 06:17:15.377803 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000008 (ops 36-40)
I20260812 06:17:15.377825 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000009 (ops 41-45)
I20260812 06:17:15.377857 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000010 (ops 46-50)
I20260812 06:17:15.377890 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000011 (ops 51-55)
I20260812 06:17:15.377920 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000012 (ops 56-60)
I20260812 06:17:15.377950 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000013 (ops 61-64)
I20260812 06:17:15.377976 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000014 (ops 65-69)
I20260812 06:17:15.409365 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: LogGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.032s	user 0.000s	sys 0.031s Metrics: {}
I20260812 06:17:15.409811 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:15.433365 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.023s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6072,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:15.433862 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:15.448241 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5601,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:15.448753 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:15.628793 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.180s	user 0.136s	sys 0.038s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877338,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":515,"lbm_read_time_us":13756,"lbm_reads_lt_1ms":674,"lbm_write_time_us":34896,"lbm_writes_lt_1ms":643,"mutex_wait_us":964,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":11776,"thread_start_us":75,"threads_started":1,"update_count":3000}
I20260812 06:17:15.629827 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:15.678598 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.049s	user 0.026s	sys 0.020s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":21742,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:15.679093 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f): 462 bytes on disk
I20260812 06:17:15.679497 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":47,"lbm_reads_lt_1ms":4}
I20260812 06:17:15.679917 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:15.691278 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4066,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:15.691700 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:15.860357 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.168s	user 0.118s	sys 0.029s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774689,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":221,"lbm_read_time_us":11536,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30171,"lbm_writes_lt_1ms":543,"mutex_wait_us":61,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10368,"update_count":2500}
I20260812 06:17:15.861114 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:15.917428 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.056s	user 0.028s	sys 0.015s Metrics: {"bytes_written":16409907,"delete_count":0,"lbm_write_time_us":19980,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:15.917945 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:15.929389 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3986,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:15.929978 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:16.114269 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.184s	user 0.140s	sys 0.044s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774694,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":156,"lbm_read_time_us":12111,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31433,"lbm_writes_lt_1ms":543,"mutex_wait_us":45,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10240,"update_count":2500}
I20260812 06:17:16.114796 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:16.167265 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.052s	user 0.032s	sys 0.016s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23700,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:16.167783 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:16.318984 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.151s	user 0.109s	sys 0.033s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672158,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":204,"lbm_read_time_us":12116,"lbm_reads_lt_1ms":463,"lbm_write_time_us":24728,"lbm_writes_lt_1ms":443,"mutex_wait_us":53,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8832,"update_count":2000}
I20260812 06:17:16.319725 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:16.375227 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.055s	user 0.021s	sys 0.032s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":25717,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:16.375773 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:16.391217 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.015s	user 0.010s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6180,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:16.391824 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:16.580878 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.189s	user 0.120s	sys 0.062s 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":902,"lbm_read_time_us":12756,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30677,"lbm_writes_lt_1ms":543,"mutex_wait_us":349,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11136,"update_count":2500}
I20260812 06:17:16.581565 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:16.634193 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.052s	user 0.022s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24338,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:16.634644 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:16.647924 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.013s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5276,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:16.648433 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:16.805605 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.157s	user 0.115s	sys 0.032s 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":278,"lbm_read_time_us":10353,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29109,"lbm_writes_lt_1ms":543,"mutex_wait_us":88,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6144,"update_count":2500}
I20260812 06:17:16.806203 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:16.862025 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.056s	user 0.039s	sys 0.012s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23353,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:16.862563 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:16.874586 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.012s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4463,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:16.875067 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:16.909636 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.034s	user 0.029s	sys 0.004s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":69,"dirs.run_cpu_time_us":232,"dirs.run_wall_time_us":1338,"drs_written":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2155,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:17:16.910439 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling LogGCOp(bbaf26275ccf4934a341f920f1a55e0f): free 124257299 bytes of WAL
I20260812 06:17:16.910727 10968 log_reader.cc:385] T bbaf26275ccf4934a341f920f1a55e0f: removed 12 log segments from log reader
I20260812 06:17:16.910792 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000015 (ops 70-74)
I20260812 06:17:16.910833 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000016 (ops 75-79)
I20260812 06:17:16.910871 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000017 (ops 80-84)
I20260812 06:17:16.910897 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000018 (ops 85-89)
I20260812 06:17:16.910925 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000019 (ops 90-94)
I20260812 06:17:16.910952 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000020 (ops 95-99)
I20260812 06:17:16.910982 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000021 (ops 100-104)
I20260812 06:17:16.911018 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000022 (ops 105-109)
I20260812 06:17:16.911051 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000023 (ops 110-114)
I20260812 06:17:16.911077 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000024 (ops 115-119)
I20260812 06:17:16.911103 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000025 (ops 120-124)
I20260812 06:17:16.911124 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000026 (ops 125-128)
I20260812 06:17:16.943538 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: LogGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.033s	user 0.000s	sys 0.030s Metrics: {}
I20260812 06:17:16.943982 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=3.181125
I20260812 06:17:16.972422 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.028s	user 0.005s	sys 0.019s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":7186,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:16.973038 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f): 482 bytes on disk
I20260812 06:17:16.973578 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":62,"lbm_reads_lt_1ms":4}
I20260812 06:17:16.974270 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:16.990124 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.016s	user 0.010s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5870,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:16.990742 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:17.203747 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.213s	user 0.148s	sys 0.064s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979740,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":290,"lbm_read_time_us":17418,"lbm_reads_lt_1ms":774,"lbm_write_time_us":36190,"lbm_writes_lt_1ms":743,"mutex_wait_us":22,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":12672,"thread_start_us":108,"threads_started":1,"update_count":3500}
I20260812 06:17:17.204387 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:17.253743 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.049s	user 0.040s	sys 0.007s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22620,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:17.254446 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:17.271936 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.017s	user 0.000s	sys 0.015s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6915,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.272611 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:17.443310 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.170s	user 0.117s	sys 0.053s 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":665,"lbm_read_time_us":12561,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30057,"lbm_writes_lt_1ms":543,"mutex_wait_us":40,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:17:17.444001 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=14.095187
I20260812 06:17:17.501165 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.057s	user 0.037s	sys 0.018s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":18913,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:17.501889 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:17.516178 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.014s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4996,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.516757 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:17.695058 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.178s	user 0.105s	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":955,"lbm_read_time_us":13109,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31702,"lbm_writes_lt_1ms":543,"mutex_wait_us":56,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10496,"update_count":2500}
I20260812 06:17:17.695643 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:17.732116 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.036s	user 0.026s	sys 0.007s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16230,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:17.732756 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:17.750315 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.016s	user 0.014s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6552,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.753551 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:17.878005 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.124s	user 0.108s	sys 0.016s 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":195,"lbm_read_time_us":7825,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25739,"lbm_writes_lt_1ms":443,"mutex_wait_us":28,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":69632,"update_count":2000}
I20260812 06:17:17.878739 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:17.919068 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.040s	user 0.026s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18219,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:17.919656 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:17.931479 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.012s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4063,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.932094 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:18.061617 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.129s	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":1195,"lbm_read_time_us":8941,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26526,"lbm_writes_lt_1ms":443,"mutex_wait_us":310,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1664,"update_count":2000}
I20260812 06:17:18.062242 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:18.103477 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.041s	user 0.020s	sys 0.011s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":15027,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:18.103955 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:18.114274 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3944,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:18.114910 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:18.244712 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.130s	user 0.103s	sys 0.024s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672276,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":2714,"lbm_read_time_us":9961,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24658,"lbm_writes_lt_1ms":443,"mutex_wait_us":1906,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":3328,"update_count":2000}
I20260812 06:17:18.245440 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=10.126437
I20260812 06:17:18.297945 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.052s	user 0.022s	sys 0.023s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16557,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:18.298470 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:18.309049 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.010s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4246,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:18.309487 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:18.340709 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushMRSOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.031s	user 0.023s	sys 0.004s Metrics: {"bytes_written":1152510,"cfile_init":1,"dirs.queue_time_us":51,"dirs.run_cpu_time_us":255,"dirs.run_wall_time_us":1405,"drs_written":1,"lbm_read_time_us":37,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1492,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28,"spinlock_wait_cycles":896}
I20260812 06:17:18.341475 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:18.482040 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.140s	user 0.104s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":410,"lbm_read_time_us":9885,"lbm_reads_lt_1ms":464,"lbm_write_time_us":23177,"lbm_writes_lt_1ms":443,"mutex_wait_us":58,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":4480,"update_count":2000}
I20260812 06:17:18.482645 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling LogGCOp(bbaf26275ccf4934a341f920f1a55e0f): free 121006640 bytes of WAL
I20260812 06:17:18.482887 10968 log_reader.cc:385] T bbaf26275ccf4934a341f920f1a55e0f: removed 12 log segments from log reader
I20260812 06:17:18.482965 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000027 (ops 129-133)
I20260812 06:17:18.483074 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000028 (ops 134-138)
I20260812 06:17:18.483116 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000029 (ops 139-143)
I20260812 06:17:18.483171 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000030 (ops 144-148)
I20260812 06:17:18.483211 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000031 (ops 149-153)
I20260812 06:17:18.483251 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000032 (ops 154-158)
I20260812 06:17:18.483290 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000033 (ops 159-163)
I20260812 06:17:18.483330 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000034 (ops 164-168)
I20260812 06:17:18.483403 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000035 (ops 169-173)
I20260812 06:17:18.483445 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000036 (ops 174-178)
I20260812 06:17:18.483485 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000037 (ops 179-182)
I20260812 06:17:18.483525 10968 log.cc:1079] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/bbaf26275ccf4934a341f920f1a55e0f/wal-000000038 (ops 183-187)
I20260812 06:17:18.518716 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: LogGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.036s	user 0.000s	sys 0.034s Metrics: {}
I20260812 06:17:18.519121 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f): 446 bytes on disk
I20260812 06:17:18.519675 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: UndoDeltaBlockGCOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":99,"lbm_reads_lt_1ms":4}
I20260812 06:17:18.520273 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=18.063937
I20260812 06:17:18.585127 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.065s	user 0.049s	sys 0.015s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":26378,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:18.585600 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=2.188937
I20260812 06:17:18.597338 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: FlushDeltaMemStoresOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.012s	user 0.002s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4530,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:18.598776 11069 maintenance_manager.cc:419] P ee95059347334025a35d5ebf1d94f462: Scheduling MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f): perf score=1.000000
I20260812 06:17:18.680616 10799 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.925s	user 1.882s	sys 0.135s
I20260812 06:17:18.768601 10799 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.087s	user 0.004s	sys 0.000s
I20260812 06:17:18.769250 10799 tablet_server.cc:179] TabletServer@127.10.139.193:0 shutting down...
I20260812 06:17:18.786286 10968 maintenance_manager.cc:643] P ee95059347334025a35d5ebf1d94f462: MajorDeltaCompactionOp(bbaf26275ccf4934a341f920f1a55e0f) complete. Timing: real 0.187s	user 0.115s	sys 0.071s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877104,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":349,"lbm_read_time_us":13753,"lbm_reads_lt_1ms":668,"lbm_write_time_us":31004,"lbm_writes_lt_1ms":643,"mutex_wait_us":47,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":15232,"update_count":3000}
I20260812 06:17:18.786936 10799 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:18.787343 10799 tablet_replica.cc:333] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462: stopping tablet replica
I20260812 06:17:18.787566 10799 raft_consensus.cc:2243] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:18.787786 10799 raft_consensus.cc:2272] T bbaf26275ccf4934a341f920f1a55e0f P ee95059347334025a35d5ebf1d94f462 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:18.804823 10799 tablet_server.cc:196] TabletServer@127.10.139.193:0 shutdown complete.
I20260812 06:17:18.842049 10799 master.cc:562] Master@127.10.139.254:34257 shutting down...
I20260812 06:17:18.845745 10799 raft_consensus.cc:2243] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:18.845906 10799 raft_consensus.cc:2272] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:18.845990 10799 tablet_replica.cc:333] T 00000000000000000000000000000000 P d4a9b2d0965a4f4ba77bb61f5977cb87: stopping tablet replica
I20260812 06:17:18.858386 10799 master.cc:584] Master@127.10.139.254:34257 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5426 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:17:18.961684 10799 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.10.139.254:44003
I20260812 06:17:18.962059 10799 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:18.964116 11111 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:18.964210 10799 server_base.cc:1061] running on GCE node
W20260812 06:17:18.964118 11116 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:18.964212 11113 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:18.964630 10799 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:18.964690 10799 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:18.964715 10799 hybrid_clock.cc:648] HybridClock initialized: now 1786515438964714 us; error 0 us; skew 500 ppm
I20260812 06:17:18.965549 10799 webserver.cc:533] Webserver started at http://127.10.139.254:45537/ using document root <none> and password file <none>
I20260812 06:17:18.965727 10799 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:18.965792 10799 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:18.965871 10799 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:18.966245 10799 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/master-0-root/instance:
uuid: "26f3cc16a78f4949a7add5534d734b0b"
format_stamp: "Formatted at 2026-08-12 06:17:18 on dist-test-slave-04bb"
I20260812 06:17:18.967808 10799 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:17:18.968822 11122 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:18.969054 10799 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:18.969142 10799 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/master-0-root
uuid: "26f3cc16a78f4949a7add5534d734b0b"
format_stamp: "Formatted at 2026-08-12 06:17:18 on dist-test-slave-04bb"
I20260812 06:17:18.969223 10799 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-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:18.978823 10799 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:18.979149 10799 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:18.983357 10799 rpc_server.cc:307] RPC server started. Bound to: 127.10.139.254:44003
I20260812 06:17:18.985019 11207 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.10.139.254:44003 every 8 connection(s)
I20260812 06:17:18.986927 11208 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:18.990653 11208 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b: Bootstrap starting.
I20260812 06:17:18.991395 11208 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:18.992333 11208 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b: No bootstrap required, opened a new log
I20260812 06:17:18.992689 11208 raft_consensus.cc:359] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "26f3cc16a78f4949a7add5534d734b0b" member_type: VOTER }
I20260812 06:17:18.992769 11208 raft_consensus.cc:385] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:18.992792 11208 raft_consensus.cc:740] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 26f3cc16a78f4949a7add5534d734b0b, State: Initialized, Role: FOLLOWER
I20260812 06:17:18.992887 11208 consensus_queue.cc:260] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [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: "26f3cc16a78f4949a7add5534d734b0b" member_type: VOTER }
I20260812 06:17:18.992941 11208 raft_consensus.cc:399] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:18.992964 11208 raft_consensus.cc:493] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:18.992992 11208 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:18.993595 11208 raft_consensus.cc:515] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "26f3cc16a78f4949a7add5534d734b0b" member_type: VOTER }
I20260812 06:17:18.993705 11208 leader_election.cc:304] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [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: 26f3cc16a78f4949a7add5534d734b0b; no voters: 
I20260812 06:17:18.993836 11208 leader_election.cc:290] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:18.993968 11214 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:18.994199 11214 raft_consensus.cc:697] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 1 LEADER]: Becoming Leader. State: Replica: 26f3cc16a78f4949a7add5534d734b0b, State: Running, Role: LEADER
I20260812 06:17:18.994334 11208 sys_catalog.cc:565] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:18.994356 11214 consensus_queue.cc:237] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [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: "26f3cc16a78f4949a7add5534d734b0b" member_type: VOTER }
I20260812 06:17:18.994844 11215 sys_catalog.cc:455] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "26f3cc16a78f4949a7add5534d734b0b" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "26f3cc16a78f4949a7add5534d734b0b" member_type: VOTER } }
I20260812 06:17:18.994895 11216 sys_catalog.cc:455] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [sys.catalog]: SysCatalogTable state changed. Reason: New leader 26f3cc16a78f4949a7add5534d734b0b. Latest consensus state: current_term: 1 leader_uuid: "26f3cc16a78f4949a7add5534d734b0b" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "26f3cc16a78f4949a7add5534d734b0b" member_type: VOTER } }
I20260812 06:17:18.994939 11215 sys_catalog.cc:458] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:18.995000 11216 sys_catalog.cc:458] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:18.995473 11224 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:18.996409 11224 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:18.996614 10799 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:18.998242 11224 catalog_manager.cc:1383] Generated new cluster ID: 69f187243f25450aabb0baa6ba02d9ec
I20260812 06:17:18.998301 11224 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:19.016566 11224 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:19.017092 11224 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:19.021644 11224 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b: Generated new TSK 0
I20260812 06:17:19.021787 11224 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:19.029066 10799 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:19.031322 11242 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:19.031284 10799 server_base.cc:1061] running on GCE node
W20260812 06:17:19.031380 11246 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:19.031303 11244 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:19.031737 10799 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:19.031796 10799 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:19.031813 10799 hybrid_clock.cc:648] HybridClock initialized: now 1786515439031814 us; error 0 us; skew 500 ppm
I20260812 06:17:19.032792 10799 webserver.cc:533] Webserver started at http://127.10.139.193:42537/ using document root <none> and password file <none>
I20260812 06:17:19.032938 10799 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:19.032984 10799 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:19.033038 10799 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:19.033445 10799 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/instance:
uuid: "957e49060d814901a47d031c21a27f91"
format_stamp: "Formatted at 2026-08-12 06:17:19 on dist-test-slave-04bb"
I20260812 06:17:19.034989 10799 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:17:19.035967 11253 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:19.036254 10799 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:19.036329 10799 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root
uuid: "957e49060d814901a47d031c21a27f91"
format_stamp: "Formatted at 2026-08-12 06:17:19 on dist-test-slave-04bb"
I20260812 06:17:19.036375 10799 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-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:19.050633 10799 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:19.050961 10799 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:19.051226 10799 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:19.051793 10799 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:19.051841 10799 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:19.051875 10799 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:19.051890 10799 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:19.056062 10799 rpc_server.cc:307] RPC server started. Bound to: 127.10.139.193:32805
I20260812 06:17:19.056671 11347 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.10.139.193:32805 every 8 connection(s)
I20260812 06:17:19.065068 11348 heartbeater.cc:344] Connected to a master server at 127.10.139.254:44003
I20260812 06:17:19.065202 11348 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:19.065462 11348 heartbeater.cc:507] Master 127.10.139.254:44003 requested a full tablet report, sending...
I20260812 06:17:19.066205 11152 ts_manager.cc:194] Registered new tserver with Master: 957e49060d814901a47d031c21a27f91 (127.10.139.193:32805)
I20260812 06:17:19.066624 10799 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.0098694s
I20260812 06:17:19.067054 11152 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:60930
I20260812 06:17:19.073555 11152 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:60946:
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:19.082053 11301 tablet_service.cc:1511] Processing CreateTablet for tablet 194588dd6ef94343bf4d1d8f4192e029 (DEFAULT_TABLE table=heavy-update-compaction-test [id=fe67e2b4fb424f9eb24b071ea4f3fa76]), partition=
I20260812 06:17:19.082325 11301 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 194588dd6ef94343bf4d1d8f4192e029. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:19.084232 11369 tablet_bootstrap.cc:492] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Bootstrap starting.
I20260812 06:17:19.085436 11369 tablet_bootstrap.cc:654] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:19.086592 11369 tablet_bootstrap.cc:492] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: No bootstrap required, opened a new log
I20260812 06:17:19.086707 11369 ts_tablet_manager.cc:1403] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:17:19.087217 11369 raft_consensus.cc:359] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "957e49060d814901a47d031c21a27f91" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 32805 } }
I20260812 06:17:19.087317 11369 raft_consensus.cc:385] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:19.087395 11369 raft_consensus.cc:740] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 957e49060d814901a47d031c21a27f91, State: Initialized, Role: FOLLOWER
I20260812 06:17:19.087587 11369 consensus_queue.cc:260] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [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: "957e49060d814901a47d031c21a27f91" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 32805 } }
I20260812 06:17:19.087678 11369 raft_consensus.cc:399] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:19.087760 11369 raft_consensus.cc:493] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:19.087845 11369 raft_consensus.cc:3060] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:19.088784 11369 raft_consensus.cc:515] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "957e49060d814901a47d031c21a27f91" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 32805 } }
I20260812 06:17:19.088963 11369 leader_election.cc:304] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [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: 957e49060d814901a47d031c21a27f91; no voters: 
I20260812 06:17:19.089215 11369 leader_election.cc:290] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:19.089330 11371 raft_consensus.cc:2804] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:19.089567 11371 raft_consensus.cc:697] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 1 LEADER]: Becoming Leader. State: Replica: 957e49060d814901a47d031c21a27f91, State: Running, Role: LEADER
I20260812 06:17:19.089643 11348 heartbeater.cc:499] Master 127.10.139.254:44003 was elected leader, sending a full tablet report...
I20260812 06:17:19.089680 11369 ts_tablet_manager.cc:1434] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Time spent starting tablet: real 0.003s	user 0.001s	sys 0.003s
I20260812 06:17:19.089718 11371 consensus_queue.cc:237] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [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: "957e49060d814901a47d031c21a27f91" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 32805 } }
I20260812 06:17:19.090978 11152 catalog_manager.cc:5719] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 reported cstate change: term changed from 0 to 1, leader changed from <none> to 957e49060d814901a47d031c21a27f91 (127.10.139.193). New cstate: current_term: 1 leader_uuid: "957e49060d814901a47d031c21a27f91" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "957e49060d814901a47d031c21a27f91" member_type: VOTER last_known_addr { host: "127.10.139.193" port: 32805 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:19.153184 10799 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.055s	user 0.024s	sys 0.000s
I20260812 06:17:19.307199 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029): perf score=19.054940
I20260812 06:17:19.461323 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.154s	user 0.114s	sys 0.039s Metrics: {"bytes_written":12307490,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":73,"dirs.run_cpu_time_us":178,"dirs.run_wall_time_us":1346,"drs_written":1,"lbm_read_time_us":78,"lbm_reads_lt_1ms":4,"lbm_write_time_us":39278,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"update_count":1500}
I20260812 06:17:19.462000 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling LogGCOp(194588dd6ef94343bf4d1d8f4192e029): free 20743880 bytes of WAL
I20260812 06:17:19.462249 11260 log_reader.cc:385] T 194588dd6ef94343bf4d1d8f4192e029: removed 2 log segments from log reader
I20260812 06:17:19.462303 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000001 (ops 1-6)
I20260812 06:17:19.462335 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000002 (ops 7-11)
I20260812 06:17:19.466688 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: LogGCOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.004s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:17:19.467042 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029): 16411394 bytes on disk
I20260812 06:17:19.467461 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":63,"lbm_reads_lt_1ms":4}
I20260812 06:17:19.467926 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:19.483738 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.016s	user 0.007s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6348,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:19.484133 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:19.637264 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.153s	user 0.103s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":488,"lbm_read_time_us":11002,"lbm_reads_lt_1ms":464,"lbm_write_time_us":25334,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1408,"thread_start_us":322,"threads_started":5,"update_count":2000}
I20260812 06:17:19.637897 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:19.700567 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.062s	user 0.032s	sys 0.028s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":22374,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:19.701071 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:19.711459 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4204,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:19.711840 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:19.914021 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.202s	user 0.088s	sys 0.107s 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":148,"lbm_read_time_us":13378,"lbm_reads_lt_1ms":572,"lbm_write_time_us":34384,"lbm_writes_lt_1ms":543,"mutex_wait_us":38,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:17:19.914623 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:19.969899 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.055s	user 0.025s	sys 0.020s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":21068,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:19.970430 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:19.981392 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4342,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:19.982013 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:20.177333 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.195s	user 0.135s	sys 0.056s 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":290,"lbm_read_time_us":12630,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31428,"lbm_writes_lt_1ms":543,"mutex_wait_us":20,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11648,"update_count":2500}
I20260812 06:17:20.178010 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:20.229072 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.051s	user 0.031s	sys 0.019s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":23088,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:20.229511 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:20.241171 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4492,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:20.241578 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:20.407074 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.165s	user 0.101s	sys 0.055s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":287,"lbm_read_time_us":10894,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30966,"lbm_writes_lt_1ms":543,"mutex_wait_us":24,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13696,"update_count":2500}
I20260812 06:17:20.407651 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:20.457046 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.049s	user 0.031s	sys 0.009s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":18137,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:20.457464 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:20.468010 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4138,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:20.468715 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:20.627601 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.159s	user 0.118s	sys 0.032s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":540,"lbm_read_time_us":9662,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32048,"lbm_writes_lt_1ms":543,"mutex_wait_us":41,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":49152,"update_count":2500}
I20260812 06:17:20.628175 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:20.676476 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.048s	user 0.032s	sys 0.012s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23322,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:20.677037 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:20.692888 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.016s	user 0.011s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6391,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:20.693568 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:20.724573 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.031s	user 0.028s	sys 0.000s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":68,"dirs.run_cpu_time_us":240,"dirs.run_wall_time_us":1400,"drs_written":1,"lbm_read_time_us":36,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1494,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:17:20.725148 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling LogGCOp(194588dd6ef94343bf4d1d8f4192e029): free 115943176 bytes of WAL
I20260812 06:17:20.725366 11260 log_reader.cc:385] T 194588dd6ef94343bf4d1d8f4192e029: removed 11 log segments from log reader
I20260812 06:17:20.725410 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000003 (ops 12-16)
I20260812 06:17:20.725438 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000004 (ops 17-21)
I20260812 06:17:20.725455 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000005 (ops 22-26)
I20260812 06:17:20.725514 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000006 (ops 27-31)
I20260812 06:17:20.725555 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000007 (ops 32-36)
I20260812 06:17:20.725594 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000008 (ops 37-41)
I20260812 06:17:20.725632 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000009 (ops 42-46)
I20260812 06:17:20.725672 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000010 (ops 47-51)
I20260812 06:17:20.725710 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000011 (ops 52-56)
I20260812 06:17:20.725749 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000012 (ops 57-61)
I20260812 06:17:20.725786 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000013 (ops 62-66)
I20260812 06:17:20.753167 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: LogGCOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.028s	user 0.001s	sys 0.026s Metrics: {}
I20260812 06:17:20.753525 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029): 472 bytes on disk
I20260812 06:17:20.753997 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029) 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:20.754433 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=4.173312
I20260812 06:17:20.771705 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.017s	user 0.015s	sys 0.000s Metrics: {"bytes_written":5866705,"delete_count":0,"lbm_write_time_us":6993,"lbm_writes_lt_1ms":146,"reinsert_count":0,"update_count":715}
I20260812 06:17:20.772142 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling LogGCOp(194588dd6ef94343bf4d1d8f4192e029): free 8767118 bytes of WAL
I20260812 06:17:20.772378 11260 log_reader.cc:385] T 194588dd6ef94343bf4d1d8f4192e029: removed 1 log segments from log reader
I20260812 06:17:20.772439 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000014 (ops 67-71)
I20260812 06:17:20.774734 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: LogGCOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.002s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:17:20.775025 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.196750
I20260812 06:17:20.783707 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.009s	user 0.006s	sys 0.000s Metrics: {"bytes_written":2338579,"delete_count":0,"lbm_write_time_us":2617,"lbm_writes_lt_1ms":60,"reinsert_count":0,"update_count":285}
I20260812 06:17:20.784102 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:21.026278 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.242s	user 0.143s	sys 0.090s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979710,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":152,"lbm_read_time_us":16519,"lbm_reads_lt_1ms":766,"lbm_write_time_us":38668,"lbm_writes_lt_1ms":743,"mutex_wait_us":22,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":11008,"thread_start_us":79,"threads_started":1,"update_count":3500}
I20260812 06:17:21.028955 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=19.056125
I20260812 06:17:21.106824 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.078s	user 0.036s	sys 0.026s Metrics: {"bytes_written":20922552,"delete_count":0,"lbm_write_time_us":29359,"lbm_writes_lt_1ms":513,"reinsert_count":0,"update_count":2550}
I20260812 06:17:21.107257 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=6.157687
I20260812 06:17:21.135447 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.028s	user 0.015s	sys 0.004s Metrics: {"bytes_written":7794838,"delete_count":0,"lbm_write_time_us":9099,"lbm_writes_lt_1ms":193,"reinsert_count":0,"update_count":950}
I20260812 06:17:21.135958 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:21.362672 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.227s	user 0.143s	sys 0.082s Metrics: {"cfile_cache_miss":732,"cfile_cache_miss_bytes":32979512,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":259,"lbm_read_time_us":15186,"lbm_reads_lt_1ms":764,"lbm_write_time_us":39741,"lbm_writes_lt_1ms":743,"mutex_wait_us":42,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":12160,"update_count":3500}
I20260812 06:17:21.363241 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=19.056125
I20260812 06:17:21.427103 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.064s	user 0.044s	sys 0.012s Metrics: {"bytes_written":20922555,"delete_count":0,"lbm_write_time_us":26948,"lbm_writes_lt_1ms":513,"reinsert_count":0,"update_count":2550}
I20260812 06:17:21.427649 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:21.441838 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.014s	user 0.001s	sys 0.012s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5577,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:21.442350 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:21.456219 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.014s	user 0.008s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5200,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:21.456838 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:21.645756 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.189s	user 0.127s	sys 0.061s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979619,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":1581,"lbm_read_time_us":13705,"lbm_reads_lt_1ms":773,"lbm_write_time_us":38339,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":3840,"update_count":3500}
I20260812 06:17:21.646482 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:21.689837 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.043s	user 0.026s	sys 0.015s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19299,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:21.690408 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:21.709581 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.019s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6150,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:21.710024 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:21.863901 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.154s	user 0.105s	sys 0.045s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":235,"lbm_read_time_us":8611,"lbm_reads_lt_1ms":564,"lbm_write_time_us":29863,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11136,"update_count":2500}
I20260812 06:17:21.864543 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:21.923022 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.058s	user 0.042s	sys 0.010s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":24793,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:21.923658 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:22.094158 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.170s	user 0.111s	sys 0.048s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672156,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":284,"lbm_read_time_us":10841,"lbm_reads_lt_1ms":463,"lbm_write_time_us":25202,"lbm_writes_lt_1ms":443,"mutex_wait_us":71,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":2000}
I20260812 06:17:22.095386 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:22.147090 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.051s	user 0.021s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21271,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:22.147617 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:22.159529 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.012s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4416,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:22.160259 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:22.197029 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.037s	user 0.025s	sys 0.004s Metrics: {"bytes_written":1193509,"cfile_init":1,"dirs.queue_time_us":76,"dirs.run_cpu_time_us":242,"dirs.run_wall_time_us":1333,"drs_written":1,"lbm_read_time_us":38,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1685,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:17:22.197714 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling LogGCOp(194588dd6ef94343bf4d1d8f4192e029): free 112692378 bytes of WAL
I20260812 06:17:22.197948 11260 log_reader.cc:385] T 194588dd6ef94343bf4d1d8f4192e029: removed 11 log segments from log reader
I20260812 06:17:22.197991 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000015 (ops 72-76)
I20260812 06:17:22.198019 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000016 (ops 77-81)
I20260812 06:17:22.198072 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000017 (ops 82-86)
I20260812 06:17:22.198113 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000018 (ops 87-91)
I20260812 06:17:22.198155 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000019 (ops 92-96)
I20260812 06:17:22.198194 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000020 (ops 97-101)
I20260812 06:17:22.198231 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000021 (ops 102-106)
I20260812 06:17:22.198268 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000022 (ops 107-111)
I20260812 06:17:22.198305 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000023 (ops 112-116)
I20260812 06:17:22.198342 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000024 (ops 117-121)
I20260812 06:17:22.198382 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000025 (ops 122-126)
I20260812 06:17:22.222584 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: LogGCOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.025s	user 0.000s	sys 0.023s Metrics: {}
I20260812 06:17:22.223163 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=3.181125
I20260812 06:17:22.248168 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.025s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4307783,"delete_count":0,"lbm_write_time_us":5557,"lbm_writes_lt_1ms":108,"reinsert_count":0,"update_count":525}
I20260812 06:17:22.248782 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029): 463 bytes on disk
I20260812 06:17:22.249219 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029) 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:22.249722 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:22.260214 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3897534,"delete_count":0,"lbm_write_time_us":3987,"lbm_writes_lt_1ms":98,"reinsert_count":0,"update_count":475}
I20260812 06:17:22.260825 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:22.502471 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.241s	user 0.137s	sys 0.104s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979749,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":956,"lbm_read_time_us":17321,"lbm_reads_lt_1ms":774,"lbm_write_time_us":39921,"lbm_writes_lt_1ms":743,"mutex_wait_us":414,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":13440,"thread_start_us":102,"threads_started":1,"update_count":3500}
I20260812 06:17:22.503104 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=18.063937
I20260812 06:17:22.606109 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.103s	user 0.036s	sys 0.022s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":70247,"lbm_writes_10-100_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:17:22.606846 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:22.621393 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.014s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4606,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:22.621819 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:22.835919 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.214s	user 0.144s	sys 0.070s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877104,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":76,"lbm_read_time_us":12996,"lbm_reads_lt_1ms":664,"lbm_write_time_us":35241,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2176,"update_count":3000}
I20260812 06:17:22.836730 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=18.063937
I20260812 06:17:22.918414 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.081s	user 0.048s	sys 0.025s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":33888,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:22.918998 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:22.929472 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3931,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:22.930738 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:23.153267 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.222s	user 0.135s	sys 0.087s 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":162,"lbm_read_time_us":15832,"lbm_reads_lt_1ms":672,"lbm_write_time_us":38394,"lbm_writes_lt_1ms":643,"mutex_wait_us":40,"peak_mem_usage":75542472,"reinsert_count":0,"update_count":3000}
I20260812 06:17:23.153838 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=18.063937
I20260812 06:17:23.212996 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.059s	user 0.040s	sys 0.016s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":25924,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:23.213559 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:23.224493 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4432,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:23.224982 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:23.428066 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.203s	user 0.115s	sys 0.081s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877104,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":183,"lbm_read_time_us":15592,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32233,"lbm_writes_lt_1ms":643,"mutex_wait_us":42,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":14080,"update_count":3000}
I20260812 06:17:23.428793 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=18.063937
I20260812 06:17:23.496343 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.067s	user 0.038s	sys 0.021s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":29049,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:23.496927 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:23.510784 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.014s	user 0.009s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5354,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:23.511225 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:23.719762 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.208s	user 0.131s	sys 0.076s 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":787,"lbm_read_time_us":15106,"lbm_reads_lt_1ms":668,"lbm_write_time_us":34977,"lbm_writes_lt_1ms":643,"mutex_wait_us":113,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":13440,"update_count":3000}
I20260812 06:17:23.720646 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=14.095187
I20260812 06:17:23.788224 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.067s	user 0.022s	sys 0.025s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":24496,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:23.788836 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=6.157687
I20260812 06:17:23.813400 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.024s	user 0.009s	sys 0.013s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":9540,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:23.813985 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:23.854351 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushMRSOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.040s	user 0.035s	sys 0.003s Metrics: {"bytes_written":1357582,"cfile_init":1,"dirs.queue_time_us":86,"dirs.run_cpu_time_us":291,"dirs.run_wall_time_us":1369,"drs_written":1,"lbm_read_time_us":42,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2794,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":33}
I20260812 06:17:23.854991 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling LogGCOp(194588dd6ef94343bf4d1d8f4192e029): free 136275453 bytes of WAL
I20260812 06:17:23.855218 11260 log_reader.cc:385] T 194588dd6ef94343bf4d1d8f4192e029: removed 13 log segments from log reader
I20260812 06:17:23.855262 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000026 (ops 127-131)
I20260812 06:17:23.855291 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000027 (ops 132-136)
I20260812 06:17:23.855365 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000028 (ops 137-141)
I20260812 06:17:23.855391 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000029 (ops 142-146)
I20260812 06:17:23.855453 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000030 (ops 147-150)
I20260812 06:17:23.855490 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000031 (ops 151-155)
I20260812 06:17:23.855528 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000032 (ops 156-160)
I20260812 06:17:23.855566 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000033 (ops 161-165)
I20260812 06:17:23.855602 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000034 (ops 166-170)
I20260812 06:17:23.855643 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000035 (ops 171-175)
I20260812 06:17:23.855683 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000036 (ops 176-180)
I20260812 06:17:23.855721 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000037 (ops 181-185)
I20260812 06:17:23.855758 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000038 (ops 186-190)
I20260812 06:17:23.885435 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: LogGCOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.030s	user 0.000s	sys 0.029s Metrics: {}
I20260812 06:17:23.885835 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=6.157687
I20260812 06:17:23.921979 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.036s	user 0.012s	sys 0.015s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":12376,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:23.922463 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling LogGCOp(194588dd6ef94343bf4d1d8f4192e029): free 8767140 bytes of WAL
I20260812 06:17:23.922663 11260 log_reader.cc:385] T 194588dd6ef94343bf4d1d8f4192e029: removed 1 log segments from log reader
I20260812 06:17:23.922708 11260 log.cc:1079] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: Deleting log segment in path: /tmp/dist-test-taskng3Vhs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515433511225-10799-0/minicluster-data/ts-0-root/wals/194588dd6ef94343bf4d1d8f4192e029/wal-000000039 (ops 191-195)
I20260812 06:17:23.924479 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: LogGCOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.002s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:17:23.924811 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029): 506 bytes on disk
I20260812 06:17:23.925181 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: UndoDeltaBlockGCOp(194588dd6ef94343bf4d1d8f4192e029) 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:23.925658 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029): perf score=2.188937
I20260812 06:17:23.937239 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: FlushDeltaMemStoresOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4311,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:23.937808 11350 maintenance_manager.cc:419] P 957e49060d814901a47d031c21a27f91: Scheduling MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029): perf score=1.000000
I20260812 06:17:24.024245 10799 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.871s	user 1.749s	sys 0.211s
I20260812 06:17:24.154493 10799 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.130s	user 0.001s	sys 0.000s
I20260812 06:17:24.155027 10799 tablet_server.cc:179] TabletServer@127.10.139.193:0 shutting down...
I20260812 06:17:24.193132 11260 maintenance_manager.cc:643] P 957e49060d814901a47d031c21a27f91: MajorDeltaCompactionOp(194588dd6ef94343bf4d1d8f4192e029) complete. Timing: real 0.255s	user 0.167s	sys 0.088s Metrics: {"cfile_cache_miss":934,"cfile_cache_miss_bytes":41184577,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":635,"lbm_read_time_us":18746,"lbm_reads_lt_1ms":970,"lbm_write_time_us":46735,"lbm_writes_lt_1ms":943,"mutex_wait_us":21,"peak_mem_usage":112822188,"reinsert_count":0,"spinlock_wait_cycles":11392,"thread_start_us":133,"threads_started":1,"update_count":4500}
I20260812 06:17:24.194139 10799 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:24.194550 10799 tablet_replica.cc:333] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91: stopping tablet replica
I20260812 06:17:24.194713 10799 raft_consensus.cc:2243] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:24.194893 10799 raft_consensus.cc:2272] T 194588dd6ef94343bf4d1d8f4192e029 P 957e49060d814901a47d031c21a27f91 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:24.210950 10799 tablet_server.cc:196] TabletServer@127.10.139.193:0 shutdown complete.
I20260812 06:17:24.284144 10799 master.cc:562] Master@127.10.139.254:44003 shutting down...
I20260812 06:17:24.288190 10799 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:24.288412 10799 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:24.288515 10799 tablet_replica.cc:333] T 00000000000000000000000000000000 P 26f3cc16a78f4949a7add5534d734b0b: stopping tablet replica
I20260812 06:17:24.301010 10799 master.cc:584] Master@127.10.139.254:44003 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5439 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10867 ms total)

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