[==========] 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:16:56.620096 30033 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.29.84.126:39055
I20260812 06:16:56.620980 30033 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:16:56.621546 30033 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:16:56.627156 30042 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:16:56.627262 30048 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:16:56.627336 30033 server_base.cc:1061] running on GCE node
W20260812 06:16:56.627451 30043 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:16:56.627885 30033 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:16:56.627969 30033 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:16:56.627997 30033 hybrid_clock.cc:648] HybridClock initialized: now 1786515416627996 us; error 0 us; skew 500 ppm
I20260812 06:16:56.629555 30033 webserver.cc:533] Webserver started at http://127.29.84.126:45067/ using document root <none> and password file <none>
I20260812 06:16:56.629997 30033 fs_manager.cc:362] Metadata directory not provided
I20260812 06:16:56.630046 30033 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:16:56.630218 30033 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:16:56.631671 30033 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/master-0-root/instance:
uuid: "a4810ffacfe14915addb5400d03ee6b5"
format_stamp: "Formatted at 2026-08-12 06:16:56 on dist-test-slave-bndk"
I20260812 06:16:56.634703 30033 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:16:56.636436 30055 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:16:56.637348 30033 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:16:56.637435 30033 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/master-0-root
uuid: "a4810ffacfe14915addb5400d03ee6b5"
format_stamp: "Formatted at 2026-08-12 06:16:56 on dist-test-slave-bndk"
I20260812 06:16:56.637513 30033 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-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:16:56.650414 30033 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:16:56.650892 30033 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:16:56.651003 30033 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:16:56.657816 30033 rpc_server.cc:307] RPC server started. Bound to: 127.29.84.126:39055
I20260812 06:16:56.657817 30145 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.84.126:39055 every 8 connection(s)
I20260812 06:16:56.659813 30147 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:16:56.665122 30147 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5: Bootstrap starting.
I20260812 06:16:56.667269 30147 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5: Neither blocks nor log segments found. Creating new log.
I20260812 06:16:56.668087 30147 log.cc:826] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5: Log is configured to *not* fsync() on all Append() calls
I20260812 06:16:56.669574 30147 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5: No bootstrap required, opened a new log
I20260812 06:16:56.672773 30147 raft_consensus.cc:359] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a4810ffacfe14915addb5400d03ee6b5" member_type: VOTER }
I20260812 06:16:56.672950 30147 raft_consensus.cc:385] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:16:56.673010 30147 raft_consensus.cc:740] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a4810ffacfe14915addb5400d03ee6b5, State: Initialized, Role: FOLLOWER
I20260812 06:16:56.673659 30147 consensus_queue.cc:260] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [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: "a4810ffacfe14915addb5400d03ee6b5" member_type: VOTER }
I20260812 06:16:56.673822 30147 raft_consensus.cc:399] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:16:56.673877 30147 raft_consensus.cc:493] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:16:56.673977 30147 raft_consensus.cc:3060] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:16:56.674811 30147 raft_consensus.cc:515] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a4810ffacfe14915addb5400d03ee6b5" member_type: VOTER }
I20260812 06:16:56.675509 30147 leader_election.cc:304] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [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: a4810ffacfe14915addb5400d03ee6b5; no voters: 
I20260812 06:16:56.675822 30147 leader_election.cc:290] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:16:56.675921 30154 raft_consensus.cc:2804] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:16:56.676129 30154 raft_consensus.cc:697] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 1 LEADER]: Becoming Leader. State: Replica: a4810ffacfe14915addb5400d03ee6b5, State: Running, Role: LEADER
I20260812 06:16:56.676518 30154 consensus_queue.cc:237] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [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: "a4810ffacfe14915addb5400d03ee6b5" member_type: VOTER }
I20260812 06:16:56.676717 30147 sys_catalog.cc:565] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:16:56.678228 30159 sys_catalog.cc:455] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [sys.catalog]: SysCatalogTable state changed. Reason: New leader a4810ffacfe14915addb5400d03ee6b5. Latest consensus state: current_term: 1 leader_uuid: "a4810ffacfe14915addb5400d03ee6b5" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a4810ffacfe14915addb5400d03ee6b5" member_type: VOTER } }
I20260812 06:16:56.678262 30155 sys_catalog.cc:455] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "a4810ffacfe14915addb5400d03ee6b5" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a4810ffacfe14915addb5400d03ee6b5" member_type: VOTER } }
I20260812 06:16:56.678339 30159 sys_catalog.cc:458] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [sys.catalog]: This master's current role is: LEADER
I20260812 06:16:56.678349 30155 sys_catalog.cc:458] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [sys.catalog]: This master's current role is: LEADER
I20260812 06:16:56.678669 30173 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:16:56.678960 30033 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:16:56.680778 30173 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:16:56.685002 30173 catalog_manager.cc:1383] Generated new cluster ID: 8a6f11c9819f4ea9bb4271bdef2972f9
I20260812 06:16:56.685060 30173 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:16:56.707643 30173 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:16:56.708669 30173 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:16:56.719184 30173 catalog_manager.cc:6092] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5: Generated new TSK 0
I20260812 06:16:56.719822 30173 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:16:56.743845 30033 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:16:56.746317 30191 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:16:56.746435 30195 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:16:56.746551 30033 server_base.cc:1061] running on GCE node
W20260812 06:16:56.746636 30192 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:16:56.746829 30033 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:16:56.746886 30033 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:16:56.746906 30033 hybrid_clock.cc:648] HybridClock initialized: now 1786515416746906 us; error 0 us; skew 500 ppm
I20260812 06:16:56.747787 30033 webserver.cc:533] Webserver started at http://127.29.84.65:33599/ using document root <none> and password file <none>
I20260812 06:16:56.747957 30033 fs_manager.cc:362] Metadata directory not provided
I20260812 06:16:56.748013 30033 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:16:56.748081 30033 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:16:56.748484 30033 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/instance:
uuid: "11ad92ced076460996bc98e6e2379592"
format_stamp: "Formatted at 2026-08-12 06:16:56 on dist-test-slave-bndk"
I20260812 06:16:56.750234 30033 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:16:56.751236 30202 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:16:56.751508 30033 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:16:56.751582 30033 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root
uuid: "11ad92ced076460996bc98e6e2379592"
format_stamp: "Formatted at 2026-08-12 06:16:56 on dist-test-slave-bndk"
I20260812 06:16:56.751644 30033 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-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:16:56.771397 30033 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:16:56.771842 30033 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:16:56.772351 30033 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:16:56.773365 30033 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:16:56.773429 30033 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:16:56.773487 30033 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:16:56.773517 30033 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:16:56.780210 30033 rpc_server.cc:307] RPC server started. Bound to: 127.29.84.65:43367
I20260812 06:16:56.780231 30310 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.84.65:43367 every 8 connection(s)
I20260812 06:16:56.789242 30312 heartbeater.cc:344] Connected to a master server at 127.29.84.126:39055
I20260812 06:16:56.789446 30312 heartbeater.cc:461] Registering TS with master...
I20260812 06:16:56.789839 30312 heartbeater.cc:507] Master 127.29.84.126:39055 requested a full tablet report, sending...
I20260812 06:16:56.791193 30083 ts_manager.cc:194] Registered new tserver with Master: 11ad92ced076460996bc98e6e2379592 (127.29.84.65:43367)
I20260812 06:16:56.791946 30033 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.011093759s
I20260812 06:16:56.792577 30083 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:52208
I20260812 06:16:56.800655 30083 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:52216:
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:16:56.814335 30253 tablet_service.cc:1511] Processing CreateTablet for tablet 5deea1542701472abfa6358b7cf5f074 (DEFAULT_TABLE table=heavy-update-compaction-test [id=a93d34b171a443ef83a8a1db4525b60a]), partition=
I20260812 06:16:56.814749 30253 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 5deea1542701472abfa6358b7cf5f074. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:16:56.816857 30329 tablet_bootstrap.cc:492] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Bootstrap starting.
I20260812 06:16:56.818032 30329 tablet_bootstrap.cc:654] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Neither blocks nor log segments found. Creating new log.
I20260812 06:16:56.819118 30329 tablet_bootstrap.cc:492] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: No bootstrap required, opened a new log
I20260812 06:16:56.819197 30329 ts_tablet_manager.cc:1403] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:16:56.819577 30329 raft_consensus.cc:359] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "11ad92ced076460996bc98e6e2379592" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 43367 } }
I20260812 06:16:56.819665 30329 raft_consensus.cc:385] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:16:56.819692 30329 raft_consensus.cc:740] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 11ad92ced076460996bc98e6e2379592, State: Initialized, Role: FOLLOWER
I20260812 06:16:56.819814 30329 consensus_queue.cc:260] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [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: "11ad92ced076460996bc98e6e2379592" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 43367 } }
I20260812 06:16:56.819891 30329 raft_consensus.cc:399] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:16:56.819916 30329 raft_consensus.cc:493] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:16:56.819962 30329 raft_consensus.cc:3060] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:16:56.871238 30329 raft_consensus.cc:515] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "11ad92ced076460996bc98e6e2379592" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 43367 } }
I20260812 06:16:56.871460 30329 leader_election.cc:304] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [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: 11ad92ced076460996bc98e6e2379592; no voters: 
I20260812 06:16:56.871697 30329 leader_election.cc:290] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:16:56.871829 30331 raft_consensus.cc:2804] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:16:56.872033 30329 ts_tablet_manager.cc:1434] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Time spent starting tablet: real 0.053s	user 0.003s	sys 0.000s
I20260812 06:16:56.872069 30331 raft_consensus.cc:697] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 1 LEADER]: Becoming Leader. State: Replica: 11ad92ced076460996bc98e6e2379592, State: Running, Role: LEADER
I20260812 06:16:56.872421 30312 heartbeater.cc:499] Master 127.29.84.126:39055 was elected leader, sending a full tablet report...
I20260812 06:16:56.872417 30331 consensus_queue.cc:237] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [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: "11ad92ced076460996bc98e6e2379592" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 43367 } }
I20260812 06:16:56.874982 30083 catalog_manager.cc:5719] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 reported cstate change: term changed from 0 to 1, leader changed from <none> to 11ad92ced076460996bc98e6e2379592 (127.29.84.65). New cstate: current_term: 1 leader_uuid: "11ad92ced076460996bc98e6e2379592" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "11ad92ced076460996bc98e6e2379592" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 43367 } health_report { overall_health: HEALTHY } } }
I20260812 06:16:56.937956 30033 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.050s	user 0.012s	sys 0.010s
I20260812 06:16:57.031219 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushMRSOp(5deea1542701472abfa6358b7cf5f074): perf score=15.086190
I20260812 06:16:57.176885 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushMRSOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.145s	user 0.093s	sys 0.032s Metrics: {"bytes_written":8205079,"cfile_init":1,"compiler_manager_pool.queue_time_us":205,"delete_count":0,"dirs.queue_time_us":63,"dirs.run_cpu_time_us":180,"dirs.run_wall_time_us":51783,"drs_written":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4,"lbm_write_time_us":29625,"lbm_writes_lt_1ms":557,"peak_mem_usage":0,"reinsert_count":0,"rows_written":103,"thread_start_us":122,"threads_started":1,"update_count":1000}
I20260812 06:16:57.178092 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling LogGCOp(5deea1542701472abfa6358b7cf5f074): free 8725963 bytes of WAL
I20260812 06:16:57.178408 30207 log_reader.cc:385] T 5deea1542701472abfa6358b7cf5f074: removed 1 log segments from log reader
I20260812 06:16:57.178475 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000001 (ops 1-6)
I20260812 06:16:57.180819 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: LogGCOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.003s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:16:57.181205 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling UndoDeltaBlockGCOp(5deea1542701472abfa6358b7cf5f074): 12308960 bytes on disk
I20260812 06:16:57.182019 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: UndoDeltaBlockGCOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":85,"lbm_reads_lt_1ms":4}
I20260812 06:16:57.182451 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:16:57.279114 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.096s	user 0.006s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5912,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:57.279810 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=7.149875
I20260812 06:16:57.384094 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.104s	user 0.019s	sys 0.000s Metrics: {"bytes_written":9271709,"delete_count":0,"lbm_write_time_us":8593,"lbm_writes_lt_1ms":229,"reinsert_count":0,"update_count":1130}
I20260812 06:16:57.384665 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=9.134250
I20260812 06:16:57.484751 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.100s	user 0.020s	sys 0.012s Metrics: {"bytes_written":11240865,"delete_count":0,"lbm_write_time_us":14146,"lbm_writes_lt_1ms":277,"reinsert_count":0,"update_count":1370}
I20260812 06:16:57.485270 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=7.149875
I20260812 06:16:57.586503 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.101s	user 0.014s	sys 0.004s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":7526,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:16:57.586987 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=10.126437
I20260812 06:16:57.688875 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.102s	user 0.024s	sys 0.003s Metrics: {"bytes_written":11897249,"delete_count":0,"lbm_write_time_us":12271,"lbm_writes_lt_1ms":293,"reinsert_count":0,"update_count":1450}
I20260812 06:16:57.689419 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=10.126437
I20260812 06:16:57.792332 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.103s	user 0.021s	sys 0.008s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":13492,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:16:57.792817 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=7.149875
I20260812 06:16:57.894301 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.101s	user 0.024s	sys 0.003s Metrics: {"bytes_written":8615322,"delete_count":0,"lbm_write_time_us":11721,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:16:57.894879 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=10.126437
I20260812 06:16:57.995476 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.100s	user 0.014s	sys 0.012s Metrics: {"bytes_written":11897250,"delete_count":0,"lbm_write_time_us":11236,"lbm_writes_lt_1ms":293,"reinsert_count":0,"update_count":1450}
I20260812 06:16:57.996224 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=7.149875
I20260812 06:16:58.082386 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.086s	user 0.017s	sys 0.008s Metrics: {"bytes_written":8410201,"delete_count":0,"lbm_write_time_us":10671,"lbm_writes_lt_1ms":208,"reinsert_count":0,"update_count":1025}
I20260812 06:16:58.082988 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=7.149875
I20260812 06:16:58.106482 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.023s	user 0.010s	sys 0.012s Metrics: {"bytes_written":8902497,"delete_count":0,"lbm_write_time_us":9691,"lbm_writes_lt_1ms":220,"reinsert_count":0,"update_count":1085}
I20260812 06:16:58.106995 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:16:58.119695 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.013s	user 0.004s	sys 0.003s Metrics: {"bytes_written":3200105,"delete_count":0,"lbm_write_time_us":3120,"lbm_writes_lt_1ms":81,"reinsert_count":0,"update_count":390}
I20260812 06:16:58.120169 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074): perf score=1.000000
I20260812 06:16:58.737574 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.617s	user 0.389s	sys 0.225s Metrics: {"cfile_cache_miss":2642,"cfile_cache_miss_bytes":110885577,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":12,"delta_iterators_relevant":12,"dirs.queue_time_us":1044,"lbm_read_time_us":41994,"lbm_reads_lt_1ms":2670,"lbm_write_time_us":113426,"lbm_writes_lt_1ms":2645,"mutex_wait_us":1,"peak_mem_usage":324073912,"reinsert_count":0,"spinlock_wait_cycles":896,"thread_start_us":384,"threads_started":6,"update_count":13000}
I20260812 06:16:58.738175 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=46.837375
I20260812 06:16:58.998183 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.260s	user 0.050s	sys 0.068s Metrics: {"bytes_written":49639444,"delete_count":0,"lbm_write_time_us":54292,"lbm_writes_lt_1ms":1213,"mutex_wait_us":3,"reinsert_count":0,"update_count":6050}
I20260812 06:16:58.998767 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=22.032687
I20260812 06:16:59.058784 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.060s	user 0.046s	sys 0.013s Metrics: {"bytes_written":24204482,"delete_count":0,"lbm_write_time_us":25878,"lbm_writes_lt_1ms":593,"reinsert_count":0,"update_count":2950}
I20260812 06:16:59.059218 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=3.181125
I20260812 06:16:59.098387 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.039s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4512904,"delete_count":0,"lbm_write_time_us":3898,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:16:59.098932 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:16:59.115881 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.017s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4728,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:59.116400 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:16:59.125514 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.009s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3461,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:16:59.126001 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushMRSOp(5deea1542701472abfa6358b7cf5f074): perf score=1.000000
I20260812 06:16:59.163239 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushMRSOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.037s	user 0.036s	sys 0.000s Metrics: {"bytes_written":1931217,"cfile_init":1,"dirs.queue_time_us":51,"dirs.run_cpu_time_us":232,"dirs.run_wall_time_us":1196,"drs_written":1,"lbm_read_time_us":35,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2704,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":47}
I20260812 06:16:59.163992 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling LogGCOp(5deea1542701472abfa6358b7cf5f074): free 199083391 bytes of WAL
I20260812 06:16:59.164211 30207 log_reader.cc:385] T 5deea1542701472abfa6358b7cf5f074: removed 19 log segments from log reader
I20260812 06:16:59.164256 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000002 (ops 7-11)
I20260812 06:16:59.164285 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000003 (ops 12-16)
I20260812 06:16:59.164315 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000004 (ops 17-21)
I20260812 06:16:59.164347 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000005 (ops 22-26)
I20260812 06:16:59.164381 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000006 (ops 27-31)
I20260812 06:16:59.164412 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000007 (ops 32-36)
I20260812 06:16:59.164451 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000008 (ops 37-41)
I20260812 06:16:59.164484 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000009 (ops 42-46)
I20260812 06:16:59.164516 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000010 (ops 47-51)
I20260812 06:16:59.164547 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000011 (ops 52-56)
I20260812 06:16:59.164579 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000012 (ops 57-61)
I20260812 06:16:59.164610 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000013 (ops 62-66)
I20260812 06:16:59.164642 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000014 (ops 67-71)
I20260812 06:16:59.164673 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000015 (ops 72-76)
I20260812 06:16:59.164705 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000016 (ops 77-81)
I20260812 06:16:59.164737 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000017 (ops 82-86)
I20260812 06:16:59.164769 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000018 (ops 87-91)
I20260812 06:16:59.164800 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000019 (ops 92-96)
I20260812 06:16:59.164831 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000020 (ops 97-101)
I20260812 06:16:59.202145 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: LogGCOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.038s	user 0.000s	sys 0.037s Metrics: {}
I20260812 06:16:59.202561 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling UndoDeltaBlockGCOp(5deea1542701472abfa6358b7cf5f074): 663 bytes on disk
I20260812 06:16:59.203015 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: UndoDeltaBlockGCOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":63,"lbm_reads_lt_1ms":4}
I20260812 06:16:59.203465 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=6.157687
I20260812 06:16:59.231886 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.028s	user 0.010s	sys 0.012s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10433,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:16:59.232357 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074): perf score=1.000000
I20260812 06:16:59.808414 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.576s	user 0.369s	sys 0.196s Metrics: {"cfile_cache_miss":2336,"cfile_cache_miss_bytes":98577611,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":6,"delta_iterators_relevant":6,"dirs.queue_time_us":768,"lbm_read_time_us":38962,"lbm_reads_lt_1ms":2368,"lbm_write_time_us":106100,"lbm_writes_lt_1ms":2345,"peak_mem_usage":286794196,"reinsert_count":0,"spinlock_wait_cycles":4096,"thread_start_us":437,"threads_started":7,"update_count":11500}
I20260812 06:16:59.808951 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=45.845187
I20260812 06:16:59.974624 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.165s	user 0.090s	sys 0.051s Metrics: {"bytes_written":49229210,"delete_count":0,"lbm_write_time_us":54860,"lbm_writes_lt_1ms":1203,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":6000}
I20260812 06:16:59.975224 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=10.126437
I20260812 06:17:00.121562 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.146s	user 0.028s	sys 0.000s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":12341,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:00.122047 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=11.118625
I20260812 06:17:00.158769 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.037s	user 0.020s	sys 0.011s Metrics: {"bytes_written":13374126,"delete_count":0,"lbm_write_time_us":14528,"lbm_writes_lt_1ms":329,"reinsert_count":0,"update_count":1630}
I20260812 06:17:00.159219 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:17:00.169036 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.010s	user 0.007s	sys 0.000s Metrics: {"bytes_written":3446259,"delete_count":0,"lbm_write_time_us":2863,"lbm_writes_lt_1ms":87,"reinsert_count":0,"update_count":420}
I20260812 06:17:00.169456 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:17:00.177773 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.008s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3082,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:00.178198 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074): perf score=1.000000
I20260812 06:17:00.665966 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.488s	user 0.323s	sys 0.162s Metrics: {"cfile_cache_miss":2035,"cfile_cache_miss_bytes":86270258,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":636,"lbm_read_time_us":35067,"lbm_reads_lt_1ms":2075,"lbm_write_time_us":90950,"lbm_writes_lt_1ms":2045,"peak_mem_usage":249514480,"reinsert_count":0,"spinlock_wait_cycles":5888,"thread_start_us":389,"threads_started":6,"update_count":10000}
I20260812 06:17:00.666716 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=38.899875
I20260812 06:17:00.830060 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.163s	user 0.058s	sys 0.046s Metrics: {"bytes_written":41352571,"delete_count":0,"lbm_write_time_us":38899,"lbm_writes_lt_1ms":1011,"reinsert_count":0,"update_count":5040}
I20260812 06:17:00.830595 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=14.095187
I20260812 06:17:00.889011 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.058s	user 0.029s	sys 0.013s Metrics: {"bytes_written":16081713,"delete_count":0,"lbm_write_time_us":20276,"lbm_writes_lt_1ms":395,"reinsert_count":0,"update_count":1960}
I20260812 06:17:00.889531 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:17:00.927627 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.038s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3616,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:00.928114 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:17:00.942075 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.014s	user 0.008s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5514,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:00.942535 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushMRSOp(5deea1542701472abfa6358b7cf5f074): perf score=1.000000
I20260812 06:17:00.980506 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushMRSOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.038s	user 0.031s	sys 0.005s Metrics: {"bytes_written":1562442,"cfile_init":1,"dirs.queue_time_us":44,"dirs.run_cpu_time_us":216,"dirs.run_wall_time_us":1283,"drs_written":1,"lbm_read_time_us":57,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2509,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":38}
I20260812 06:17:00.981308 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling LogGCOp(5deea1542701472abfa6358b7cf5f074): free 153809634 bytes of WAL
I20260812 06:17:00.981552 30207 log_reader.cc:385] T 5deea1542701472abfa6358b7cf5f074: removed 15 log segments from log reader
I20260812 06:17:00.981602 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000021 (ops 102-106)
I20260812 06:17:00.981628 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000022 (ops 107-110)
I20260812 06:17:00.981649 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000023 (ops 111-115)
I20260812 06:17:00.981679 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000024 (ops 116-120)
I20260812 06:17:00.981711 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000025 (ops 121-125)
I20260812 06:17:00.981742 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000026 (ops 126-130)
I20260812 06:17:00.981772 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000027 (ops 131-135)
I20260812 06:17:00.981802 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000028 (ops 136-140)
I20260812 06:17:00.981833 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000029 (ops 141-145)
I20260812 06:17:00.981865 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000030 (ops 146-150)
I20260812 06:17:00.981895 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000031 (ops 151-155)
I20260812 06:17:00.981925 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000032 (ops 156-160)
I20260812 06:17:00.981954 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000033 (ops 161-165)
I20260812 06:17:00.981985 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000034 (ops 166-170)
I20260812 06:17:00.982023 30207 log.cc:1079] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/5deea1542701472abfa6358b7cf5f074/wal-000000035 (ops 171-175)
I20260812 06:17:01.006445 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: LogGCOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.025s	user 0.000s	sys 0.024s Metrics: {}
I20260812 06:17:01.006834 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:17:01.025628 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.019s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6002,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:01.026001 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling UndoDeltaBlockGCOp(5deea1542701472abfa6358b7cf5f074): 562 bytes on disk
I20260812 06:17:01.026345 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: UndoDeltaBlockGCOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":48,"lbm_reads_lt_1ms":4}
I20260812 06:17:01.026794 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=2.188937
I20260812 06:17:01.036388 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3613,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:01.036743 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074): perf score=1.000000
I20260812 06:17:01.495500 30033 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.557s	user 1.649s	sys 0.094s
I20260812 06:17:01.549629 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.513s	user 0.333s	sys 0.173s Metrics: {"cfile_cache_miss":1836,"cfile_cache_miss_bytes":78065565,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":6,"delta_iterators_relevant":6,"lbm_read_time_us":34861,"lbm_reads_lt_1ms":1872,"lbm_write_time_us":97568,"lbm_writes_lt_1ms":1845,"peak_mem_usage":224661336,"reinsert_count":0,"spinlock_wait_cycles":1152,"update_count":9000}
I20260812 06:17:01.550160 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074): perf score=37.907687
I20260812 06:17:01.624022 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: FlushDeltaMemStoresOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.074s	user 0.045s	sys 0.028s Metrics: {"bytes_written":41024378,"delete_count":0,"lbm_write_time_us":35464,"lbm_writes_lt_1ms":1003,"reinsert_count":0,"spinlock_wait_cycles":4608,"update_count":5000}
I20260812 06:17:01.624504 30313 maintenance_manager.cc:419] P 11ad92ced076460996bc98e6e2379592: Scheduling MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074): perf score=1.000000
I20260812 06:17:01.641390 30033 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.145s	user 0.003s	sys 0.000s
I20260812 06:17:01.641990 30033 tablet_server.cc:179] TabletServer@127.29.84.65:0 shutting down...
I20260812 06:17:01.832226 30207 maintenance_manager.cc:643] P 11ad92ced076460996bc98e6e2379592: MajorDeltaCompactionOp(5deea1542701472abfa6358b7cf5f074) complete. Timing: real 0.208s	user 0.151s	sys 0.055s Metrics: {"cfile_cache_miss":1031,"cfile_cache_miss_bytes":45245669,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":638,"lbm_read_time_us":15147,"lbm_reads_lt_1ms":1067,"lbm_write_time_us":43732,"lbm_writes_lt_1ms":1043,"mutex_wait_us":266,"peak_mem_usage":125248760,"reinsert_count":0,"spinlock_wait_cycles":7040,"update_count":5000}
I20260812 06:17:01.832816 30033 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:01.833179 30033 tablet_replica.cc:333] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592: stopping tablet replica
I20260812 06:17:01.833413 30033 raft_consensus.cc:2243] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:01.833624 30033 raft_consensus.cc:2272] T 5deea1542701472abfa6358b7cf5f074 P 11ad92ced076460996bc98e6e2379592 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:01.849360 30033 tablet_server.cc:196] TabletServer@127.29.84.65:0 shutdown complete.
I20260812 06:17:02.047921 30033 master.cc:562] Master@127.29.84.126:39055 shutting down...
I20260812 06:17:02.051556 30033 raft_consensus.cc:2243] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:02.051728 30033 raft_consensus.cc:2272] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:02.051803 30033 tablet_replica.cc:333] T 00000000000000000000000000000000 P a4810ffacfe14915addb5400d03ee6b5: stopping tablet replica
I20260812 06:17:02.064028 30033 master.cc:584] Master@127.29.84.126:39055 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5518 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:17:02.153429 30033 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.29.84.126:43643
I20260812 06:17:02.153813 30033 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:02.155835 30400 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:17:02.155934 30398 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:17:02.156006 30033 server_base.cc:1061] running on GCE node
W20260812 06:17:02.156061 30395 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:17:02.156277 30033 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:02.156318 30033 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:17:02.156332 30033 hybrid_clock.cc:648] HybridClock initialized: now 1786515422156332 us; error 0 us; skew 500 ppm
I20260812 06:17:02.157054 30033 webserver.cc:533] Webserver started at http://127.29.84.126:35643/ using document root <none> and password file <none>
I20260812 06:17:02.157181 30033 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:02.157244 30033 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:02.157307 30033 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:02.157647 30033 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/master-0-root/instance:
uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03"
format_stamp: "Formatted at 2026-08-12 06:17:02 on dist-test-slave-bndk"
I20260812 06:17:02.158980 30033 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:17:02.159806 30406 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:02.160003 30033 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:02.160072 30033 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/master-0-root
uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03"
format_stamp: "Formatted at 2026-08-12 06:17:02 on dist-test-slave-bndk"
I20260812 06:17:02.160137 30033 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:17:02.167821 30033 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:02.168105 30033 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:02.171907 30033 rpc_server.cc:307] RPC server started. Bound to: 127.29.84.126:43643
I20260812 06:17:02.172838 30488 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.84.126:43643 every 8 connection(s)
I20260812 06:17:02.173285 30490 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:02.174885 30490 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03: Bootstrap starting.
I20260812 06:17:02.175635 30490 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:02.176499 30490 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03: No bootstrap required, opened a new log
I20260812 06:17:02.176846 30490 raft_consensus.cc:359] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03" member_type: VOTER }
I20260812 06:17:02.176926 30490 raft_consensus.cc:385] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:02.176957 30490 raft_consensus.cc:740] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fa3dcc92439f4a9ebdfff2cc133ecd03, State: Initialized, Role: FOLLOWER
I20260812 06:17:02.177093 30490 consensus_queue.cc:260] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [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: "fa3dcc92439f4a9ebdfff2cc133ecd03" member_type: VOTER }
I20260812 06:17:02.177161 30490 raft_consensus.cc:399] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:02.177199 30490 raft_consensus.cc:493] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:02.177270 30490 raft_consensus.cc:3060] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:02.177893 30490 raft_consensus.cc:515] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03" member_type: VOTER }
I20260812 06:17:02.178016 30490 leader_election.cc:304] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [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: fa3dcc92439f4a9ebdfff2cc133ecd03; no voters: 
I20260812 06:17:02.178176 30490 leader_election.cc:290] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:02.178254 30496 raft_consensus.cc:2804] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:02.178431 30496 raft_consensus.cc:697] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 1 LEADER]: Becoming Leader. State: Replica: fa3dcc92439f4a9ebdfff2cc133ecd03, State: Running, Role: LEADER
I20260812 06:17:02.178570 30490 sys_catalog.cc:565] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:02.178560 30496 consensus_queue.cc:237] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [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: "fa3dcc92439f4a9ebdfff2cc133ecd03" member_type: VOTER }
I20260812 06:17:02.178968 30499 sys_catalog.cc:455] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [sys.catalog]: SysCatalogTable state changed. Reason: New leader fa3dcc92439f4a9ebdfff2cc133ecd03. Latest consensus state: current_term: 1 leader_uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03" member_type: VOTER } }
I20260812 06:17:02.179068 30499 sys_catalog.cc:458] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:02.178948 30497 sys_catalog.cc:455] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fa3dcc92439f4a9ebdfff2cc133ecd03" member_type: VOTER } }
I20260812 06:17:02.179184 30497 sys_catalog.cc:458] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:02.179589 30502 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:02.180291 30502 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:02.180445 30033 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:02.181916 30502 catalog_manager.cc:1383] Generated new cluster ID: 82d8bf35394d4bf48f4ccc7410c9e28b
I20260812 06:17:02.181977 30502 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:02.192770 30502 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:02.193291 30502 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:02.202211 30502 catalog_manager.cc:6092] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03: Generated new TSK 0
I20260812 06:17:02.202351 30502 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:02.212451 30033 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:02.214143 30522 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:17:02.214321 30523 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:17:02.214349 30525 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:17:02.214318 30033 server_base.cc:1061] running on GCE node
I20260812 06:17:02.214597 30033 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:02.214627 30033 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:17:02.214638 30033 hybrid_clock.cc:648] HybridClock initialized: now 1786515422214639 us; error 0 us; skew 500 ppm
I20260812 06:17:02.215387 30033 webserver.cc:533] Webserver started at http://127.29.84.65:33361/ using document root <none> and password file <none>
I20260812 06:17:02.215536 30033 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:02.215582 30033 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:02.215652 30033 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:02.215976 30033 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/instance:
uuid: "10551fe4525a4d41a522e0cb8b3dc4d4"
format_stamp: "Formatted at 2026-08-12 06:17:02 on dist-test-slave-bndk"
I20260812 06:17:02.217314 30033 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:17:02.218155 30533 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:02.218360 30033 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:02.218421 30033 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root
uuid: "10551fe4525a4d41a522e0cb8b3dc4d4"
format_stamp: "Formatted at 2026-08-12 06:17:02 on dist-test-slave-bndk"
I20260812 06:17:02.218484 30033 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:17:02.227249 30033 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:02.227535 30033 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:02.227768 30033 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:02.228152 30033 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:02.228188 30033 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:02.228225 30033 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:02.228253 30033 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:02.232043 30033 rpc_server.cc:307] RPC server started. Bound to: 127.29.84.65:39915
I20260812 06:17:02.232992 30636 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.84.65:39915 every 8 connection(s)
I20260812 06:17:02.236788 30638 heartbeater.cc:344] Connected to a master server at 127.29.84.126:43643
I20260812 06:17:02.236864 30638 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:02.237042 30638 heartbeater.cc:507] Master 127.29.84.126:43643 requested a full tablet report, sending...
I20260812 06:17:02.237609 30432 ts_manager.cc:194] Registered new tserver with Master: 10551fe4525a4d41a522e0cb8b3dc4d4 (127.29.84.65:39915)
I20260812 06:17:02.238262 30432 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:49582
I20260812 06:17:02.238268 30033 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.00553278s
I20260812 06:17:02.244202 30432 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:49592:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:17:02.251762 30580 tablet_service.cc:1511] Processing CreateTablet for tablet a1e441ee1f46473cb536d316c1a176af (DEFAULT_TABLE table=heavy-update-compaction-test [id=e0cc632f86bb4fc192ee5a9de87a59fb]), partition=
I20260812 06:17:02.251982 30580 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a1e441ee1f46473cb536d316c1a176af. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:02.253721 30652 tablet_bootstrap.cc:492] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Bootstrap starting.
I20260812 06:17:02.254532 30652 tablet_bootstrap.cc:654] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:02.255426 30652 tablet_bootstrap.cc:492] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: No bootstrap required, opened a new log
I20260812 06:17:02.255506 30652 ts_tablet_manager.cc:1403] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:17:02.255856 30652 raft_consensus.cc:359] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "10551fe4525a4d41a522e0cb8b3dc4d4" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 39915 } }
I20260812 06:17:02.255930 30652 raft_consensus.cc:385] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:02.255960 30652 raft_consensus.cc:740] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 10551fe4525a4d41a522e0cb8b3dc4d4, State: Initialized, Role: FOLLOWER
I20260812 06:17:02.256080 30652 consensus_queue.cc:260] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [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: "10551fe4525a4d41a522e0cb8b3dc4d4" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 39915 } }
I20260812 06:17:02.256146 30652 raft_consensus.cc:399] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:02.256181 30652 raft_consensus.cc:493] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:02.256227 30652 raft_consensus.cc:3060] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:02.256858 30652 raft_consensus.cc:515] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "10551fe4525a4d41a522e0cb8b3dc4d4" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 39915 } }
I20260812 06:17:02.256978 30652 leader_election.cc:304] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [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: 10551fe4525a4d41a522e0cb8b3dc4d4; no voters: 
I20260812 06:17:02.257156 30652 leader_election.cc:290] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:02.257261 30654 raft_consensus.cc:2804] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:02.257412 30654 raft_consensus.cc:697] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 1 LEADER]: Becoming Leader. State: Replica: 10551fe4525a4d41a522e0cb8b3dc4d4, State: Running, Role: LEADER
I20260812 06:17:02.257470 30652 ts_tablet_manager.cc:1434] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Time spent starting tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:02.257517 30638 heartbeater.cc:499] Master 127.29.84.126:43643 was elected leader, sending a full tablet report...
I20260812 06:17:02.257536 30654 consensus_queue.cc:237] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [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: "10551fe4525a4d41a522e0cb8b3dc4d4" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 39915 } }
I20260812 06:17:02.258749 30432 catalog_manager.cc:5719] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 reported cstate change: term changed from 0 to 1, leader changed from <none> to 10551fe4525a4d41a522e0cb8b3dc4d4 (127.29.84.65). New cstate: current_term: 1 leader_uuid: "10551fe4525a4d41a522e0cb8b3dc4d4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "10551fe4525a4d41a522e0cb8b3dc4d4" member_type: VOTER last_known_addr { host: "127.29.84.65" port: 39915 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:02.311877 30033 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.049s	user 0.018s	sys 0.004s
I20260812 06:17:02.483605 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushMRSOp(a1e441ee1f46473cb536d316c1a176af): perf score=23.023690
I20260812 06:17:02.646814 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushMRSOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.163s	user 0.122s	sys 0.039s Metrics: {"bytes_written":13333097,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":35,"dirs.run_cpu_time_us":195,"dirs.run_wall_time_us":853,"drs_written":1,"lbm_read_time_us":34,"lbm_reads_lt_1ms":4,"lbm_write_time_us":41932,"lbm_writes_lt_1ms":882,"peak_mem_usage":0,"reinsert_count":0,"rows_written":105,"spinlock_wait_cycles":14720,"update_count":1625}
I20260812 06:17:02.647372 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling LogGCOp(a1e441ee1f46473cb536d316c1a176af): free 20743880 bytes of WAL
I20260812 06:17:02.647584 30546 log_reader.cc:385] T a1e441ee1f46473cb536d316c1a176af: removed 2 log segments from log reader
I20260812 06:17:02.647634 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000001 (ops 1-6)
I20260812 06:17:02.647663 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000002 (ops 7-11)
I20260812 06:17:02.651476 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: LogGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.004s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:17:02.651782 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af): 20513814 bytes on disk
I20260812 06:17:02.652206 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":77,"lbm_reads_lt_1ms":4}
I20260812 06:17:02.652604 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:02.666404 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.014s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3487284,"delete_count":0,"lbm_write_time_us":3254,"lbm_writes_lt_1ms":88,"reinsert_count":0,"update_count":425}
I20260812 06:17:02.666775 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:02.675364 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.008s	user 0.000s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3397,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:02.675737 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:02.844528 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.169s	user 0.104s	sys 0.064s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24815781,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":770,"lbm_read_time_us":11215,"lbm_reads_lt_1ms":569,"lbm_write_time_us":27905,"lbm_writes_lt_1ms":543,"mutex_wait_us":78,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"thread_start_us":331,"threads_started":5,"update_count":2500}
I20260812 06:17:02.844946 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:02.901592 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.057s	user 0.030s	sys 0.023s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":20383,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:02.902055 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:02.912256 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4099,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:02.912642 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:03.083822 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.171s	user 0.100s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1063,"lbm_read_time_us":11585,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26568,"lbm_writes_lt_1ms":543,"mutex_wait_us":316,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2500}
I20260812 06:17:03.084321 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:03.147357 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.063s	user 0.033s	sys 0.026s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":25858,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:03.147881 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:03.157668 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3802,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:03.158104 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:03.335608 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.177s	user 0.116s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":773,"lbm_read_time_us":12077,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26583,"lbm_writes_lt_1ms":543,"mutex_wait_us":389,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5120,"update_count":2500}
I20260812 06:17:03.336187 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:03.386915 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.050s	user 0.025s	sys 0.019s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":20145,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:03.387387 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:03.397567 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.010s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3941,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:03.398090 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:03.560851 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.163s	user 0.092s	sys 0.065s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":270,"lbm_read_time_us":9979,"lbm_reads_lt_1ms":572,"lbm_write_time_us":24542,"lbm_writes_lt_1ms":543,"mutex_wait_us":18,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":23424,"update_count":2500}
I20260812 06:17:03.561406 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:03.610734 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.049s	user 0.028s	sys 0.013s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":18617,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:03.611195 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:03.620972 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3671,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:03.621547 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:03.773658 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.152s	user 0.106s	sys 0.040s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":636,"lbm_read_time_us":10423,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29339,"lbm_writes_lt_1ms":543,"mutex_wait_us":20,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":17024,"update_count":2500}
I20260812 06:17:03.774325 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=11.118625
I20260812 06:17:03.804008 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.030s	user 0.014s	sys 0.012s Metrics: {"bytes_written":12717734,"delete_count":0,"lbm_write_time_us":12224,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:17:03.804533 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:03.828743 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.024s	user 0.000s	sys 0.012s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5111,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:03.829201 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:03.838852 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3779,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:03.839229 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushMRSOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:03.869473 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushMRSOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.030s	user 0.022s	sys 0.008s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":314,"dirs.run_cpu_time_us":184,"dirs.run_wall_time_us":1209,"drs_written":1,"lbm_read_time_us":45,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2151,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:17:03.870023 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling LogGCOp(a1e441ee1f46473cb536d316c1a176af): free 124710286 bytes of WAL
I20260812 06:17:03.870229 30546 log_reader.cc:385] T a1e441ee1f46473cb536d316c1a176af: removed 12 log segments from log reader
I20260812 06:17:03.870275 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000003 (ops 12-16)
I20260812 06:17:03.870309 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000004 (ops 17-21)
I20260812 06:17:03.870343 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000005 (ops 22-26)
I20260812 06:17:03.870376 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000006 (ops 27-31)
I20260812 06:17:03.870409 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000007 (ops 32-36)
I20260812 06:17:03.870441 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000008 (ops 37-41)
I20260812 06:17:03.870473 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000009 (ops 42-46)
I20260812 06:17:03.870505 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000010 (ops 47-51)
I20260812 06:17:03.870536 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000011 (ops 52-56)
I20260812 06:17:03.870568 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000012 (ops 57-61)
I20260812 06:17:03.870599 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000013 (ops 62-66)
I20260812 06:17:03.870630 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000014 (ops 67-71)
I20260812 06:17:03.894273 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: LogGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.024s	user 0.000s	sys 0.023s Metrics: {}
I20260812 06:17:03.894645 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=3.181125
I20260812 06:17:03.916436 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.022s	user 0.000s	sys 0.017s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":3986,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:03.916842 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af): 472 bytes on disk
I20260812 06:17:03.917272 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":63,"lbm_reads_lt_1ms":4}
I20260812 06:17:03.917737 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:03.926951 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3522,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:03.927299 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:04.153489 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.226s	user 0.129s	sys 0.087s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":33020845,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":567,"lbm_read_time_us":15312,"lbm_reads_lt_1ms":775,"lbm_write_time_us":33817,"lbm_writes_lt_1ms":743,"mutex_wait_us":283,"peak_mem_usage":87969044,"reinsert_count":0,"thread_start_us":75,"threads_started":1,"update_count":3500}
I20260812 06:17:04.154065 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=18.063937
I20260812 06:17:04.221365 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.067s	user 0.037s	sys 0.017s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":24565,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:04.221836 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:04.231731 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3505,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:04.232264 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:04.428170 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.196s	user 0.122s	sys 0.072s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918097,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":647,"lbm_read_time_us":12595,"lbm_reads_lt_1ms":672,"lbm_write_time_us":31405,"lbm_writes_lt_1ms":643,"mutex_wait_us":275,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":9216,"update_count":3000}
I20260812 06:17:04.428802 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=16.079562
I20260812 06:17:04.492455 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.063s	user 0.036s	sys 0.008s Metrics: {"bytes_written":18091890,"delete_count":0,"lbm_write_time_us":21250,"lbm_writes_lt_1ms":444,"reinsert_count":0,"update_count":2205}
I20260812 06:17:04.492877 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=5.165500
I20260812 06:17:04.508932 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.016s	user 0.011s	sys 0.003s Metrics: {"bytes_written":6523096,"delete_count":0,"lbm_write_time_us":6580,"lbm_writes_lt_1ms":162,"reinsert_count":0,"update_count":795}
I20260812 06:17:04.509404 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:04.701457 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.192s	user 0.126s	sys 0.061s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918103,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":640,"lbm_read_time_us":13691,"lbm_reads_lt_1ms":672,"lbm_write_time_us":31816,"lbm_writes_lt_1ms":643,"mutex_wait_us":28,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":7936,"update_count":3000}
I20260812 06:17:04.701969 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=16.079562
I20260812 06:17:04.749145 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.047s	user 0.038s	sys 0.004s Metrics: {"bytes_written":17763699,"delete_count":0,"lbm_write_time_us":20256,"lbm_writes_lt_1ms":436,"reinsert_count":0,"update_count":2165}
I20260812 06:17:04.749666 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.196750
I20260812 06:17:04.760488 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.011s	user 0.007s	sys 0.000s Metrics: {"bytes_written":3159084,"delete_count":0,"lbm_write_time_us":3017,"lbm_writes_lt_1ms":80,"reinsert_count":0,"update_count":385}
I20260812 06:17:04.760907 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:04.769810 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3513,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:04.770313 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:04.963585 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.193s	user 0.134s	sys 0.059s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28918183,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":227,"lbm_read_time_us":13660,"lbm_reads_lt_1ms":673,"lbm_write_time_us":31896,"lbm_writes_lt_1ms":643,"mutex_wait_us":275,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":3000}
I20260812 06:17:04.964654 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:05.006429 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.041s	user 0.036s	sys 0.003s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":17600,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:05.006894 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:05.021034 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.014s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5496,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.021590 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:05.191308 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.170s	user 0.107s	sys 0.059s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":827,"lbm_read_time_us":13167,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27369,"lbm_writes_lt_1ms":543,"mutex_wait_us":261,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3456,"update_count":2500}
I20260812 06:17:05.191792 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:05.242198 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.050s	user 0.025s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":17849,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:05.242671 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:05.256942 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.014s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5605,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.257383 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushMRSOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:05.297273 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushMRSOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.040s	user 0.024s	sys 0.005s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":60,"dirs.run_cpu_time_us":205,"dirs.run_wall_time_us":1070,"drs_written":1,"lbm_read_time_us":37,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1444,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:17:05.297928 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling LogGCOp(a1e441ee1f46473cb536d316c1a176af): free 128867430 bytes of WAL
I20260812 06:17:05.298142 30546 log_reader.cc:385] T a1e441ee1f46473cb536d316c1a176af: removed 13 log segments from log reader
I20260812 06:17:05.298189 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000015 (ops 72-76)
I20260812 06:17:05.298218 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000016 (ops 77-80)
I20260812 06:17:05.298249 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000017 (ops 81-85)
I20260812 06:17:05.298283 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000018 (ops 86-90)
I20260812 06:17:05.298316 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000019 (ops 91-95)
I20260812 06:17:05.298348 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000020 (ops 96-100)
I20260812 06:17:05.298381 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000021 (ops 101-104)
I20260812 06:17:05.298413 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000022 (ops 105-109)
I20260812 06:17:05.298445 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000023 (ops 110-114)
I20260812 06:17:05.298477 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000024 (ops 115-119)
I20260812 06:17:05.298509 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000025 (ops 120-124)
I20260812 06:17:05.298542 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000026 (ops 125-128)
I20260812 06:17:05.298575 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000027 (ops 129-133)
I20260812 06:17:05.320197 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: LogGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.022s	user 0.000s	sys 0.019s Metrics: {}
I20260812 06:17:05.320541 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=3.181125
I20260812 06:17:05.341501 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.021s	user 0.005s	sys 0.007s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":4729,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:05.341949 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af): 472 bytes on disk
I20260812 06:17:05.342356 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":64,"lbm_reads_lt_1ms":4}
I20260812 06:17:05.342815 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:05.356007 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.013s	user 0.004s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5005,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:05.356477 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:05.569551 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.213s	user 0.153s	sys 0.053s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020734,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":571,"lbm_read_time_us":14612,"lbm_reads_lt_1ms":774,"lbm_write_time_us":36463,"lbm_writes_lt_1ms":743,"mutex_wait_us":42,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":25600,"thread_start_us":101,"threads_started":1,"update_count":3500}
I20260812 06:17:05.570168 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=18.063937
I20260812 06:17:05.617619 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.047s	user 0.031s	sys 0.016s Metrics: {"bytes_written":20512313,"delete_count":0,"lbm_write_time_us":20586,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:05.618108 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:05.632742 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5651,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.633157 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:05.789872 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.157s	user 0.108s	sys 0.049s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918095,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":238,"lbm_read_time_us":10157,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32115,"lbm_writes_lt_1ms":643,"mutex_wait_us":48,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2176,"update_count":3000}
I20260812 06:17:05.790458 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:05.836855 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.046s	user 0.029s	sys 0.012s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19495,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:05.837419 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:05.851851 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.014s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5306,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.852283 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:05.997208 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.145s	user 0.120s	sys 0.024s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":618,"lbm_read_time_us":11728,"lbm_reads_lt_1ms":572,"lbm_write_time_us":24799,"lbm_writes_lt_1ms":543,"mutex_wait_us":344,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":61440,"update_count":2500}
I20260812 06:17:05.997925 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=12.110812
I20260812 06:17:06.030541 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.032s	user 0.024s	sys 0.007s Metrics: {"bytes_written":13538209,"delete_count":0,"lbm_write_time_us":13830,"lbm_writes_lt_1ms":333,"reinsert_count":0,"update_count":1650}
I20260812 06:17:06.031024 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.196750
I20260812 06:17:06.043691 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.013s	user 0.005s	sys 0.005s Metrics: {"bytes_written":2953959,"delete_count":0,"lbm_write_time_us":4189,"lbm_writes_lt_1ms":75,"reinsert_count":0,"update_count":360}
I20260812 06:17:06.044096 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:06.180727 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.136s	user 0.092s	sys 0.040s Metrics: {"cfile_cache_miss":434,"cfile_cache_miss_bytes":20795291,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":275,"lbm_read_time_us":9834,"lbm_reads_lt_1ms":466,"lbm_write_time_us":23015,"lbm_writes_lt_1ms":445,"mutex_wait_us":1,"peak_mem_usage":50771398,"reinsert_count":0,"update_count":2010}
I20260812 06:17:06.181195 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:06.229563 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.048s	user 0.021s	sys 0.021s Metrics: {"bytes_written":16327857,"delete_count":0,"lbm_write_time_us":18802,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":1990}
I20260812 06:17:06.230110 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:06.249044 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.019s	user 0.005s	sys 0.010s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4066,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:06.249699 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:06.423714 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.174s	user 0.093s	sys 0.074s Metrics: {"cfile_cache_miss":530,"cfile_cache_miss_bytes":24733638,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":136,"lbm_read_time_us":10845,"lbm_reads_lt_1ms":570,"lbm_write_time_us":27146,"lbm_writes_lt_1ms":541,"mutex_wait_us":32,"peak_mem_usage":61993446,"reinsert_count":0,"update_count":2490}
I20260812 06:17:06.424238 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:06.473456 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.049s	user 0.032s	sys 0.014s Metrics: {"bytes_written":16409909,"delete_count":0,"lbm_write_time_us":23054,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:06.473987 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:06.485863 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.012s	user 0.010s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3832,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:06.486291 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:06.649623 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.163s	user 0.133s	sys 0.024s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815691,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":640,"lbm_read_time_us":11769,"lbm_reads_lt_1ms":564,"lbm_write_time_us":27598,"lbm_writes_lt_1ms":543,"mutex_wait_us":264,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6912,"update_count":2500}
I20260812 06:17:06.650167 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=14.095187
I20260812 06:17:06.695050 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.045s	user 0.026s	sys 0.012s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":17114,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:06.695616 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:06.705334 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.010s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3791,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:06.705828 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushMRSOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:06.737555 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushMRSOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.032s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1316417,"cfile_init":1,"dirs.queue_time_us":65,"dirs.run_cpu_time_us":197,"dirs.run_wall_time_us":1077,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1707,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:17:06.738183 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling LogGCOp(a1e441ee1f46473cb536d316c1a176af): free 128867735 bytes of WAL
I20260812 06:17:06.738387 30546 log_reader.cc:385] T a1e441ee1f46473cb536d316c1a176af: removed 13 log segments from log reader
I20260812 06:17:06.738432 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000028 (ops 134-138)
I20260812 06:17:06.738458 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000029 (ops 139-143)
I20260812 06:17:06.738488 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000030 (ops 144-148)
I20260812 06:17:06.738529 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000031 (ops 149-153)
I20260812 06:17:06.738561 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000032 (ops 154-158)
I20260812 06:17:06.738595 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000033 (ops 159-162)
I20260812 06:17:06.738646 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000034 (ops 163-167)
I20260812 06:17:06.738668 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000035 (ops 168-172)
I20260812 06:17:06.738694 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000036 (ops 173-176)
I20260812 06:17:06.738725 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000037 (ops 177-181)
I20260812 06:17:06.738755 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000038 (ops 182-186)
I20260812 06:17:06.738785 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000039 (ops 187-190)
I20260812 06:17:06.738816 30546 log.cc:1079] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: Deleting log segment in path: /tmp/dist-test-taskflM__E/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515416610129-30033-0/minicluster-data/ts-0-root/wals/a1e441ee1f46473cb536d316c1a176af/wal-000000040 (ops 191-195)
I20260812 06:17:06.760097 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: LogGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.022s	user 0.000s	sys 0.019s Metrics: {}
I20260812 06:17:06.760448 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af): 493 bytes on disk
I20260812 06:17:06.760878 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: UndoDeltaBlockGCOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":68,"lbm_reads_lt_1ms":4}
I20260812 06:17:06.761464 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=3.181125
I20260812 06:17:06.787240 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.026s	user 0.012s	sys 0.012s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":6655,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:06.787856 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af): perf score=2.188937
I20260812 06:17:06.801241 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: FlushDeltaMemStoresOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.013s	user 0.011s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5244,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:06.801679 30639 maintenance_manager.cc:419] P 10551fe4525a4d41a522e0cb8b3dc4d4: Scheduling MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af): perf score=1.000000
I20260812 06:17:06.850919 30033 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.539s	user 1.663s	sys 0.146s
I20260812 06:17:06.942263 30033 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.091s	user 0.002s	sys 0.000s
I20260812 06:17:06.942782 30033 tablet_server.cc:179] TabletServer@127.29.84.65:0 shutting down...
I20260812 06:17:06.984757 30546 maintenance_manager.cc:643] P 10551fe4525a4d41a522e0cb8b3dc4d4: MajorDeltaCompactionOp(a1e441ee1f46473cb536d316c1a176af) complete. Timing: real 0.183s	user 0.127s	sys 0.056s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020737,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":680,"lbm_read_time_us":13677,"lbm_reads_lt_1ms":770,"lbm_write_time_us":29838,"lbm_writes_lt_1ms":743,"mutex_wait_us":27,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":3712,"thread_start_us":60,"threads_started":1,"update_count":3500}
I20260812 06:17:06.985425 30033 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:06.985776 30033 tablet_replica.cc:333] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4: stopping tablet replica
I20260812 06:17:06.985908 30033 raft_consensus.cc:2243] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:06.986064 30033 raft_consensus.cc:2272] T a1e441ee1f46473cb536d316c1a176af P 10551fe4525a4d41a522e0cb8b3dc4d4 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:07.000550 30033 tablet_server.cc:196] TabletServer@127.29.84.65:0 shutdown complete.
I20260812 06:17:07.041419 30033 master.cc:562] Master@127.29.84.126:43643 shutting down...
I20260812 06:17:07.044747 30033 raft_consensus.cc:2243] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:07.044915 30033 raft_consensus.cc:2272] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:07.044986 30033 tablet_replica.cc:333] T 00000000000000000000000000000000 P fa3dcc92439f4a9ebdfff2cc133ecd03: stopping tablet replica
I20260812 06:17:07.057051 30033 master.cc:584] Master@127.29.84.126:43643 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (4985 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10504 ms total)

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