[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:20:23.551122  5757 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.5.159.126:39187
I20260812 06:20:23.554029  5757 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:20:23.554899  5757 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:23.563112  5765 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:23.563446  5764 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:23.563263  5757 server_base.cc:1061] running on GCE node
W20260812 06:20:23.563091  5767 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:23.564254  5757 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:23.564427  5757 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:23.564465  5757 hybrid_clock.cc:648] HybridClock initialized: now 1786515623564464 us; error 0 us; skew 500 ppm
I20260812 06:20:23.567092  5757 webserver.cc:533] Webserver started at http://127.5.159.126:33755/ using document root <none> and password file <none>
I20260812 06:20:23.567785  5757 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:23.567857  5757 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:23.568063  5757 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:23.570084  5757 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/master-0-root/instance:
uuid: "51efded24c9a4f018dd381ba69a16dca"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-csg5"
I20260812 06:20:23.574905  5757 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.003s
I20260812 06:20:23.578145  5772 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:23.579700  5757 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:20:23.579927  5757 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/master-0-root
uuid: "51efded24c9a4f018dd381ba69a16dca"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-csg5"
I20260812 06:20:23.580072  5757 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:23.605214  5757 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:23.606063  5757 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:20:23.606455  5757 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:23.616632  5841 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.159.126:39187 every 8 connection(s)
I20260812 06:20:23.616690  5757 rpc_server.cc:307] RPC server started. Bound to: 127.5.159.126:39187
I20260812 06:20:23.619617  5844 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:23.626228  5844 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca: Bootstrap starting.
I20260812 06:20:23.629205  5844 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:23.630390  5844 log.cc:826] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca: Log is configured to *not* fsync() on all Append() calls
I20260812 06:20:23.632717  5844 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca: No bootstrap required, opened a new log
I20260812 06:20:23.636406  5844 raft_consensus.cc:359] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "51efded24c9a4f018dd381ba69a16dca" member_type: VOTER }
I20260812 06:20:23.636641  5844 raft_consensus.cc:385] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:23.636722  5844 raft_consensus.cc:740] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 51efded24c9a4f018dd381ba69a16dca, State: Initialized, Role: FOLLOWER
I20260812 06:20:23.637449  5844 consensus_queue.cc:260] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [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: "51efded24c9a4f018dd381ba69a16dca" member_type: VOTER }
I20260812 06:20:23.637632  5844 raft_consensus.cc:399] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:23.637679  5844 raft_consensus.cc:493] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:23.637827  5844 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:23.639050  5844 raft_consensus.cc:515] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "51efded24c9a4f018dd381ba69a16dca" member_type: VOTER }
I20260812 06:20:23.639642  5844 leader_election.cc:304] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [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: 51efded24c9a4f018dd381ba69a16dca; no voters: 
I20260812 06:20:23.640054  5844 leader_election.cc:290] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:23.640527  5848 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:23.640859  5848 raft_consensus.cc:697] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 1 LEADER]: Becoming Leader. State: Replica: 51efded24c9a4f018dd381ba69a16dca, State: Running, Role: LEADER
I20260812 06:20:23.641387  5848 consensus_queue.cc:237] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [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: "51efded24c9a4f018dd381ba69a16dca" member_type: VOTER }
I20260812 06:20:23.641536  5844 sys_catalog.cc:565] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:23.643496  5850 sys_catalog.cc:455] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [sys.catalog]: SysCatalogTable state changed. Reason: New leader 51efded24c9a4f018dd381ba69a16dca. Latest consensus state: current_term: 1 leader_uuid: "51efded24c9a4f018dd381ba69a16dca" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "51efded24c9a4f018dd381ba69a16dca" member_type: VOTER } }
I20260812 06:20:23.643647  5850 sys_catalog.cc:458] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:23.643965  5849 sys_catalog.cc:455] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "51efded24c9a4f018dd381ba69a16dca" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "51efded24c9a4f018dd381ba69a16dca" member_type: VOTER } }
I20260812 06:20:23.644068  5849 sys_catalog.cc:458] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:23.644236  5858 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:23.646987  5858 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:23.647300  5757 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:23.652796  5858 catalog_manager.cc:1383] Generated new cluster ID: c100ff81e6e74bc3965c1c5b756fd378
I20260812 06:20:23.652901  5858 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:23.662070  5858 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:23.664274  5858 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:23.674045  5858 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca: Generated new TSK 0
I20260812 06:20:23.675395  5858 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:23.680367  5757 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:23.684187  5871 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:23.684391  5870 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:23.684541  5874 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:23.685043  5757 server_base.cc:1061] running on GCE node
I20260812 06:20:23.685271  5757 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:23.685314  5757 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:23.685336  5757 hybrid_clock.cc:648] HybridClock initialized: now 1786515623685336 us; error 0 us; skew 500 ppm
I20260812 06:20:23.687494  5757 webserver.cc:533] Webserver started at http://127.5.159.65:35037/ using document root <none> and password file <none>
I20260812 06:20:23.687718  5757 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:23.687949  5757 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:23.688090  5757 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:23.688640  5757 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/instance:
uuid: "9a83f989530745e0bb4c62225ebd5a31"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-csg5"
I20260812 06:20:23.691815  5757 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:20:23.693634  5881 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:23.694177  5757 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:20:23.694283  5757 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root
uuid: "9a83f989530745e0bb4c62225ebd5a31"
format_stamp: "Formatted at 2026-08-12 06:20:23 on dist-test-slave-csg5"
I20260812 06:20:23.694418  5757 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:23.717684  5757 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:23.718307  5757 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:23.718957  5757 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:23.720500  5757 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:23.720600  5757 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:23.720674  5757 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:23.720700  5757 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:23.728875  5757 rpc_server.cc:307] RPC server started. Bound to: 127.5.159.65:44247
I20260812 06:20:23.728932  5958 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.159.65:44247 every 8 connection(s)
I20260812 06:20:23.746470  5959 heartbeater.cc:344] Connected to a master server at 127.5.159.126:39187
I20260812 06:20:23.746919  5959 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:23.747550  5959 heartbeater.cc:507] Master 127.5.159.126:39187 requested a full tablet report, sending...
I20260812 06:20:23.749651  5798 ts_manager.cc:194] Registered new tserver with Master: 9a83f989530745e0bb4c62225ebd5a31 (127.5.159.65:44247)
I20260812 06:20:23.750171  5757 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.020445354s
I20260812 06:20:23.751533  5798 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:42762
I20260812 06:20:23.762353  5798 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:42772:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:20:23.779210  5911 tablet_service.cc:1511] Processing CreateTablet for tablet 69d65da8baad42c7bef8d24c27d3b30a (DEFAULT_TABLE table=heavy-update-compaction-test [id=245fa6af98114fa9b3d89e2b48532556]), partition=
I20260812 06:20:23.779759  5911 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 69d65da8baad42c7bef8d24c27d3b30a. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:23.782641  5973 tablet_bootstrap.cc:492] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Bootstrap starting.
I20260812 06:20:23.783756  5973 tablet_bootstrap.cc:654] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:23.785133  5973 tablet_bootstrap.cc:492] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: No bootstrap required, opened a new log
I20260812 06:20:23.785277  5973 ts_tablet_manager.cc:1403] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Time spent bootstrapping tablet: real 0.003s	user 0.000s	sys 0.002s
I20260812 06:20:23.786058  5973 raft_consensus.cc:359] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a83f989530745e0bb4c62225ebd5a31" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 44247 } }
I20260812 06:20:23.786238  5973 raft_consensus.cc:385] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:23.786346  5973 raft_consensus.cc:740] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 9a83f989530745e0bb4c62225ebd5a31, State: Initialized, Role: FOLLOWER
I20260812 06:20:23.786617  5973 consensus_queue.cc:260] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [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: "9a83f989530745e0bb4c62225ebd5a31" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 44247 } }
I20260812 06:20:23.786794  5973 raft_consensus.cc:399] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:23.786870  5973 raft_consensus.cc:493] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:23.786931  5973 raft_consensus.cc:3060] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:23.787912  5973 raft_consensus.cc:515] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a83f989530745e0bb4c62225ebd5a31" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 44247 } }
I20260812 06:20:23.788110  5973 leader_election.cc:304] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [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: 9a83f989530745e0bb4c62225ebd5a31; no voters: 
I20260812 06:20:23.788415  5973 leader_election.cc:290] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:23.788550  5975 raft_consensus.cc:2804] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:23.788832  5975 raft_consensus.cc:697] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 1 LEADER]: Becoming Leader. State: Replica: 9a83f989530745e0bb4c62225ebd5a31, State: Running, Role: LEADER
I20260812 06:20:23.788887  5973 ts_tablet_manager.cc:1434] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Time spent starting tablet: real 0.004s	user 0.003s	sys 0.001s
I20260812 06:20:23.789136  5959 heartbeater.cc:499] Master 127.5.159.126:39187 was elected leader, sending a full tablet report...
I20260812 06:20:23.789109  5975 consensus_queue.cc:237] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [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: "9a83f989530745e0bb4c62225ebd5a31" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 44247 } }
I20260812 06:20:23.792992  5797 catalog_manager.cc:5719] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 reported cstate change: term changed from 0 to 1, leader changed from <none> to 9a83f989530745e0bb4c62225ebd5a31 (127.5.159.65). New cstate: current_term: 1 leader_uuid: "9a83f989530745e0bb4c62225ebd5a31" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a83f989530745e0bb4c62225ebd5a31" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 44247 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:23.872964  5757 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.072s	user 0.021s	sys 0.004s
I20260812 06:20:23.980549  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.125253
I20260812 06:20:24.119309  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.138s	user 0.118s	sys 0.020s Metrics: {"bytes_written":8861468,"cfile_init":1,"compiler_manager_pool.queue_time_us":73,"delete_count":0,"dirs.queue_time_us":67,"dirs.run_cpu_time_us":198,"dirs.run_wall_time_us":1164,"drs_written":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4,"lbm_write_time_us":28686,"lbm_writes_lt_1ms":473,"peak_mem_usage":0,"reinsert_count":0,"rows_written":102,"spinlock_wait_cycles":65792,"update_count":1080}
I20260812 06:20:24.120541  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling LogGCOp(69d65da8baad42c7bef8d24c27d3b30a): free 11976772 bytes of WAL
I20260812 06:20:24.120895  5888 log_reader.cc:385] T 69d65da8baad42c7bef8d24c27d3b30a: removed 1 log segments from log reader
I20260812 06:20:24.120986  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000001 (ops 1-6)
I20260812 06:20:24.123765  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: LogGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.003s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:20:24.124173  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:24.140724  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.016s	user 0.008s	sys 0.005s Metrics: {"bytes_written":3446255,"delete_count":0,"lbm_write_time_us":5600,"lbm_writes_lt_1ms":87,"reinsert_count":0,"update_count":420}
I20260812 06:20:24.141245  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:24.291162  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.150s	user 0.102s	sys 0.036s Metrics: {"cfile_cache_miss":332,"cfile_cache_miss_bytes":16487921,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":856,"lbm_read_time_us":7305,"lbm_reads_lt_1ms":368,"lbm_write_time_us":25595,"lbm_writes_lt_1ms":343,"peak_mem_usage":38262756,"reinsert_count":0,"thread_start_us":355,"threads_started":5,"update_count":1500}
I20260812 06:20:24.291774  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a): 8206537 bytes on disk
I20260812 06:20:24.292325  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4}
I20260812 06:20:24.292932  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:24.350826  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.058s	user 0.018s	sys 0.024s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18926,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:24.351496  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:24.366725  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.015s	user 0.015s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5869,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.367280  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:24.517210  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.150s	user 0.109s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590347,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":234,"lbm_read_time_us":10211,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25770,"lbm_writes_lt_1ms":443,"mutex_wait_us":77,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8960,"update_count":2000}
I20260812 06:20:24.518018  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:24.573714  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.055s	user 0.025s	sys 0.027s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":20884,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:24.574611  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:24.588711  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.014s	user 0.003s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5459,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.589424  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:24.774461  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.185s	user 0.124s	sys 0.056s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590348,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":412,"lbm_read_time_us":14887,"lbm_reads_lt_1ms":472,"lbm_write_time_us":30365,"lbm_writes_lt_1ms":443,"mutex_wait_us":1,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:20:24.775218  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:24.826040  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.050s	user 0.030s	sys 0.015s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":21580,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:24.826782  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:24.952005  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.125s	user 0.103s	sys 0.020s Metrics: {"cfile_cache_miss":331,"cfile_cache_miss_bytes":16487816,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":292,"lbm_read_time_us":7486,"lbm_reads_lt_1ms":363,"lbm_write_time_us":24567,"lbm_writes_lt_1ms":343,"mutex_wait_us":3,"peak_mem_usage":38262756,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":1500}
I20260812 06:20:24.952661  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:25.020632  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.068s	user 0.025s	sys 0.028s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":26993,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:20:25.021262  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:25.034210  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.012s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4269,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.035168  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:25.181778  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.146s	user 0.109s	sys 0.037s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590346,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":327,"lbm_read_time_us":10285,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28292,"lbm_writes_lt_1ms":443,"mutex_wait_us":66,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2432,"update_count":2000}
I20260812 06:20:25.182727  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:25.242784  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.060s	user 0.029s	sys 0.021s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17941,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:25.243690  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:25.255782  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.012s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4467,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.256424  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:25.415594  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.159s	user 0.095s	sys 0.064s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590347,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":981,"lbm_read_time_us":11975,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26171,"lbm_writes_lt_1ms":443,"mutex_wait_us":286,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":13056,"update_count":2000}
I20260812 06:20:25.416146  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:25.469239  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.052s	user 0.024s	sys 0.013s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16984,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:25.470028  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:25.493636  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.023s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4673,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.494227  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:25.635264  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.141s	user 0.105s	sys 0.035s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590347,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":342,"lbm_read_time_us":8946,"lbm_reads_lt_1ms":472,"lbm_write_time_us":30048,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2000}
I20260812 06:20:25.636142  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:25.679230  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.043s	user 0.027s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17133,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:25.680078  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:25.692749  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.012s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4742,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.693677  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:25.728372  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.034s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1234474,"cfile_init":1,"dirs.queue_time_us":73,"dirs.run_cpu_time_us":268,"dirs.run_wall_time_us":1841,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1922,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:20:25.729351  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling LogGCOp(69d65da8baad42c7bef8d24c27d3b30a): free 121006378 bytes of WAL
I20260812 06:20:25.729605  5888 log_reader.cc:385] T 69d65da8baad42c7bef8d24c27d3b30a: removed 12 log segments from log reader
I20260812 06:20:25.729652  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000002 (ops 7-11)
I20260812 06:20:25.729683  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000003 (ops 12-16)
I20260812 06:20:25.729761  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000004 (ops 17-21)
I20260812 06:20:25.729790  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000005 (ops 22-26)
I20260812 06:20:25.729831  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000006 (ops 27-31)
I20260812 06:20:25.729869  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000007 (ops 32-36)
I20260812 06:20:25.729928  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000008 (ops 37-41)
I20260812 06:20:25.729966  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000009 (ops 42-46)
I20260812 06:20:25.730028  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000010 (ops 47-51)
I20260812 06:20:25.730099  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000011 (ops 52-56)
I20260812 06:20:25.730141  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000012 (ops 57-60)
I20260812 06:20:25.730173  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000013 (ops 61-65)
I20260812 06:20:25.760463  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: LogGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.031s	user 0.000s	sys 0.029s Metrics: {}
I20260812 06:20:25.761086  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=3.181125
I20260812 06:20:25.781844  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.020s	user 0.008s	sys 0.008s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":7641,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:25.782358  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a): 473 bytes on disk
I20260812 06:20:25.782836  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4}
I20260812 06:20:25.783377  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:25.793844  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.010s	user 0.005s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3972,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:25.794378  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:25.997876  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.203s	user 0.166s	sys 0.027s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28795396,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":289,"lbm_read_time_us":12081,"lbm_reads_lt_1ms":674,"lbm_write_time_us":40567,"lbm_writes_lt_1ms":643,"mutex_wait_us":29,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":33792,"thread_start_us":86,"threads_started":1,"update_count":3000}
I20260812 06:20:25.998732  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=14.095187
I20260812 06:20:26.068455  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.070s	user 0.031s	sys 0.036s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":32026,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:20:26.069175  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:26.081604  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.012s	user 0.002s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4487,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.082446  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:26.249668  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.167s	user 0.129s	sys 0.036s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24692758,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":927,"lbm_read_time_us":10950,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32161,"lbm_writes_lt_1ms":543,"mutex_wait_us":217,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":14592,"update_count":2500}
I20260812 06:20:26.250221  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=11.118625
I20260812 06:20:26.290267  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.040s	user 0.024s	sys 0.012s Metrics: {"bytes_written":12717736,"delete_count":0,"lbm_write_time_us":17838,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:26.291077  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:26.310837  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.019s	user 0.019s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":7096,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:26.311576  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:26.489447  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.178s	user 0.119s	sys 0.052s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590339,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1263,"lbm_read_time_us":11839,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28503,"lbm_writes_lt_1ms":443,"mutex_wait_us":597,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":13184,"update_count":2000}
I20260812 06:20:26.490180  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=11.118625
I20260812 06:20:26.528908  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.039s	user 0.025s	sys 0.011s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":16341,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:26.529644  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:26.546057  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.016s	user 0.008s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5725,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:26.546552  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:26.690785  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.144s	user 0.104s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590338,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":143,"lbm_read_time_us":7614,"lbm_reads_lt_1ms":464,"lbm_write_time_us":28808,"lbm_writes_lt_1ms":443,"mutex_wait_us":30,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":27648,"update_count":2000}
I20260812 06:20:26.691396  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:26.731592  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.040s	user 0.031s	sys 0.008s Metrics: {"bytes_written":12307493,"delete_count":0,"lbm_write_time_us":17289,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:26.732203  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:26.745332  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.013s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4576,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.746702  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:26.899780  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.153s	user 0.111s	sys 0.041s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590350,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1118,"lbm_read_time_us":11330,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29418,"lbm_writes_lt_1ms":443,"mutex_wait_us":340,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":6272,"update_count":2000}
I20260812 06:20:26.900368  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:26.939566  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.039s	user 0.024s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16859,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:26.940112  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:27.056428  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.116s	user 0.084s	sys 0.031s Metrics: {"cfile_cache_miss":331,"cfile_cache_miss_bytes":16487816,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":134,"lbm_read_time_us":6834,"lbm_reads_lt_1ms":363,"lbm_write_time_us":22208,"lbm_writes_lt_1ms":343,"mutex_wait_us":43,"peak_mem_usage":38262756,"reinsert_count":0,"spinlock_wait_cycles":214784,"update_count":1500}
I20260812 06:20:27.057173  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:27.097179  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.040s	user 0.013s	sys 0.024s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":17301,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:27.097739  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:27.109977  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.012s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4679,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.110575  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:27.252826  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.142s	user 0.097s	sys 0.044s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590348,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":202,"lbm_read_time_us":9868,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29008,"lbm_writes_lt_1ms":443,"mutex_wait_us":638,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2000}
I20260812 06:20:27.254972  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:27.293053  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.038s	user 0.023s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15369,"lbm_writes_lt_1ms":303,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":1500}
I20260812 06:20:27.293664  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:27.307381  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.013s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4777,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.307950  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:27.342265  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.034s	user 0.028s	sys 0.004s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":125,"dirs.run_cpu_time_us":264,"dirs.run_wall_time_us":1887,"drs_written":1,"lbm_read_time_us":60,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2240,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30,"spinlock_wait_cycles":1408}
I20260812 06:20:27.343168  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling LogGCOp(69d65da8baad42c7bef8d24c27d3b30a): free 124257299 bytes of WAL
I20260812 06:20:27.343442  5888 log_reader.cc:385] T 69d65da8baad42c7bef8d24c27d3b30a: removed 12 log segments from log reader
I20260812 06:20:27.343504  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000014 (ops 66-70)
I20260812 06:20:27.343542  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000015 (ops 71-75)
I20260812 06:20:27.343570  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000016 (ops 76-80)
I20260812 06:20:27.343595  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000017 (ops 81-85)
I20260812 06:20:27.343617  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000018 (ops 86-90)
I20260812 06:20:27.343638  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000019 (ops 91-95)
I20260812 06:20:27.343660  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000020 (ops 96-100)
I20260812 06:20:27.343683  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000021 (ops 101-104)
I20260812 06:20:27.343704  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000022 (ops 105-109)
I20260812 06:20:27.343730  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000023 (ops 110-114)
I20260812 06:20:27.343766  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000024 (ops 115-119)
I20260812 06:20:27.343789  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000025 (ops 120-124)
I20260812 06:20:27.375192  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: LogGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.032s	user 0.000s	sys 0.031s Metrics: {}
I20260812 06:20:27.375800  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a): 472 bytes on disk
I20260812 06:20:27.376295  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":71,"lbm_reads_lt_1ms":4}
I20260812 06:20:27.377125  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:27.392630  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.015s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4585,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.393186  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:27.404722  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4240,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.405277  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:27.606060  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.201s	user 0.137s	sys 0.060s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28795408,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":658,"lbm_read_time_us":13121,"lbm_reads_lt_1ms":674,"lbm_write_time_us":41837,"lbm_writes_lt_1ms":643,"mutex_wait_us":310,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":11520,"thread_start_us":104,"threads_started":1,"update_count":3000}
I20260812 06:20:27.607275  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=14.095187
I20260812 06:20:27.659646  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.052s	user 0.029s	sys 0.021s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":23317,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:27.660384  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:27.674818  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.014s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5748,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.675308  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:27.848799  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.173s	user 0.129s	sys 0.040s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24692757,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":196,"lbm_read_time_us":10509,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33673,"lbm_writes_lt_1ms":543,"mutex_wait_us":60,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":18688,"update_count":2500}
I20260812 06:20:27.849892  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=11.118625
I20260812 06:20:27.883785  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.034s	user 0.022s	sys 0.011s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":14469,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:27.884609  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:27.900955  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.016s	user 0.009s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5744,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:27.901554  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:28.049381  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.147s	user 0.115s	sys 0.031s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590340,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":140,"lbm_read_time_us":7734,"lbm_reads_lt_1ms":464,"lbm_write_time_us":30061,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":442,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:20:28.050134  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:28.097808  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.047s	user 0.028s	sys 0.013s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":19059,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:28.098482  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:28.111054  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.012s	user 0.003s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4525,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.111981  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:28.255733  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.143s	user 0.136s	sys 0.007s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590347,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":69,"lbm_read_time_us":9987,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24842,"lbm_writes_lt_1ms":443,"mutex_wait_us":23,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":20096,"update_count":2000}
I20260812 06:20:28.256496  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:28.303084  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.046s	user 0.027s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15484,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:28.303764  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:28.317404  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.013s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4995,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.317939  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:28.468890  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.151s	user 0.098s	sys 0.052s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590347,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1000,"lbm_read_time_us":11620,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24538,"lbm_writes_lt_1ms":443,"mutex_wait_us":285,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8704,"update_count":2000}
I20260812 06:20:28.472129  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:28.518976  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.047s	user 0.030s	sys 0.007s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15940,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:28.519759  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:28.534835  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.015s	user 0.015s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5468,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.535766  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:28.680682  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.145s	user 0.128s	sys 0.016s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590347,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":472,"lbm_read_time_us":8838,"lbm_reads_lt_1ms":472,"lbm_write_time_us":30021,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2048,"update_count":2000}
I20260812 06:20:28.681322  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:28.732796  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.051s	user 0.021s	sys 0.028s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":23117,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:20:28.733378  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:28.748121  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.015s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5185,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.748795  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:28.885797  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.137s	user 0.101s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20590346,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":737,"lbm_read_time_us":10579,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27720,"lbm_writes_lt_1ms":443,"mutex_wait_us":44,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":2000}
I20260812 06:20:28.886520  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=10.126437
I20260812 06:20:28.945919  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.059s	user 0.035s	sys 0.016s Metrics: {"bytes_written":12307494,"delete_count":0,"lbm_write_time_us":21622,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:20:28.946784  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:28.960811  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.014s	user 0.003s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5032,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.961450  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:29.008976  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushMRSOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.047s	user 0.039s	sys 0.000s Metrics: {"bytes_written":1275447,"cfile_init":1,"dirs.queue_time_us":95,"dirs.run_cpu_time_us":287,"dirs.run_wall_time_us":2004,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1671,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:20:29.009805  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling LogGCOp(69d65da8baad42c7bef8d24c27d3b30a): free 133477630 bytes of WAL
I20260812 06:20:29.010064  5888 log_reader.cc:385] T 69d65da8baad42c7bef8d24c27d3b30a: removed 13 log segments from log reader
I20260812 06:20:29.010107  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000026 (ops 125-129)
I20260812 06:20:29.010145  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000027 (ops 130-134)
I20260812 06:20:29.010221  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000028 (ops 135-139)
I20260812 06:20:29.010250  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000029 (ops 140-144)
I20260812 06:20:29.010291  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000030 (ops 145-149)
I20260812 06:20:29.010330  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000031 (ops 150-154)
I20260812 06:20:29.010370  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000032 (ops 155-159)
I20260812 06:20:29.010414  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000033 (ops 160-164)
I20260812 06:20:29.010454  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000034 (ops 165-169)
I20260812 06:20:29.010495  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000035 (ops 170-174)
I20260812 06:20:29.010543  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000036 (ops 175-179)
I20260812 06:20:29.010586  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000037 (ops 180-184)
I20260812 06:20:29.010632  5888 log.cc:1079] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/69d65da8baad42c7bef8d24c27d3b30a/wal-000000038 (ops 185-189)
I20260812 06:20:29.039976  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: LogGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.030s	user 0.003s	sys 0.026s Metrics: {}
I20260812 06:20:29.040510  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a): 483 bytes on disk
I20260812 06:20:29.041002  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: UndoDeltaBlockGCOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":50,"lbm_reads_lt_1ms":4}
I20260812 06:20:29.041638  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:29.059176  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.017s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4464,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.059665  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=2.188937
I20260812 06:20:29.071774  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.012s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4517,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.072312  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:29.275321  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.203s	user 0.139s	sys 0.060s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28795413,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":3239,"lbm_read_time_us":14371,"lbm_reads_lt_1ms":674,"lbm_write_time_us":33280,"lbm_writes_lt_1ms":643,"mutex_wait_us":2435,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":15872,"thread_start_us":94,"threads_started":1,"update_count":3000}
I20260812 06:20:29.276050  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=14.095187
I20260812 06:20:29.331593  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: FlushDeltaMemStoresOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.055s	user 0.027s	sys 0.028s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":24214,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:29.332127  5960 maintenance_manager.cc:419] P 9a83f989530745e0bb4c62225ebd5a31: Scheduling MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a): perf score=1.000000
I20260812 06:20:29.348553  5757 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.475s	user 2.001s	sys 0.142s
I20260812 06:20:29.417259  5757 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.068s	user 0.003s	sys 0.000s
I20260812 06:20:29.418051  5757 tablet_server.cc:179] TabletServer@127.5.159.65:0 shutting down...
I20260812 06:20:29.473625  5888 maintenance_manager.cc:643] P 9a83f989530745e0bb4c62225ebd5a31: MajorDeltaCompactionOp(69d65da8baad42c7bef8d24c27d3b30a) complete. Timing: real 0.141s	user 0.081s	sys 0.059s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20590228,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":1974,"lbm_read_time_us":11036,"lbm_reads_lt_1ms":463,"lbm_write_time_us":25060,"lbm_writes_lt_1ms":443,"mutex_wait_us":439,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":12800,"update_count":2000}
I20260812 06:20:29.474413  5757 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:29.474870  5757 tablet_replica.cc:333] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31: stopping tablet replica
I20260812 06:20:29.475288  5757 raft_consensus.cc:2243] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:29.475695  5757 raft_consensus.cc:2272] T 69d65da8baad42c7bef8d24c27d3b30a P 9a83f989530745e0bb4c62225ebd5a31 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:29.495882  5757 tablet_server.cc:196] TabletServer@127.5.159.65:0 shutdown complete.
I20260812 06:20:29.514799  5757 master.cc:562] Master@127.5.159.126:39187 shutting down...
I20260812 06:20:29.519567  5757 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:29.519829  5757 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:29.519939  5757 tablet_replica.cc:333] T 00000000000000000000000000000000 P 51efded24c9a4f018dd381ba69a16dca: stopping tablet replica
I20260812 06:20:29.533113  5757 master.cc:584] Master@127.5.159.126:39187 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (6070 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:20:29.633252  5757 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.5.159.126:35609
I20260812 06:20:29.633723  5757 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:29.636134  5995 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:29.636341  5994 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:29.636538  5998 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:29.636584  5757 server_base.cc:1061] running on GCE node
I20260812 06:20:29.636812  5757 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:29.636859  5757 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:29.636876  5757 hybrid_clock.cc:648] HybridClock initialized: now 1786515629636876 us; error 0 us; skew 500 ppm
I20260812 06:20:29.638115  5757 webserver.cc:533] Webserver started at http://127.5.159.126:42991/ using document root <none> and password file <none>
I20260812 06:20:29.638365  5757 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:29.638466  5757 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:29.638592  5757 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:29.639106  5757 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/master-0-root/instance:
uuid: "fe4c67c7265b45e0b0818ae286f07d3a"
format_stamp: "Formatted at 2026-08-12 06:20:29 on dist-test-slave-csg5"
I20260812 06:20:29.641027  5757 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:20:29.642449  6004 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:29.642879  5757 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:29.643004  5757 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/master-0-root
uuid: "fe4c67c7265b45e0b0818ae286f07d3a"
format_stamp: "Formatted at 2026-08-12 06:20:29 on dist-test-slave-csg5"
I20260812 06:20:29.643126  5757 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:29.658116  5757 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:29.658627  5757 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:29.664024  5757 rpc_server.cc:307] RPC server started. Bound to: 127.5.159.126:35609
I20260812 06:20:29.664093  6066 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.159.126:35609 every 8 connection(s)
I20260812 06:20:29.665213  6067 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:29.667493  6067 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a: Bootstrap starting.
I20260812 06:20:29.668485  6067 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:29.669785  6067 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a: No bootstrap required, opened a new log
I20260812 06:20:29.670490  6067 raft_consensus.cc:359] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fe4c67c7265b45e0b0818ae286f07d3a" member_type: VOTER }
I20260812 06:20:29.670606  6067 raft_consensus.cc:385] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:29.670635  6067 raft_consensus.cc:740] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fe4c67c7265b45e0b0818ae286f07d3a, State: Initialized, Role: FOLLOWER
I20260812 06:20:29.670885  6067 consensus_queue.cc:260] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [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: "fe4c67c7265b45e0b0818ae286f07d3a" member_type: VOTER }
I20260812 06:20:29.670989  6067 raft_consensus.cc:399] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:29.671018  6067 raft_consensus.cc:493] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:29.671052  6067 raft_consensus.cc:3060] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:29.671918  6067 raft_consensus.cc:515] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fe4c67c7265b45e0b0818ae286f07d3a" member_type: VOTER }
I20260812 06:20:29.672066  6067 leader_election.cc:304] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [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: fe4c67c7265b45e0b0818ae286f07d3a; no voters: 
I20260812 06:20:29.672261  6067 leader_election.cc:290] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:29.672538  6070 raft_consensus.cc:2804] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:29.672781  6070 raft_consensus.cc:697] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 1 LEADER]: Becoming Leader. State: Replica: fe4c67c7265b45e0b0818ae286f07d3a, State: Running, Role: LEADER
I20260812 06:20:29.672869  6067 sys_catalog.cc:565] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:29.672942  6070 consensus_queue.cc:237] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [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: "fe4c67c7265b45e0b0818ae286f07d3a" member_type: VOTER }
I20260812 06:20:29.673477  6071 sys_catalog.cc:455] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "fe4c67c7265b45e0b0818ae286f07d3a" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fe4c67c7265b45e0b0818ae286f07d3a" member_type: VOTER } }
I20260812 06:20:29.673601  6071 sys_catalog.cc:458] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:29.673522  6072 sys_catalog.cc:455] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [sys.catalog]: SysCatalogTable state changed. Reason: New leader fe4c67c7265b45e0b0818ae286f07d3a. Latest consensus state: current_term: 1 leader_uuid: "fe4c67c7265b45e0b0818ae286f07d3a" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fe4c67c7265b45e0b0818ae286f07d3a" member_type: VOTER } }
I20260812 06:20:29.673674  6072 sys_catalog.cc:458] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:29.674073  6076 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:29.675033  6076 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:29.675451  5757 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:29.677564  6076 catalog_manager.cc:1383] Generated new cluster ID: 58f2fb5d02ce4966bc8b23549cac6ffd
I20260812 06:20:29.677639  6076 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:29.697378  6076 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:29.698232  6076 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:29.707422  6076 catalog_manager.cc:6092] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a: Generated new TSK 0
I20260812 06:20:29.707806  6076 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:29.740365  5757 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:29.743197  6091 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:29.743156  5757 server_base.cc:1061] running on GCE node
W20260812 06:20:29.743286  6090 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:20:29.743414  6093 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:20:29.743918  5757 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:29.743973  5757 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:20:29.743989  5757 hybrid_clock.cc:648] HybridClock initialized: now 1786515629743989 us; error 0 us; skew 500 ppm
I20260812 06:20:29.745172  5757 webserver.cc:533] Webserver started at http://127.5.159.65:45935/ using document root <none> and password file <none>
I20260812 06:20:29.745348  5757 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:29.745401  5757 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:29.745471  5757 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:29.745899  5757 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/instance:
uuid: "f0382ad86fe44d2188e0afc2f6a2bf87"
format_stamp: "Formatted at 2026-08-12 06:20:29 on dist-test-slave-csg5"
I20260812 06:20:29.747983  5757 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:20:29.749366  6099 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:29.749745  5757 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:29.749859  5757 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root
uuid: "f0382ad86fe44d2188e0afc2f6a2bf87"
format_stamp: "Formatted at 2026-08-12 06:20:29 on dist-test-slave-csg5"
I20260812 06:20:29.749989  5757 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:20:29.769579  5757 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:29.770099  5757 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:29.770466  5757 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:29.771086  5757 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:29.771155  5757 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:29.771251  5757 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:29.771306  5757 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:29.777443  5757 rpc_server.cc:307] RPC server started. Bound to: 127.5.159.65:41533
I20260812 06:20:29.777459  6170 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.159.65:41533 every 8 connection(s)
I20260812 06:20:29.789703  6172 heartbeater.cc:344] Connected to a master server at 127.5.159.126:35609
I20260812 06:20:29.789896  6172 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:29.790304  6172 heartbeater.cc:507] Master 127.5.159.126:35609 requested a full tablet report, sending...
I20260812 06:20:29.791333  6023 ts_manager.cc:194] Registered new tserver with Master: f0382ad86fe44d2188e0afc2f6a2bf87 (127.5.159.65:41533)
I20260812 06:20:29.791846  5757 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.01390824s
I20260812 06:20:29.792341  6023 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:33858
I20260812 06:20:29.801892  6023 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:33872:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:20:29.813019  6131 tablet_service.cc:1511] Processing CreateTablet for tablet 15e4291aa63e4493b3cd53ff8994bfdf (DEFAULT_TABLE table=heavy-update-compaction-test [id=53aa6fd3ed384bc0b933461702728469]), partition=
I20260812 06:20:29.813495  6131 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 15e4291aa63e4493b3cd53ff8994bfdf. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:29.816291  6187 tablet_bootstrap.cc:492] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Bootstrap starting.
I20260812 06:20:29.817282  6187 tablet_bootstrap.cc:654] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:29.819074  6187 tablet_bootstrap.cc:492] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: No bootstrap required, opened a new log
I20260812 06:20:29.819221  6187 ts_tablet_manager.cc:1403] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:20:29.819748  6187 raft_consensus.cc:359] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f0382ad86fe44d2188e0afc2f6a2bf87" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 41533 } }
I20260812 06:20:29.819893  6187 raft_consensus.cc:385] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:29.819947  6187 raft_consensus.cc:740] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: f0382ad86fe44d2188e0afc2f6a2bf87, State: Initialized, Role: FOLLOWER
I20260812 06:20:29.820114  6187 consensus_queue.cc:260] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [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: "f0382ad86fe44d2188e0afc2f6a2bf87" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 41533 } }
I20260812 06:20:29.820214  6187 raft_consensus.cc:399] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:29.820263  6187 raft_consensus.cc:493] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:29.820341  6187 raft_consensus.cc:3060] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:29.821266  6187 raft_consensus.cc:515] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f0382ad86fe44d2188e0afc2f6a2bf87" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 41533 } }
I20260812 06:20:29.821445  6187 leader_election.cc:304] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [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: f0382ad86fe44d2188e0afc2f6a2bf87; no voters: 
I20260812 06:20:29.821712  6187 leader_election.cc:290] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:29.821985  6189 raft_consensus.cc:2804] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:29.822120  6187 ts_tablet_manager.cc:1434] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:20:29.822161  6172 heartbeater.cc:499] Master 127.5.159.126:35609 was elected leader, sending a full tablet report...
I20260812 06:20:29.822180  6189 raft_consensus.cc:697] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 1 LEADER]: Becoming Leader. State: Replica: f0382ad86fe44d2188e0afc2f6a2bf87, State: Running, Role: LEADER
I20260812 06:20:29.822490  6189 consensus_queue.cc:237] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [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: "f0382ad86fe44d2188e0afc2f6a2bf87" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 41533 } }
I20260812 06:20:29.824107  6023 catalog_manager.cc:5719] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 reported cstate change: term changed from 0 to 1, leader changed from <none> to f0382ad86fe44d2188e0afc2f6a2bf87 (127.5.159.65). New cstate: current_term: 1 leader_uuid: "f0382ad86fe44d2188e0afc2f6a2bf87" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f0382ad86fe44d2188e0afc2f6a2bf87" member_type: VOTER last_known_addr { host: "127.5.159.65" port: 41533 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:29.889595  5757 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.060s	user 0.016s	sys 0.008s
I20260812 06:20:30.028517  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=15.086190
I20260812 06:20:30.183817  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.155s	user 0.111s	sys 0.041s Metrics: {"bytes_written":12307490,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":76,"dirs.run_cpu_time_us":208,"dirs.run_wall_time_us":1261,"drs_written":1,"lbm_read_time_us":56,"lbm_reads_lt_1ms":4,"lbm_write_time_us":42256,"lbm_writes_lt_1ms":657,"peak_mem_usage":0,"reinsert_count":0,"rows_written":103,"update_count":1500}
I20260812 06:20:30.184597  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf): free 8725963 bytes of WAL
I20260812 06:20:30.184890  6105 log_reader.cc:385] T 15e4291aa63e4493b3cd53ff8994bfdf: removed 1 log segments from log reader
I20260812 06:20:30.184940  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000001 (ops 1-6)
I20260812 06:20:30.187114  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.002s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:20:30.187515  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf): 12308958 bytes on disk
I20260812 06:20:30.188045  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":66,"lbm_reads_lt_1ms":4}
I20260812 06:20:30.188489  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:30.205754  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.017s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7019,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.206538  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:30.368417  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.162s	user 0.125s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":72,"lbm_read_time_us":11834,"lbm_reads_lt_1ms":464,"lbm_write_time_us":29838,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"thread_start_us":358,"threads_started":5,"update_count":2000}
I20260812 06:20:30.369050  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:30.404915  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.036s	user 0.032s	sys 0.001s Metrics: {"bytes_written":12307492,"delete_count":0,"lbm_write_time_us":16057,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:30.405463  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:30.418816  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.013s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4170,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.419270  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:30.569393  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.150s	user 0.117s	sys 0.032s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631314,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":363,"lbm_read_time_us":9693,"lbm_reads_lt_1ms":472,"lbm_write_time_us":31184,"lbm_writes_lt_1ms":443,"mutex_wait_us":44,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":44800,"update_count":2000}
I20260812 06:20:30.570173  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:30.628998  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.059s	user 0.023s	sys 0.025s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":22389,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:30.629693  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:30.644137  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.014s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4900,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.644865  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:30.785394  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.140s	user 0.122s	sys 0.016s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631311,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":472,"lbm_read_time_us":8800,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27595,"lbm_writes_lt_1ms":443,"mutex_wait_us":1,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8320,"update_count":2000}
I20260812 06:20:30.786139  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:30.839377  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.053s	user 0.016s	sys 0.031s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16022,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:30.840085  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:30.852154  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4479,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.852648  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:31.031032  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.178s	user 0.121s	sys 0.056s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631313,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":773,"lbm_read_time_us":12657,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29145,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":442,"mutex_wait_us":32,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8576,"update_count":2000}
I20260812 06:20:31.032500  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:31.087292  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.055s	user 0.018s	sys 0.036s Metrics: {"bytes_written":12307493,"delete_count":0,"lbm_write_time_us":27476,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:20:31.087906  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:31.103056  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.015s	user 0.015s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5426,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.103808  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:31.244652  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.141s	user 0.120s	sys 0.020s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631315,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1125,"lbm_read_time_us":10128,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26506,"lbm_writes_lt_1ms":443,"mutex_wait_us":649,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":10880,"update_count":2000}
I20260812 06:20:31.245613  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:31.294606  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.049s	user 0.032s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":20840,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:31.295511  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:31.308243  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.004s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4329,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.308773  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:31.448021  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.139s	user 0.103s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":412,"lbm_read_time_us":9440,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25857,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":25344,"update_count":2000}
I20260812 06:20:31.449020  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:31.498884  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.050s	user 0.027s	sys 0.020s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":20585,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:31.499471  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:31.510408  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4173,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.511107  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:31.543491  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.032s	user 0.028s	sys 0.003s Metrics: {"bytes_written":1152509,"cfile_init":1,"dirs.queue_time_us":116,"dirs.run_cpu_time_us":323,"dirs.run_wall_time_us":1943,"drs_written":1,"lbm_read_time_us":65,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2290,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:31.544164  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf): free 124257180 bytes of WAL
I20260812 06:20:31.544399  6105 log_reader.cc:385] T 15e4291aa63e4493b3cd53ff8994bfdf: removed 12 log segments from log reader
I20260812 06:20:31.544466  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000002 (ops 7-11)
I20260812 06:20:31.544517  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000003 (ops 12-16)
I20260812 06:20:31.544577  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000004 (ops 17-21)
I20260812 06:20:31.544617  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000005 (ops 22-26)
I20260812 06:20:31.544654  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000006 (ops 27-31)
I20260812 06:20:31.544695  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000007 (ops 32-36)
I20260812 06:20:31.544735  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000008 (ops 37-41)
I20260812 06:20:31.544775  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000009 (ops 42-46)
I20260812 06:20:31.544813  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000010 (ops 47-51)
I20260812 06:20:31.544854  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000011 (ops 52-56)
I20260812 06:20:31.544893  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000012 (ops 57-60)
I20260812 06:20:31.544932  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000013 (ops 61-65)
I20260812 06:20:31.569048  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.025s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:20:31.569449  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf): 447 bytes on disk
I20260812 06:20:31.569873  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":68,"lbm_reads_lt_1ms":4}
I20260812 06:20:31.570499  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:31.592093  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.021s	user 0.007s	sys 0.009s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6602,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.592567  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:31.605139  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4647,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.605708  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:31.803367  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.197s	user 0.150s	sys 0.041s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28836372,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":2143,"lbm_read_time_us":12952,"lbm_reads_lt_1ms":674,"lbm_write_time_us":37493,"lbm_writes_lt_1ms":643,"mutex_wait_us":667,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":40832,"thread_start_us":102,"threads_started":1,"update_count":3000}
I20260812 06:20:31.803954  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=14.095187
I20260812 06:20:31.861047  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.057s	user 0.026s	sys 0.028s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":25302,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:31.861575  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:31.875757  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.014s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4949,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.876531  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:32.046936  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.170s	user 0.118s	sys 0.049s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733721,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":937,"lbm_read_time_us":11746,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31602,"lbm_writes_lt_1ms":543,"mutex_wait_us":333,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":21376,"update_count":2500}
I20260812 06:20:32.047699  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=11.118625
I20260812 06:20:32.085407  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.037s	user 0.026s	sys 0.009s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":16470,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:32.085930  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:32.101974  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.016s	user 0.003s	sys 0.011s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6423,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:32.102494  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:32.274329  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.172s	user 0.127s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631305,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":289,"lbm_read_time_us":9667,"lbm_reads_lt_1ms":468,"lbm_write_time_us":29761,"lbm_writes_lt_1ms":443,"mutex_wait_us":27,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":73088,"update_count":2000}
I20260812 06:20:32.275068  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=11.118625
I20260812 06:20:32.314947  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.040s	user 0.031s	sys 0.008s Metrics: {"bytes_written":12717744,"delete_count":0,"lbm_write_time_us":16786,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:32.315690  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:32.337934  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.022s	user 0.012s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":7467,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:32.338557  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:32.493637  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.155s	user 0.116s	sys 0.037s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1350,"lbm_read_time_us":10810,"lbm_reads_lt_1ms":464,"lbm_write_time_us":27841,"lbm_writes_lt_1ms":443,"mutex_wait_us":310,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":5248,"update_count":2000}
I20260812 06:20:32.494225  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:32.532171  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.038s	user 0.029s	sys 0.007s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":16719,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:20:32.532780  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:32.545113  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4373,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.545869  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:32.678269  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.132s	user 0.103s	sys 0.029s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631310,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":452,"lbm_read_time_us":9683,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26082,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2000}
I20260812 06:20:32.679041  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:32.731520  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.052s	user 0.035s	sys 0.015s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":22573,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:32.732048  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:32.844540  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.112s	user 0.092s	sys 0.020s Metrics: {"cfile_cache_miss":331,"cfile_cache_miss_bytes":16528781,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":849,"lbm_read_time_us":7309,"lbm_reads_lt_1ms":363,"lbm_write_time_us":20571,"lbm_writes_lt_1ms":343,"mutex_wait_us":281,"peak_mem_usage":38262756,"reinsert_count":0,"spinlock_wait_cycles":14592,"update_count":1500}
I20260812 06:20:32.845209  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:32.890910  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.046s	user 0.028s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17244,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:20:32.891467  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:32.904908  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.013s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4361,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.905660  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:33.045938  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.140s	user 0.108s	sys 0.032s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631311,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":946,"lbm_read_time_us":8092,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26859,"lbm_writes_lt_1ms":443,"mutex_wait_us":271,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":3712,"update_count":2000}
I20260812 06:20:33.049489  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:33.092545  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.043s	user 0.022s	sys 0.012s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":15145,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:33.093225  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:33.107115  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.014s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5153,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.107910  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:33.135835  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.028s	user 0.022s	sys 0.004s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":84,"dirs.run_cpu_time_us":346,"dirs.run_wall_time_us":1664,"drs_written":1,"lbm_read_time_us":71,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1658,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:20:33.136725  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf): free 120553433 bytes of WAL
I20260812 06:20:33.136994  6105 log_reader.cc:385] T 15e4291aa63e4493b3cd53ff8994bfdf: removed 12 log segments from log reader
I20260812 06:20:33.137046  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000014 (ops 66-70)
I20260812 06:20:33.137089  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000015 (ops 71-75)
I20260812 06:20:33.137123  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000016 (ops 76-80)
I20260812 06:20:33.137154  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000017 (ops 81-84)
I20260812 06:20:33.137185  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000018 (ops 85-89)
I20260812 06:20:33.137216  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000019 (ops 90-94)
I20260812 06:20:33.137251  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000020 (ops 95-99)
I20260812 06:20:33.137284  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000021 (ops 100-104)
I20260812 06:20:33.137315  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000022 (ops 105-108)
I20260812 06:20:33.137343  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000023 (ops 109-113)
I20260812 06:20:33.137373  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000024 (ops 114-118)
I20260812 06:20:33.137405  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000025 (ops 119-123)
I20260812 06:20:33.167109  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.030s	user 0.000s	sys 0.030s Metrics: {}
I20260812 06:20:33.167555  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf): 473 bytes on disk
I20260812 06:20:33.168247  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":58,"lbm_reads_lt_1ms":4}
I20260812 06:20:33.168849  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:33.192288  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.023s	user 0.001s	sys 0.012s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5617,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.192852  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:33.204316  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4308,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.204905  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:33.406978  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.202s	user 0.132s	sys 0.063s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28836374,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":529,"lbm_read_time_us":13064,"lbm_reads_lt_1ms":674,"lbm_write_time_us":40179,"lbm_writes_lt_1ms":643,"mutex_wait_us":43,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":6528,"thread_start_us":105,"threads_started":1,"update_count":3000}
I20260812 06:20:33.408525  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=14.095187
I20260812 06:20:33.472556  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.064s	user 0.026s	sys 0.036s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":32110,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":400,"reinsert_count":0,"update_count":2000}
I20260812 06:20:33.473277  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:33.488076  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.014s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5308,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.488760  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:33.654992  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.166s	user 0.122s	sys 0.043s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733722,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":332,"lbm_read_time_us":12207,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33389,"lbm_writes_lt_1ms":543,"mutex_wait_us":52,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":18944,"update_count":2500}
I20260812 06:20:33.656247  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=11.118625
I20260812 06:20:33.689523  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.033s	user 0.010s	sys 0.020s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":14392,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:20:33.690181  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:33.703881  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.013s	user 0.004s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5330,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:33.704422  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:33.836100  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.131s	user 0.095s	sys 0.035s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631303,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1952,"lbm_read_time_us":9224,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27152,"lbm_writes_lt_1ms":443,"mutex_wait_us":775,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":4096,"update_count":2000}
I20260812 06:20:33.836959  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:33.883708  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.047s	user 0.024s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16182,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:33.884332  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:33.898173  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.014s	user 0.008s	sys 0.002s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4516,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:33.898909  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:34.030035  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.131s	user 0.094s	sys 0.037s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":258,"lbm_read_time_us":8769,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26644,"lbm_writes_lt_1ms":443,"mutex_wait_us":31,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":84864,"update_count":2000}
I20260812 06:20:34.032878  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:34.091507  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.058s	user 0.026s	sys 0.027s Metrics: {"bytes_written":12307492,"delete_count":0,"lbm_write_time_us":19271,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:34.092196  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:34.104665  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.003s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4884,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:34.105454  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:34.275171  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.169s	user 0.133s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631314,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":808,"lbm_read_time_us":12912,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29155,"lbm_writes_lt_1ms":443,"mutex_wait_us":256,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":17792,"update_count":2000}
I20260812 06:20:34.276094  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:34.316560  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.040s	user 0.021s	sys 0.016s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16575,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:34.317286  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:34.333206  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.016s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5909,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:34.333956  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:34.463850  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.130s	user 0.094s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631313,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1100,"lbm_read_time_us":10276,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25383,"lbm_writes_lt_1ms":443,"mutex_wait_us":282,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":14592,"update_count":2000}
I20260812 06:20:34.464613  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:34.511812  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.047s	user 0.023s	sys 0.016s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":17097,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:34.512375  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:34.523742  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.011s	user 0.009s	sys 0.001s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4375,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:34.524436  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:34.659404  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.135s	user 0.115s	sys 0.018s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":968,"lbm_read_time_us":9020,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27404,"lbm_writes_lt_1ms":443,"mutex_wait_us":74,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2688,"update_count":2000}
I20260812 06:20:34.660326  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=10.126437
I20260812 06:20:34.720621  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.060s	user 0.017s	sys 0.033s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":19811,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:34.721309  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:34.733206  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4406,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:34.733888  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:34.780174  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushMRSOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.046s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1275445,"cfile_init":1,"dirs.queue_time_us":140,"dirs.run_cpu_time_us":342,"dirs.run_wall_time_us":2021,"drs_written":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1982,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:20:34.780964  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf): free 129320759 bytes of WAL
I20260812 06:20:34.781214  6105 log_reader.cc:385] T 15e4291aa63e4493b3cd53ff8994bfdf: removed 13 log segments from log reader
I20260812 06:20:34.781258  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000026 (ops 124-128)
I20260812 06:20:34.781291  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000027 (ops 129-133)
I20260812 06:20:34.781358  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000028 (ops 134-138)
I20260812 06:20:34.781389  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000029 (ops 139-143)
I20260812 06:20:34.781430  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000030 (ops 144-148)
I20260812 06:20:34.781466  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000031 (ops 149-153)
I20260812 06:20:34.781522  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000032 (ops 154-158)
I20260812 06:20:34.781559  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000033 (ops 159-162)
I20260812 06:20:34.781634  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000034 (ops 163-167)
I20260812 06:20:34.781701  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000035 (ops 168-172)
I20260812 06:20:34.781744  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000036 (ops 173-176)
I20260812 06:20:34.781785  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000037 (ops 177-181)
I20260812 06:20:34.781818  6105 log.cc:1079] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: Deleting log segment in path: /tmp/dist-test-taskyiSBIa/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515623536144-5757-0/minicluster-data/ts-0-root/wals/15e4291aa63e4493b3cd53ff8994bfdf/wal-000000038 (ops 182-186)
I20260812 06:20:34.809787  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: LogGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.029s	user 0.000s	sys 0.026s Metrics: {}
I20260812 06:20:34.810228  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf): 482 bytes on disk
I20260812 06:20:34.810775  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: UndoDeltaBlockGCOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":56,"lbm_reads_lt_1ms":4}
I20260812 06:20:34.811334  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:34.831936  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.020s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4443,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:34.832504  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:34.844655  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.003s	sys 0.007s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4816,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:34.845180  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:35.057744  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.212s	user 0.147s	sys 0.064s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28836374,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":2095,"lbm_read_time_us":13882,"lbm_reads_lt_1ms":674,"lbm_write_time_us":34108,"lbm_writes_lt_1ms":643,"mutex_wait_us":1108,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3200,"thread_start_us":96,"threads_started":1,"update_count":3000}
I20260812 06:20:35.058990  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=14.095187
I20260812 06:20:35.126605  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.067s	user 0.042s	sys 0.008s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":23833,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:35.127447  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=2.188937
I20260812 06:20:35.139636  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: FlushDeltaMemStoresOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.012s	user 0.005s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4596,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:35.140214  6173 maintenance_manager.cc:419] P f0382ad86fe44d2188e0afc2f6a2bf87: Scheduling MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf): perf score=1.000000
I20260812 06:20:35.174466  5757 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.285s	user 1.952s	sys 0.143s
I20260812 06:20:35.240592  5757 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.066s	user 0.001s	sys 0.000s
I20260812 06:20:35.241174  5757 tablet_server.cc:179] TabletServer@127.5.159.65:0 shutting down...
I20260812 06:20:35.299611  6105 maintenance_manager.cc:643] P f0382ad86fe44d2188e0afc2f6a2bf87: MajorDeltaCompactionOp(15e4291aa63e4493b3cd53ff8994bfdf) complete. Timing: real 0.159s	user 0.121s	sys 0.037s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733722,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1025,"lbm_read_time_us":12981,"lbm_reads_lt_1ms":568,"lbm_write_time_us":28938,"lbm_writes_lt_1ms":543,"mutex_wait_us":269,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5632,"update_count":2500}
I20260812 06:20:35.300311  5757 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:35.300657  5757 tablet_replica.cc:333] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87: stopping tablet replica
I20260812 06:20:35.300817  5757 raft_consensus.cc:2243] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:35.301014  5757 raft_consensus.cc:2272] T 15e4291aa63e4493b3cd53ff8994bfdf P f0382ad86fe44d2188e0afc2f6a2bf87 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:35.318035  5757 tablet_server.cc:196] TabletServer@127.5.159.65:0 shutdown complete.
I20260812 06:20:35.346626  5757 master.cc:562] Master@127.5.159.126:35609 shutting down...
I20260812 06:20:35.351095  5757 raft_consensus.cc:2243] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:35.351312  5757 raft_consensus.cc:2272] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:35.351372  5757 tablet_replica.cc:333] T 00000000000000000000000000000000 P fe4c67c7265b45e0b0818ae286f07d3a: stopping tablet replica
I20260812 06:20:35.365020  5757 master.cc:584] Master@127.5.159.126:35609 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5840 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (11911 ms total)

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