[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:17:06.095329 30417 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.29.180.126:38749
I20260812 06:17:06.096395 30417 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:17:06.097047 30417 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:06.103502 30427 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:06.103551 30424 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:06.103790 30417 server_base.cc:1061] running on GCE node
W20260812 06:17:06.103874 30425 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:06.104452 30417 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:06.104581 30417 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:06.104632 30417 hybrid_clock.cc:648] HybridClock initialized: now 1786515426104629 us; error 0 us; skew 500 ppm
I20260812 06:17:06.106688 30417 webserver.cc:533] Webserver started at http://127.29.180.126:43263/ using document root <none> and password file <none>
I20260812 06:17:06.107348 30417 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:06.107451 30417 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:06.107712 30417 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:06.109506 30417 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/master-0-root/instance:
uuid: "330d91c0b99c431ababfbf86d4b09960"
format_stamp: "Formatted at 2026-08-12 06:17:06 on dist-test-slave-2w3w"
I20260812 06:17:06.113556 30417 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.002s	sys 0.004s
I20260812 06:17:06.116035 30432 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:06.117257 30417 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:17:06.117440 30417 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/master-0-root
uuid: "330d91c0b99c431ababfbf86d4b09960"
format_stamp: "Formatted at 2026-08-12 06:17:06 on dist-test-slave-2w3w"
I20260812 06:17:06.117573 30417 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-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:06.135761 30417 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:06.136529 30417 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:17:06.136734 30417 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:06.144877 30417 rpc_server.cc:307] RPC server started. Bound to: 127.29.180.126:38749
I20260812 06:17:06.144904 30490 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.180.126:38749 every 8 connection(s)
I20260812 06:17:06.147398 30492 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:06.153004 30492 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960: Bootstrap starting.
I20260812 06:17:06.155537 30492 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:06.156486 30492 log.cc:826] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960: Log is configured to *not* fsync() on all Append() calls
I20260812 06:17:06.158380 30492 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960: No bootstrap required, opened a new log
I20260812 06:17:06.161387 30492 raft_consensus.cc:359] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "330d91c0b99c431ababfbf86d4b09960" member_type: VOTER }
I20260812 06:17:06.161580 30492 raft_consensus.cc:385] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:06.161623 30492 raft_consensus.cc:740] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 330d91c0b99c431ababfbf86d4b09960, State: Initialized, Role: FOLLOWER
I20260812 06:17:06.162197 30492 consensus_queue.cc:260] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [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: "330d91c0b99c431ababfbf86d4b09960" member_type: VOTER }
I20260812 06:17:06.162344 30492 raft_consensus.cc:399] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:06.162389 30492 raft_consensus.cc:493] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:06.162483 30492 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:06.163440 30492 raft_consensus.cc:515] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "330d91c0b99c431ababfbf86d4b09960" member_type: VOTER }
I20260812 06:17:06.163895 30492 leader_election.cc:304] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [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: 330d91c0b99c431ababfbf86d4b09960; no voters: 
I20260812 06:17:06.164215 30492 leader_election.cc:290] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:06.164436 30495 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:06.164745 30495 raft_consensus.cc:697] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 1 LEADER]: Becoming Leader. State: Replica: 330d91c0b99c431ababfbf86d4b09960, State: Running, Role: LEADER
I20260812 06:17:06.165153 30495 consensus_queue.cc:237] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [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: "330d91c0b99c431ababfbf86d4b09960" member_type: VOTER }
I20260812 06:17:06.165436 30492 sys_catalog.cc:565] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:06.167563 30496 sys_catalog.cc:455] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "330d91c0b99c431ababfbf86d4b09960" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "330d91c0b99c431ababfbf86d4b09960" member_type: VOTER } }
I20260812 06:17:06.167734 30496 sys_catalog.cc:458] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:06.167586 30497 sys_catalog.cc:455] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 330d91c0b99c431ababfbf86d4b09960. Latest consensus state: current_term: 1 leader_uuid: "330d91c0b99c431ababfbf86d4b09960" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "330d91c0b99c431ababfbf86d4b09960" member_type: VOTER } }
I20260812 06:17:06.167979 30497 sys_catalog.cc:458] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:06.168022 30417 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:06.168174 30513 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:06.170879 30513 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:06.176429 30513 catalog_manager.cc:1383] Generated new cluster ID: a260b0393a1d4a45951072906ea60056
I20260812 06:17:06.176544 30513 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:06.192250 30513 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:06.193197 30513 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:06.198833 30513 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960: Generated new TSK 0
I20260812 06:17:06.199558 30513 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:06.233131 30417 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:06.235915 30522 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:06.236063 30520 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:06.236089 30519 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:06.236630 30417 server_base.cc:1061] running on GCE node
I20260812 06:17:06.236811 30417 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:06.236908 30417 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:06.236950 30417 hybrid_clock.cc:648] HybridClock initialized: now 1786515426236949 us; error 0 us; skew 500 ppm
I20260812 06:17:06.237985 30417 webserver.cc:533] Webserver started at http://127.29.180.65:35899/ using document root <none> and password file <none>
I20260812 06:17:06.238181 30417 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:06.238255 30417 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:06.238338 30417 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:06.238765 30417 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/instance:
uuid: "43ee51c74d8448f3bf4e98be491ddd2f"
format_stamp: "Formatted at 2026-08-12 06:17:06 on dist-test-slave-2w3w"
I20260812 06:17:06.240571 30417 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:06.241642 30528 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:06.241909 30417 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:17:06.241986 30417 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root
uuid: "43ee51c74d8448f3bf4e98be491ddd2f"
format_stamp: "Formatted at 2026-08-12 06:17:06 on dist-test-slave-2w3w"
I20260812 06:17:06.242094 30417 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-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:06.279953 30417 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:06.280454 30417 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:06.280977 30417 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:06.281858 30417 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:06.281909 30417 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:06.281955 30417 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:06.282019 30417 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:06.289175 30417 rpc_server.cc:307] RPC server started. Bound to: 127.29.180.65:34733
I20260812 06:17:06.289206 30601 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.180.65:34733 every 8 connection(s)
I20260812 06:17:06.302887 30602 heartbeater.cc:344] Connected to a master server at 127.29.180.126:38749
I20260812 06:17:06.303226 30602 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:06.303709 30602 heartbeater.cc:507] Master 127.29.180.126:38749 requested a full tablet report, sending...
I20260812 06:17:06.305215 30451 ts_manager.cc:194] Registered new tserver with Master: 43ee51c74d8448f3bf4e98be491ddd2f (127.29.180.65:34733)
I20260812 06:17:06.305756 30417 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.015894025s
I20260812 06:17:06.306531 30451 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:41730
I20260812 06:17:06.316700 30451 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:41740:
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:06.331091 30562 tablet_service.cc:1511] Processing CreateTablet for tablet 06b789dfdc674c63ae093981ab8f6db1 (DEFAULT_TABLE table=heavy-update-compaction-test [id=cd956f908da448c288c071a87d499abe]), partition=
I20260812 06:17:06.331555 30562 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 06b789dfdc674c63ae093981ab8f6db1. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:06.334885 30616 tablet_bootstrap.cc:492] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Bootstrap starting.
I20260812 06:17:06.335837 30616 tablet_bootstrap.cc:654] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:06.337095 30616 tablet_bootstrap.cc:492] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: No bootstrap required, opened a new log
I20260812 06:17:06.337240 30616 ts_tablet_manager.cc:1403] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:17:06.337759 30616 raft_consensus.cc:359] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "43ee51c74d8448f3bf4e98be491ddd2f" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 34733 } }
I20260812 06:17:06.337946 30616 raft_consensus.cc:385] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:06.338033 30616 raft_consensus.cc:740] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 43ee51c74d8448f3bf4e98be491ddd2f, State: Initialized, Role: FOLLOWER
I20260812 06:17:06.338218 30616 consensus_queue.cc:260] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [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: "43ee51c74d8448f3bf4e98be491ddd2f" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 34733 } }
I20260812 06:17:06.338342 30616 raft_consensus.cc:399] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:06.338403 30616 raft_consensus.cc:493] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:06.338699 30616 raft_consensus.cc:3060] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:06.339618 30616 raft_consensus.cc:515] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "43ee51c74d8448f3bf4e98be491ddd2f" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 34733 } }
I20260812 06:17:06.339792 30616 leader_election.cc:304] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [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: 43ee51c74d8448f3bf4e98be491ddd2f; no voters: 
I20260812 06:17:06.340039 30616 leader_election.cc:290] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:06.340145 30619 raft_consensus.cc:2804] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:06.340339 30619 raft_consensus.cc:697] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 1 LEADER]: Becoming Leader. State: Replica: 43ee51c74d8448f3bf4e98be491ddd2f, State: Running, Role: LEADER
I20260812 06:17:06.340437 30616 ts_tablet_manager.cc:1434] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Time spent starting tablet: real 0.003s	user 0.002s	sys 0.002s
I20260812 06:17:06.340546 30619 consensus_queue.cc:237] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [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: "43ee51c74d8448f3bf4e98be491ddd2f" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 34733 } }
I20260812 06:17:06.340837 30602 heartbeater.cc:499] Master 127.29.180.126:38749 was elected leader, sending a full tablet report...
I20260812 06:17:06.344146 30451 catalog_manager.cc:5719] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f reported cstate change: term changed from 0 to 1, leader changed from <none> to 43ee51c74d8448f3bf4e98be491ddd2f (127.29.180.65). New cstate: current_term: 1 leader_uuid: "43ee51c74d8448f3bf4e98be491ddd2f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "43ee51c74d8448f3bf4e98be491ddd2f" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 34733 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:06.410991 30417 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.059s	user 0.019s	sys 0.009s
I20260812 06:17:06.540424 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1): perf score=15.086190
I20260812 06:17:06.716269 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.175s	user 0.140s	sys 0.032s Metrics: {"bytes_written":12307490,"cfile_init":1,"compiler_manager_pool.queue_time_us":230,"delete_count":0,"dirs.queue_time_us":59,"dirs.run_cpu_time_us":226,"dirs.run_wall_time_us":934,"drs_written":1,"lbm_read_time_us":116,"lbm_reads_lt_1ms":4,"lbm_write_time_us":44836,"lbm_writes_lt_1ms":667,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"thread_start_us":150,"threads_started":1,"update_count":1500}
I20260812 06:17:06.717523 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling LogGCOp(06b789dfdc674c63ae093981ab8f6db1): free 20743880 bytes of WAL
I20260812 06:17:06.717834 30535 log_reader.cc:385] T 06b789dfdc674c63ae093981ab8f6db1: removed 2 log segments from log reader
I20260812 06:17:06.717896 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000001 (ops 1-6)
I20260812 06:17:06.717947 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000002 (ops 7-11)
I20260812 06:17:06.724166 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: LogGCOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.006s	user 0.000s	sys 0.005s Metrics: {}
I20260812 06:17:06.724628 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:06.739490 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.015s	user 0.013s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5880,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:06.739998 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:06.753700 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.014s	user 0.011s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5368,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:06.754192 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:06.952687 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.198s	user 0.135s	sys 0.056s Metrics: {"cfile_cache_miss":523,"cfile_cache_miss_bytes":24364554,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":885,"lbm_read_time_us":13696,"lbm_reads_lt_1ms":563,"lbm_write_time_us":32410,"lbm_writes_lt_1ms":533,"peak_mem_usage":61665166,"reinsert_count":0,"thread_start_us":334,"threads_started":5,"update_count":2450}
I20260812 06:17:06.953423 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling UndoDeltaBlockGCOp(06b789dfdc674c63ae093981ab8f6db1): 12719216 bytes on disk
I20260812 06:17:06.953979 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: UndoDeltaBlockGCOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":81,"lbm_reads_lt_1ms":4}
I20260812 06:17:06.954540 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=11.118625
I20260812 06:17:06.995458 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.041s	user 0.037s	sys 0.003s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":17319,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:17:06.995960 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:07.009558 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.013s	user 0.008s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5337,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:07.010067 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:07.219058 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.209s	user 0.184s	sys 0.024s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672268,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":388,"lbm_read_time_us":8413,"lbm_reads_lt_1ms":464,"lbm_write_time_us":44372,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2000}
I20260812 06:17:07.220043 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=10.126437
I20260812 06:17:07.294420 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.074s	user 0.047s	sys 0.023s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":32268,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:07.295385 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:07.326494 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.031s	user 0.015s	sys 0.013s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":11646,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:07.327453 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:07.738176 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.410s	user 0.309s	sys 0.100s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":111,"lbm_read_time_us":27005,"lbm_reads_lt_1ms":472,"lbm_write_time_us":69402,"lbm_writes_lt_1ms":443,"mutex_wait_us":234,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1280,"thread_start_us":594,"threads_started":4,"update_count":2000}
I20260812 06:17:07.739455 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=18.063937
I20260812 06:17:07.890193 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.150s	user 0.097s	sys 0.045s Metrics: {"bytes_written":20512318,"delete_count":0,"lbm_write_time_us":67672,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:07.891502 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:07.927371 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.036s	user 0.027s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":13951,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:07.928238 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:08.335649 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.407s	user 0.257s	sys 0.139s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877105,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":433,"lbm_read_time_us":37589,"lbm_reads_lt_1ms":672,"lbm_write_time_us":70103,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":24704,"thread_start_us":508,"threads_started":6,"update_count":3000}
I20260812 06:17:08.337085 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=14.095187
I20260812 06:17:08.477355 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.140s	user 0.072s	sys 0.032s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":48356,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:08.478665 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:08.503487 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.024s	user 0.016s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":9474,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:08.504345 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:08.865504 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.361s	user 0.224s	sys 0.116s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774691,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":864,"lbm_read_time_us":29007,"lbm_reads_lt_1ms":572,"lbm_write_time_us":62203,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":12416,"thread_start_us":660,"threads_started":5,"update_count":2500}
I20260812 06:17:08.867363 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=14.095187
I20260812 06:17:09.010294 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.143s	user 0.078s	sys 0.059s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":55499,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:09.011282 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:09.032174 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.021s	user 0.011s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":8974,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:09.032940 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:09.120201 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.087s	user 0.063s	sys 0.002s Metrics: {"bytes_written":1152508,"cfile_init":1,"dirs.queue_time_us":178,"dirs.run_cpu_time_us":452,"dirs.run_wall_time_us":1697,"drs_written":1,"lbm_read_time_us":169,"lbm_reads_lt_1ms":4,"lbm_write_time_us":4501,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:17:09.121850 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling LogGCOp(06b789dfdc674c63ae093981ab8f6db1): free 112239312 bytes of WAL
I20260812 06:17:09.122331 30535 log_reader.cc:385] T 06b789dfdc674c63ae093981ab8f6db1: removed 11 log segments from log reader
I20260812 06:17:09.122397 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000003 (ops 12-16)
I20260812 06:17:09.122496 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000004 (ops 17-21)
I20260812 06:17:09.122568 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000005 (ops 22-26)
I20260812 06:17:09.122646 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000006 (ops 27-31)
I20260812 06:17:09.122725 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000007 (ops 32-36)
I20260812 06:17:09.123006 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000008 (ops 37-41)
I20260812 06:17:09.123101 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000009 (ops 42-46)
I20260812 06:17:09.123157 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000010 (ops 47-50)
I20260812 06:17:09.123189 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000011 (ops 51-55)
I20260812 06:17:09.123276 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000012 (ops 56-60)
I20260812 06:17:09.123351 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000013 (ops 61-65)
I20260812 06:17:09.171851 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: LogGCOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.050s	user 0.006s	sys 0.043s Metrics: {}
I20260812 06:17:09.172669 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling UndoDeltaBlockGCOp(06b789dfdc674c63ae093981ab8f6db1): 448 bytes on disk
I20260812 06:17:09.174108 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: UndoDeltaBlockGCOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":259,"lbm_reads_lt_1ms":4}
I20260812 06:17:09.175324 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:09.212194 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.037s	user 0.008s	sys 0.014s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":9483,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:09.212963 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:09.235193 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.022s	user 0.006s	sys 0.015s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":8694,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:09.236632 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:09.699306 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.462s	user 0.260s	sys 0.168s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979750,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":997,"lbm_read_time_us":29181,"lbm_reads_lt_1ms":774,"lbm_write_time_us":84601,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":4096,"thread_start_us":1150,"threads_started":7,"update_count":3500}
I20260812 06:17:09.700500 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=18.063937
I20260812 06:17:09.853161 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.152s	user 0.078s	sys 0.072s Metrics: {"bytes_written":20512320,"delete_count":0,"lbm_write_time_us":72048,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:09.854310 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:09.889971 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.035s	user 0.027s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":15004,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:09.890853 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:10.279508 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.388s	user 0.322s	sys 0.065s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877107,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":2293,"lbm_read_time_us":45127,"lbm_reads_lt_1ms":664,"lbm_write_time_us":69341,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"thread_start_us":850,"threads_started":6,"update_count":3000}
I20260812 06:17:10.280961 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=14.095187
I20260812 06:17:10.381690 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.100s	user 0.068s	sys 0.029s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":46597,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:10.382608 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:10.430933 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.048s	user 0.008s	sys 0.024s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":15104,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:10.432096 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:10.464758 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.032s	user 0.023s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":12400,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:10.465731 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:10.792953 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.327s	user 0.263s	sys 0.063s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877221,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":2102,"lbm_read_time_us":29846,"lbm_reads_lt_1ms":673,"lbm_write_time_us":70584,"lbm_writes_lt_1ms":643,"mutex_wait_us":150,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2304,"thread_start_us":737,"threads_started":5,"update_count":3000}
I20260812 06:17:10.794142 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=14.095187
I20260812 06:17:10.888844 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.094s	user 0.060s	sys 0.032s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":43613,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:10.890715 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:10.946734 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.055s	user 0.030s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":13557,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:10.947734 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:10.971405 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.023s	user 0.019s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":8314,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:10.972679 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:11.316640 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.343s	user 0.256s	sys 0.087s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877220,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":1324,"lbm_read_time_us":29775,"lbm_reads_lt_1ms":673,"lbm_write_time_us":74785,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":6912,"thread_start_us":600,"threads_started":5,"update_count":3000}
I20260812 06:17:11.317771 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=14.095187
I20260812 06:17:11.385355 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.067s	user 0.036s	sys 0.027s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":31628,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:11.385979 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:11.411801 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.026s	user 0.003s	sys 0.013s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6540,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:11.412340 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:11.423709 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.011s	user 0.005s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4192,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:11.424356 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:11.591950 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.167s	user 0.131s	sys 0.036s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877218,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":263,"lbm_read_time_us":12399,"lbm_reads_lt_1ms":673,"lbm_write_time_us":37115,"lbm_writes_lt_1ms":643,"mutex_wait_us":32,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":7296,"update_count":3000}
I20260812 06:17:11.592695 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=14.095187
I20260812 06:17:11.657174 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.064s	user 0.038s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":32109,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:11.657707 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=2.188937
I20260812 06:17:11.671029 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102660,"delete_count":0,"lbm_write_time_us":5344,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:11.671557 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:11.703320 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.032s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":82,"dirs.run_cpu_time_us":306,"dirs.run_wall_time_us":1562,"drs_written":1,"lbm_read_time_us":42,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1538,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:17:11.704212 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling LogGCOp(06b789dfdc674c63ae093981ab8f6db1): free 132571314 bytes of WAL
I20260812 06:17:11.704471 30535 log_reader.cc:385] T 06b789dfdc674c63ae093981ab8f6db1: removed 13 log segments from log reader
I20260812 06:17:11.704537 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000014 (ops 66-70)
I20260812 06:17:11.704593 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000015 (ops 71-75)
I20260812 06:17:11.704651 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000016 (ops 76-80)
I20260812 06:17:11.704694 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000017 (ops 81-85)
I20260812 06:17:11.704730 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000018 (ops 86-90)
I20260812 06:17:11.704774 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000019 (ops 91-94)
I20260812 06:17:11.704811 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000020 (ops 95-99)
I20260812 06:17:11.704857 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000021 (ops 100-104)
I20260812 06:17:11.704895 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000022 (ops 105-109)
I20260812 06:17:11.704932 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000023 (ops 110-114)
I20260812 06:17:11.704970 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000024 (ops 115-118)
I20260812 06:17:11.705008 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000025 (ops 119-123)
I20260812 06:17:11.705044 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000026 (ops 124-128)
I20260812 06:17:11.735606 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: LogGCOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.031s	user 0.000s	sys 0.031s Metrics: {}
I20260812 06:17:11.736063 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:11.762322 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.026s	user 0.014s	sys 0.009s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10914,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:11.762887 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:11.995411 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.232s	user 0.164s	sys 0.052s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979633,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":3230,"lbm_read_time_us":15611,"lbm_reads_lt_1ms":765,"lbm_write_time_us":49130,"lbm_writes_lt_1ms":743,"mutex_wait_us":2302,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":6784,"thread_start_us":149,"threads_started":1,"update_count":3500}
I20260812 06:17:11.996088 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=18.063937
I20260812 06:17:12.100637 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.104s	user 0.036s	sys 0.020s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":25502,"lbm_writes_lt_1ms":503,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":2500}
I20260812 06:17:12.101173 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling UndoDeltaBlockGCOp(06b789dfdc674c63ae093981ab8f6db1): 484 bytes on disk
I20260812 06:17:12.101825 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: UndoDeltaBlockGCOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":49,"lbm_reads_lt_1ms":4}
I20260812 06:17:12.102406 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:12.190354 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.088s	user 0.007s	sys 0.012s Metrics: {"bytes_written":8246103,"delete_count":0,"lbm_write_time_us":8536,"lbm_writes_lt_1ms":204,"reinsert_count":0,"update_count":1005}
I20260812 06:17:12.191123 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=7.149875
I20260812 06:17:12.288787 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.097s	user 0.023s	sys 0.005s Metrics: {"bytes_written":8574299,"delete_count":0,"lbm_write_time_us":12249,"lbm_writes_lt_1ms":212,"reinsert_count":0,"update_count":1045}
I20260812 06:17:12.289284 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:12.396157 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.107s	user 0.017s	sys 0.004s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":8602,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:12.396914 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:12.496969 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.100s	user 0.017s	sys 0.004s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":9819,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:12.497556 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:12.601053 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.103s	user 0.012s	sys 0.008s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":9246,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:12.601768 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=10.126437
I20260812 06:17:12.707314 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.105s	user 0.024s	sys 0.012s Metrics: {"bytes_written":11897249,"delete_count":0,"lbm_write_time_us":15033,"lbm_writes_lt_1ms":293,"reinsert_count":0,"update_count":1450}
I20260812 06:17:12.708827 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:12.810043 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.101s	user 0.013s	sys 0.012s Metrics: {"bytes_written":8205079,"delete_count":0,"lbm_write_time_us":11208,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:12.810851 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:12.912636 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.102s	user 0.025s	sys 0.005s Metrics: {"bytes_written":8205079,"delete_count":0,"lbm_write_time_us":13242,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:12.913697 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:13.015795 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.102s	user 0.012s	sys 0.012s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":9770,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:13.016695 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=7.149875
I20260812 06:17:13.116726 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.099s	user 0.015s	sys 0.016s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":13997,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:17:13.117511 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:13.216136 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.097s	user 0.019s	sys 0.003s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":9843,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:13.216756 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=8.142062
I20260812 06:17:13.319815 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.103s	user 0.023s	sys 0.007s Metrics: {"bytes_written":10174235,"delete_count":0,"lbm_write_time_us":13236,"lbm_writes_lt_1ms":251,"reinsert_count":0,"update_count":1240}
I20260812 06:17:13.320631 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=8.142062
I20260812 06:17:13.423220 30417 heavy-update-compaction-itest.cc:229] Time spent updating: real 7.012s	user 2.385s	sys 0.173s
I20260812 06:17:13.423336 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.103s	user 0.026s	sys 0.009s Metrics: {"bytes_written":9928100,"delete_count":0,"lbm_write_time_us":14594,"lbm_writes_lt_1ms":245,"reinsert_count":0,"update_count":1210}
I20260812 06:17:13.423869 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1): perf score=6.157687
I20260812 06:17:13.524644 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushDeltaMemStoresOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.101s	user 0.014s	sys 0.005s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":9273,"lbm_writes_lt_1ms":203,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":1000}
I20260812 06:17:13.525255 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:13.631785 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: FlushMRSOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.106s	user 0.022s	sys 0.010s Metrics: {"bytes_written":1521477,"cfile_init":1,"dirs.queue_time_us":252,"dirs.run_cpu_time_us":309,"dirs.run_wall_time_us":76673,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2279,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":37,"thread_start_us":119,"threads_started":1}
I20260812 06:17:13.632568 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling LogGCOp(06b789dfdc674c63ae093981ab8f6db1): free 142244857 bytes of WAL
I20260812 06:17:13.632872 30535 log_reader.cc:385] T 06b789dfdc674c63ae093981ab8f6db1: removed 14 log segments from log reader
I20260812 06:17:13.632943 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000027 (ops 129-133)
I20260812 06:17:13.633039 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000028 (ops 134-138)
I20260812 06:17:13.633100 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000029 (ops 139-143)
I20260812 06:17:13.633149 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000030 (ops 144-148)
I20260812 06:17:13.633193 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000031 (ops 149-153)
I20260812 06:17:13.633239 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000032 (ops 154-158)
I20260812 06:17:13.633282 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000033 (ops 159-163)
I20260812 06:17:13.633325 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000034 (ops 164-168)
I20260812 06:17:13.633371 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000035 (ops 169-173)
I20260812 06:17:13.633414 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000036 (ops 174-178)
I20260812 06:17:13.633476 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000037 (ops 179-183)
I20260812 06:17:13.633517 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000038 (ops 184-188)
I20260812 06:17:13.633587 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000039 (ops 189-193)
I20260812 06:17:13.633646 30535 log.cc:1079] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/06b789dfdc674c63ae093981ab8f6db1/wal-000000040 (ops 194-198)
I20260812 06:17:13.663527 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: LogGCOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 0.031s	user 0.003s	sys 0.028s Metrics: {}
I20260812 06:17:13.663966 30603 maintenance_manager.cc:419] P 43ee51c74d8448f3bf4e98be491ddd2f: Scheduling MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1): perf score=1.000000
I20260812 06:17:13.874554 30417 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.450s	user 0.002s	sys 0.000s
I20260812 06:17:13.875438 30417 tablet_server.cc:179] TabletServer@127.29.180.65:0 shutting down...
I20260812 06:17:15.886482 30535 maintenance_manager.cc:643] P 43ee51c74d8448f3bf4e98be491ddd2f: MajorDeltaCompactionOp(06b789dfdc674c63ae093981ab8f6db1) complete. Timing: real 2.222s	user 0.557s	sys 1.634s Metrics: {"cfile_cache_hit":3314,"cfile_cache_hit_bytes":135381295,"cfile_cache_miss":231,"cfile_cache_miss_bytes":12467333,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":15,"delta_iterators_relevant":15,"dirs.queue_time_us":1585,"lbm_read_time_us":7499,"lbm_reads_lt_1ms":263,"lbm_write_time_us":871395,"lbm_writes_1-10_ms":15,"lbm_writes_10-100_ms":2,"lbm_writes_lt_1ms":3529,"mutex_wait_us":248,"peak_mem_usage":435913060,"reinsert_count":0,"spinlock_wait_cycles":3456,"thread_start_us":605,"threads_started":7,"update_count":17500,"wal-append.queue_time_us":246}
I20260812 06:17:15.887392 30417 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:15.887897 30417 tablet_replica.cc:333] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f: stopping tablet replica
I20260812 06:17:15.888207 30417 raft_consensus.cc:2243] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:15.888496 30417 raft_consensus.cc:2272] T 06b789dfdc674c63ae093981ab8f6db1 P 43ee51c74d8448f3bf4e98be491ddd2f [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:15.904809 30417 tablet_server.cc:196] TabletServer@127.29.180.65:0 shutdown complete.
I20260812 06:17:16.417913 30417 master.cc:562] Master@127.29.180.126:38749 shutting down...
I20260812 06:17:16.422011 30417 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:16.422231 30417 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:16.422330 30417 tablet_replica.cc:333] T 00000000000000000000000000000000 P 330d91c0b99c431ababfbf86d4b09960: stopping tablet replica
I20260812 06:17:16.436319 30417 master.cc:584] Master@127.29.180.126:38749 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (10436 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:17:16.546839 30417 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.29.180.126:46003
I20260812 06:17:16.547341 30417 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:16.549986 30692 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:16.550069 30690 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:16.550125 30417 server_base.cc:1061] running on GCE node
W20260812 06:17:16.550187 30688 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:16.550485 30417 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:16.550534 30417 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:16.550573 30417 hybrid_clock.cc:648] HybridClock initialized: now 1786515436550572 us; error 0 us; skew 500 ppm
I20260812 06:17:16.551697 30417 webserver.cc:533] Webserver started at http://127.29.180.126:37019/ using document root <none> and password file <none>
I20260812 06:17:16.551885 30417 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:16.551946 30417 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:16.552030 30417 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:16.552413 30417 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/master-0-root/instance:
uuid: "bf55950395364bb89f8b546e8a8c0b21"
format_stamp: "Formatted at 2026-08-12 06:17:16 on dist-test-slave-2w3w"
I20260812 06:17:16.553934 30417 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:17:16.555052 30699 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:16.555369 30417 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:16.555464 30417 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/master-0-root
uuid: "bf55950395364bb89f8b546e8a8c0b21"
format_stamp: "Formatted at 2026-08-12 06:17:16 on dist-test-slave-2w3w"
I20260812 06:17:16.555560 30417 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-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:16.594471 30417 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:16.595044 30417 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:16.599436 30417 rpc_server.cc:307] RPC server started. Bound to: 127.29.180.126:46003
I20260812 06:17:16.601079 30760 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:16.608592 30760 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21: Bootstrap starting.
I20260812 06:17:16.612325 30757 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.180.126:46003 every 8 connection(s)
I20260812 06:17:16.612950 30760 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:16.614125 30760 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21: No bootstrap required, opened a new log
I20260812 06:17:16.614584 30760 raft_consensus.cc:359] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bf55950395364bb89f8b546e8a8c0b21" member_type: VOTER }
I20260812 06:17:16.614681 30760 raft_consensus.cc:385] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:16.614704 30760 raft_consensus.cc:740] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: bf55950395364bb89f8b546e8a8c0b21, State: Initialized, Role: FOLLOWER
I20260812 06:17:16.614848 30760 consensus_queue.cc:260] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [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: "bf55950395364bb89f8b546e8a8c0b21" member_type: VOTER }
I20260812 06:17:16.614916 30760 raft_consensus.cc:399] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:16.614941 30760 raft_consensus.cc:493] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:16.615151 30760 raft_consensus.cc:3060] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:16.615917 30760 raft_consensus.cc:515] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bf55950395364bb89f8b546e8a8c0b21" member_type: VOTER }
I20260812 06:17:16.616044 30760 leader_election.cc:304] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [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: bf55950395364bb89f8b546e8a8c0b21; no voters: 
I20260812 06:17:16.616214 30760 leader_election.cc:290] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:16.616385 30764 raft_consensus.cc:2804] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:16.616600 30764 raft_consensus.cc:697] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 1 LEADER]: Becoming Leader. State: Replica: bf55950395364bb89f8b546e8a8c0b21, State: Running, Role: LEADER
I20260812 06:17:16.616775 30764 consensus_queue.cc:237] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [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: "bf55950395364bb89f8b546e8a8c0b21" member_type: VOTER }
I20260812 06:17:16.616794 30760 sys_catalog.cc:565] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:16.617319 30766 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [sys.catalog]: SysCatalogTable state changed. Reason: New leader bf55950395364bb89f8b546e8a8c0b21. Latest consensus state: current_term: 1 leader_uuid: "bf55950395364bb89f8b546e8a8c0b21" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bf55950395364bb89f8b546e8a8c0b21" member_type: VOTER } }
I20260812 06:17:16.617285 30765 sys_catalog.cc:455] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "bf55950395364bb89f8b546e8a8c0b21" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "bf55950395364bb89f8b546e8a8c0b21" member_type: VOTER } }
I20260812 06:17:16.617394 30766 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:16.617408 30765 sys_catalog.cc:458] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:16.617658 30768 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:16.618468 30768 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:16.619024 30417 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:16.620580 30768 catalog_manager.cc:1383] Generated new cluster ID: 5059aa793e9249a3b9cfd1a8aa4a2100
I20260812 06:17:16.620637 30768 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:16.636623 30768 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:16.637210 30768 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:16.647480 30768 catalog_manager.cc:6092] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21: Generated new TSK 0
I20260812 06:17:16.647671 30768 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:16.651507 30417 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:17:16.653614 30417 server_base.cc:1061] running on GCE node
W20260812 06:17:16.653614 30782 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:16.653862 30785 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:16.654032 30783 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:16.654295 30417 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:16.654361 30417 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:16.654397 30417 hybrid_clock.cc:648] HybridClock initialized: now 1786515436654396 us; error 0 us; skew 500 ppm
I20260812 06:17:16.655339 30417 webserver.cc:533] Webserver started at http://127.29.180.65:46463/ using document root <none> and password file <none>
I20260812 06:17:16.655519 30417 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:16.655593 30417 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:16.655673 30417 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:16.656062 30417 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/instance:
uuid: "c5bfa4f7bf9c4918a07bbf692d2ae007"
format_stamp: "Formatted at 2026-08-12 06:17:16 on dist-test-slave-2w3w"
I20260812 06:17:16.657644 30417 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:17:16.658646 30792 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:16.658921 30417 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:16.659030 30417 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root
uuid: "c5bfa4f7bf9c4918a07bbf692d2ae007"
format_stamp: "Formatted at 2026-08-12 06:17:16 on dist-test-slave-2w3w"
I20260812 06:17:16.659118 30417 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-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:16.686421 30417 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:16.686895 30417 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:16.687285 30417 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:16.687810 30417 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:16.687871 30417 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:16.687922 30417 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:16.687956 30417 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:16.693055 30417 rpc_server.cc:307] RPC server started. Bound to: 127.29.180.65:46243
I20260812 06:17:16.693094 30864 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.29.180.65:46243 every 8 connection(s)
I20260812 06:17:16.703073 30866 heartbeater.cc:344] Connected to a master server at 127.29.180.126:46003
I20260812 06:17:16.703210 30866 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:16.703591 30866 heartbeater.cc:507] Master 127.29.180.126:46003 requested a full tablet report, sending...
I20260812 06:17:16.704389 30719 ts_manager.cc:194] Registered new tserver with Master: c5bfa4f7bf9c4918a07bbf692d2ae007 (127.29.180.65:46243)
I20260812 06:17:16.704705 30417 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.011170937s
I20260812 06:17:16.705205 30719 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:41220
I20260812 06:17:16.712877 30719 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:41234:
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:16.722617 30824 tablet_service.cc:1511] Processing CreateTablet for tablet cc2b8704ae76449086b3f94478760d0e (DEFAULT_TABLE table=heavy-update-compaction-test [id=b44a9e95e21a475581534f402857d061]), partition=
I20260812 06:17:16.722895 30824 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet cc2b8704ae76449086b3f94478760d0e. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:16.725142 30881 tablet_bootstrap.cc:492] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Bootstrap starting.
I20260812 06:17:16.726104 30881 tablet_bootstrap.cc:654] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:16.727283 30881 tablet_bootstrap.cc:492] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: No bootstrap required, opened a new log
I20260812 06:17:16.727402 30881 ts_tablet_manager.cc:1403] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:16.727891 30881 raft_consensus.cc:359] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c5bfa4f7bf9c4918a07bbf692d2ae007" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 46243 } }
I20260812 06:17:16.728015 30881 raft_consensus.cc:385] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:16.728047 30881 raft_consensus.cc:740] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c5bfa4f7bf9c4918a07bbf692d2ae007, State: Initialized, Role: FOLLOWER
I20260812 06:17:16.728238 30881 consensus_queue.cc:260] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [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: "c5bfa4f7bf9c4918a07bbf692d2ae007" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 46243 } }
I20260812 06:17:16.728356 30881 raft_consensus.cc:399] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:16.728405 30881 raft_consensus.cc:493] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:16.728461 30881 raft_consensus.cc:3060] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:16.729218 30881 raft_consensus.cc:515] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c5bfa4f7bf9c4918a07bbf692d2ae007" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 46243 } }
I20260812 06:17:16.729388 30881 leader_election.cc:304] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [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: c5bfa4f7bf9c4918a07bbf692d2ae007; no voters: 
I20260812 06:17:16.729605 30881 leader_election.cc:290] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:16.729744 30883 raft_consensus.cc:2804] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:16.729990 30866 heartbeater.cc:499] Master 127.29.180.126:46003 was elected leader, sending a full tablet report...
I20260812 06:17:16.729987 30883 raft_consensus.cc:697] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 1 LEADER]: Becoming Leader. State: Replica: c5bfa4f7bf9c4918a07bbf692d2ae007, State: Running, Role: LEADER
I20260812 06:17:16.730078 30881 ts_tablet_manager.cc:1434] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:17:16.730204 30883 consensus_queue.cc:237] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [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: "c5bfa4f7bf9c4918a07bbf692d2ae007" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 46243 } }
I20260812 06:17:16.731652 30719 catalog_manager.cc:5719] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 reported cstate change: term changed from 0 to 1, leader changed from <none> to c5bfa4f7bf9c4918a07bbf692d2ae007 (127.29.180.65). New cstate: current_term: 1 leader_uuid: "c5bfa4f7bf9c4918a07bbf692d2ae007" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c5bfa4f7bf9c4918a07bbf692d2ae007" member_type: VOTER last_known_addr { host: "127.29.180.65" port: 46243 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:16.798035 30417 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.059s	user 0.015s	sys 0.008s
I20260812 06:17:16.944051 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushMRSOp(cc2b8704ae76449086b3f94478760d0e): perf score=15.086190
I20260812 06:17:17.210127 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushMRSOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.266s	user 0.201s	sys 0.060s Metrics: {"bytes_written":16409901,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":49,"dirs.run_cpu_time_us":195,"dirs.run_wall_time_us":908,"drs_written":1,"lbm_read_time_us":92,"lbm_reads_lt_1ms":4,"lbm_write_time_us":59498,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":103,"update_count":2000}
I20260812 06:17:17.211464 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling LogGCOp(cc2b8704ae76449086b3f94478760d0e): free 11976772 bytes of WAL
I20260812 06:17:17.216171 30797 log_reader.cc:385] T cc2b8704ae76449086b3f94478760d0e: removed 1 log segments from log reader
I20260812 06:17:17.216315 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000001 (ops 1-6)
I20260812 06:17:17.220333 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: LogGCOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.004s	user 0.000s	sys 0.003s Metrics: {}
I20260812 06:17:17.221010 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e): 12308960 bytes on disk
I20260812 06:17:17.224697 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":114,"lbm_reads_lt_1ms":4}
I20260812 06:17:17.225489 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:17.252843 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.027s	user 0.009s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7589,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.253525 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:17.267498 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.014s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5124,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.267946 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:17.478206 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.210s	user 0.131s	sys 0.073s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28836254,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":684,"lbm_read_time_us":15972,"lbm_reads_lt_1ms":669,"lbm_write_time_us":35902,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":5120,"thread_start_us":357,"threads_started":5,"update_count":3000}
I20260812 06:17:17.478950 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=18.063937
I20260812 06:17:17.535054 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.056s	user 0.039s	sys 0.017s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":25816,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:17.535610 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:17.548890 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.013s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5495,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.549412 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:17.737181 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.188s	user 0.137s	sys 0.051s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28836137,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":177,"lbm_read_time_us":14380,"lbm_reads_lt_1ms":672,"lbm_write_time_us":37056,"lbm_writes_lt_1ms":643,"mutex_wait_us":51,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":11264,"update_count":3000}
I20260812 06:17:17.737738 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:17.797303 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.059s	user 0.045s	sys 0.012s Metrics: {"bytes_written":16409897,"delete_count":0,"lbm_write_time_us":26884,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:17.797978 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:17.815619 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.017s	user 0.001s	sys 0.015s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6837,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:17.816282 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:17.986351 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.170s	user 0.149s	sys 0.020s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733719,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":301,"lbm_read_time_us":12908,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30992,"lbm_writes_lt_1ms":543,"mutex_wait_us":50,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":61568,"update_count":2500}
I20260812 06:17:17.986933 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=12.110812
I20260812 06:17:18.041205 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.054s	user 0.023s	sys 0.029s Metrics: {"bytes_written":13784352,"delete_count":0,"lbm_write_time_us":24933,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":338,"reinsert_count":0,"update_count":1680}
I20260812 06:17:18.041709 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.196750
I20260812 06:17:18.058164 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.016s	user 0.003s	sys 0.004s Metrics: {"bytes_written":3036009,"delete_count":0,"lbm_write_time_us":3376,"lbm_writes_lt_1ms":77,"reinsert_count":0,"update_count":370}
I20260812 06:17:18.058660 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:18.069113 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3981,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:18.069770 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:18.249447 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.179s	user 0.135s	sys 0.044s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24733801,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":2322,"lbm_read_time_us":13365,"lbm_reads_lt_1ms":573,"lbm_write_time_us":31555,"lbm_writes_lt_1ms":543,"mutex_wait_us":661,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:17:18.250079 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:18.311805 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.062s	user 0.022s	sys 0.023s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":20670,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:18.312465 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:18.323868 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.011s	user 0.001s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4616,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:18.324326 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:18.510447 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.186s	user 0.117s	sys 0.067s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733724,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":368,"lbm_read_time_us":13728,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32466,"lbm_writes_lt_1ms":543,"mutex_wait_us":64,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":33024,"update_count":2500}
I20260812 06:17:18.512859 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:18.582877 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.070s	user 0.032s	sys 0.030s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23070,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:18.583578 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:18.600962 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.017s	user 0.014s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6569,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:18.601655 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushMRSOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:18.644805 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushMRSOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.043s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1316414,"cfile_init":1,"dirs.queue_time_us":76,"dirs.run_cpu_time_us":240,"dirs.run_wall_time_us":1473,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1823,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:17:18.645500 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling LogGCOp(cc2b8704ae76449086b3f94478760d0e): free 133024354 bytes of WAL
I20260812 06:17:18.645732 30797 log_reader.cc:385] T cc2b8704ae76449086b3f94478760d0e: removed 13 log segments from log reader
I20260812 06:17:18.645777 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000002 (ops 7-11)
I20260812 06:17:18.645804 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000003 (ops 12-16)
I20260812 06:17:18.645871 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000004 (ops 17-21)
I20260812 06:17:18.645915 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000005 (ops 22-26)
I20260812 06:17:18.645962 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000006 (ops 27-31)
I20260812 06:17:18.646006 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000007 (ops 32-36)
I20260812 06:17:18.646068 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000008 (ops 37-41)
I20260812 06:17:18.646098 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000009 (ops 42-46)
I20260812 06:17:18.646139 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000010 (ops 47-51)
I20260812 06:17:18.646179 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000011 (ops 52-56)
I20260812 06:17:18.646219 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000012 (ops 57-61)
I20260812 06:17:18.646258 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000013 (ops 62-66)
I20260812 06:17:18.646299 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000014 (ops 67-70)
I20260812 06:17:18.679687 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: LogGCOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.034s	user 0.000s	sys 0.033s Metrics: {}
I20260812 06:17:18.680310 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=3.181125
I20260812 06:17:18.695292 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.015s	user 0.007s	sys 0.006s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":4984,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:18.695806 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e): 491 bytes on disk
I20260812 06:17:18.696213 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4}
I20260812 06:17:18.696645 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:18.707193 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.010s	user 0.010s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4044,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:18.707798 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:18.942165 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.234s	user 0.134s	sys 0.100s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32938775,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":966,"lbm_read_time_us":17928,"lbm_reads_lt_1ms":774,"lbm_write_time_us":40917,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":28032,"thread_start_us":133,"threads_started":1,"update_count":3500}
I20260812 06:17:18.942981 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:18.995126 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.052s	user 0.034s	sys 0.017s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":23537,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:18.995625 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:19.011919 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.016s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6352,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:19.012548 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:19.210798 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.198s	user 0.154s	sys 0.034s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733726,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":351,"lbm_read_time_us":14225,"lbm_reads_lt_1ms":564,"lbm_write_time_us":36075,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":542,"mutex_wait_us":26,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10496,"update_count":2500}
I20260812 06:17:19.211493 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:19.270617 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.059s	user 0.042s	sys 0.007s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":24537,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:19.271214 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:19.441741 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.170s	user 0.122s	sys 0.043s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20631193,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":955,"lbm_read_time_us":11548,"lbm_reads_lt_1ms":463,"lbm_write_time_us":28639,"lbm_writes_lt_1ms":443,"mutex_wait_us":284,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:17:19.442369 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:19.502781 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.060s	user 0.040s	sys 0.017s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":27816,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:19.503299 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:19.515808 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.012s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4907,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:19.516340 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:19.720453 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.204s	user 0.149s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733724,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":271,"lbm_read_time_us":13286,"lbm_reads_lt_1ms":572,"lbm_write_time_us":35119,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":540,"mutex_wait_us":47,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":37888,"update_count":2500}
I20260812 06:17:19.721117 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:19.783667 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.062s	user 0.042s	sys 0.019s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":26810,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:19.784261 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:19.797850 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5022,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:19.798424 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:19.969628 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.171s	user 0.141s	sys 0.027s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733723,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":226,"lbm_read_time_us":11668,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31992,"lbm_writes_lt_1ms":543,"mutex_wait_us":30,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":57216,"update_count":2500}
I20260812 06:17:19.970398 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:20.025604 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.055s	user 0.036s	sys 0.007s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19767,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:20.026201 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:20.042829 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.016s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6447,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:20.043705 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:20.207309 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.163s	user 0.113s	sys 0.049s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733723,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":818,"lbm_read_time_us":11825,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33552,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11776,"update_count":2500}
I20260812 06:17:20.207892 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=11.118625
I20260812 06:17:20.249730 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.042s	user 0.022s	sys 0.016s Metrics: {"bytes_written":12717736,"delete_count":0,"lbm_write_time_us":18259,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:17:20.250589 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:20.267616 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.017s	user 0.011s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5403,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:20.268252 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushMRSOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:20.320461 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushMRSOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.052s	user 0.030s	sys 0.000s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":228,"dirs.run_wall_time_us":1883,"drs_written":1,"lbm_read_time_us":63,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2522,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:17:20.321171 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling LogGCOp(cc2b8704ae76449086b3f94478760d0e): free 121006384 bytes of WAL
I20260812 06:17:20.321415 30797 log_reader.cc:385] T cc2b8704ae76449086b3f94478760d0e: removed 12 log segments from log reader
I20260812 06:17:20.321462 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000015 (ops 71-75)
I20260812 06:17:20.321491 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000016 (ops 76-80)
I20260812 06:17:20.321542 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000017 (ops 81-85)
I20260812 06:17:20.321586 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000018 (ops 86-90)
I20260812 06:17:20.321633 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000019 (ops 91-95)
I20260812 06:17:20.321677 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000020 (ops 96-100)
I20260812 06:17:20.321725 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000021 (ops 101-104)
I20260812 06:17:20.321768 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000022 (ops 105-109)
I20260812 06:17:20.321811 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000023 (ops 110-114)
I20260812 06:17:20.321849 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000024 (ops 115-119)
I20260812 06:17:20.321890 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000025 (ops 120-124)
I20260812 06:17:20.321940 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000026 (ops 125-129)
I20260812 06:17:20.352295 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: LogGCOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.031s	user 0.000s	sys 0.030s Metrics: {}
I20260812 06:17:20.352771 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e): 482 bytes on disk
I20260812 06:17:20.353163 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e) 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:20.353827 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=7.149875
I20260812 06:17:20.379345 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.025s	user 0.024s	sys 0.000s Metrics: {"bytes_written":9394779,"delete_count":0,"lbm_write_time_us":10050,"lbm_writes_lt_1ms":232,"reinsert_count":0,"update_count":1145}
I20260812 06:17:20.380043 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling LogGCOp(cc2b8704ae76449086b3f94478760d0e): free 12018006 bytes of WAL
I20260812 06:17:20.380271 30797 log_reader.cc:385] T cc2b8704ae76449086b3f94478760d0e: removed 1 log segments from log reader
I20260812 06:17:20.380323 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000027 (ops 130-134)
I20260812 06:17:20.384002 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: LogGCOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.004s	user 0.000s	sys 0.003s Metrics: {}
I20260812 06:17:20.391515 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.196750
I20260812 06:17:20.406317 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.015s	user 0.006s	sys 0.001s Metrics: {"bytes_written":2912930,"delete_count":0,"lbm_write_time_us":3240,"lbm_writes_lt_1ms":74,"reinsert_count":0,"update_count":355}
I20260812 06:17:20.406816 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:20.663167 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.256s	user 0.157s	sys 0.099s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32938751,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":651,"lbm_read_time_us":19347,"lbm_reads_lt_1ms":766,"lbm_write_time_us":45333,"lbm_writes_lt_1ms":743,"mutex_wait_us":40,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":20608,"thread_start_us":78,"threads_started":1,"update_count":3500}
I20260812 06:17:20.663961 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=18.063937
I20260812 06:17:20.722313 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.058s	user 0.034s	sys 0.020s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":26091,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:20.722869 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:20.740758 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.018s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6181,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:20.741314 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:20.978021 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.236s	user 0.176s	sys 0.055s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28836137,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":430,"lbm_read_time_us":16708,"lbm_reads_lt_1ms":668,"lbm_write_time_us":37385,"lbm_writes_lt_1ms":643,"mutex_wait_us":33,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":6784,"update_count":3000}
I20260812 06:17:20.978849 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=18.063937
I20260812 06:17:21.059252 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.080s	user 0.038s	sys 0.029s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":32299,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:17:21.059808 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:21.072063 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.012s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4554,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:21.072570 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:21.292577 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.220s	user 0.126s	sys 0.094s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28836139,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":535,"lbm_read_time_us":15980,"lbm_reads_lt_1ms":672,"lbm_write_time_us":38865,"lbm_writes_lt_1ms":643,"mutex_wait_us":26,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":29056,"update_count":3000}
I20260812 06:17:21.293283 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=14.095187
I20260812 06:17:21.365139 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.072s	user 0.035s	sys 0.017s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":26617,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:21.365696 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=6.157687
I20260812 06:17:21.390918 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.025s	user 0.007s	sys 0.013s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":10128,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:17:21.391695 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:21.655400 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.263s	user 0.147s	sys 0.112s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28836135,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":333,"lbm_read_time_us":14829,"lbm_reads_lt_1ms":668,"lbm_write_time_us":69325,"lbm_writes_1-10_ms":13,"lbm_writes_lt_1ms":630,"peak_mem_usage":75542472,"reinsert_count":0,"update_count":3000}
I20260812 06:17:21.656342 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=18.063937
I20260812 06:17:21.730818 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.074s	user 0.042s	sys 0.028s Metrics: {"bytes_written":20512318,"delete_count":0,"lbm_write_time_us":33662,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:21.733536 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:21.762650 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.029s	user 0.007s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6520,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:21.763222 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:21.774931 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4647,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:21.775557 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:22.011667 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.236s	user 0.186s	sys 0.050s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32938670,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":226,"lbm_read_time_us":17224,"lbm_reads_lt_1ms":773,"lbm_write_time_us":44574,"lbm_writes_lt_1ms":743,"mutex_wait_us":32,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":45440,"update_count":3500}
I20260812 06:17:22.012611 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=18.063937
I20260812 06:17:22.075996 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.063s	user 0.043s	sys 0.019s Metrics: {"bytes_written":20512320,"delete_count":0,"lbm_write_time_us":29070,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:22.076530 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:22.089610 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.013s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4674,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:22.090077 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushMRSOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:22.115633 30417 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.317s	user 1.892s	sys 0.192s
I20260812 06:17:22.128827 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushMRSOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.039s	user 0.037s	sys 0.000s Metrics: {"bytes_written":1357582,"cfile_init":1,"dirs.queue_time_us":81,"dirs.run_cpu_time_us":255,"dirs.run_wall_time_us":1545,"drs_written":1,"lbm_read_time_us":57,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2665,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":33}
I20260812 06:17:22.129647 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling LogGCOp(cc2b8704ae76449086b3f94478760d0e): free 129773845 bytes of WAL
I20260812 06:17:22.129964 30797 log_reader.cc:385] T cc2b8704ae76449086b3f94478760d0e: removed 13 log segments from log reader
I20260812 06:17:22.130034 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000028 (ops 135-139)
I20260812 06:17:22.130084 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000029 (ops 140-144)
I20260812 06:17:22.130125 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000030 (ops 145-149)
I20260812 06:17:22.130157 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000031 (ops 150-154)
I20260812 06:17:22.130183 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000032 (ops 155-159)
I20260812 06:17:22.130223 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000033 (ops 160-164)
I20260812 06:17:22.130261 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000034 (ops 165-169)
I20260812 06:17:22.130295 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000035 (ops 170-174)
I20260812 06:17:22.130333 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000036 (ops 175-179)
I20260812 06:17:22.130362 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000037 (ops 180-184)
I20260812 06:17:22.130393 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000038 (ops 185-188)
I20260812 06:17:22.130429 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000039 (ops 189-193)
I20260812 06:17:22.130468 30797 log.cc:1079] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: Deleting log segment in path: /tmp/dist-test-taskqQBCbX/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515426084142-30417-0/minicluster-data/ts-0-root/wals/cc2b8704ae76449086b3f94478760d0e/wal-000000040 (ops 194-198)
I20260812 06:17:22.165798 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: LogGCOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.036s	user 0.000s	sys 0.033s Metrics: {}
I20260812 06:17:22.166329 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e): perf score=2.188937
I20260812 06:17:22.177119 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: FlushDeltaMemStoresOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4328,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:22.177697 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e): 507 bytes on disk
I20260812 06:17:22.178130 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: UndoDeltaBlockGCOp(cc2b8704ae76449086b3f94478760d0e) 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:22.178668 30867 maintenance_manager.cc:419] P c5bfa4f7bf9c4918a07bbf692d2ae007: Scheduling MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e): perf score=1.000000
I20260812 06:17:22.193485 30417 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.077s	user 0.001s	sys 0.000s
I20260812 06:17:22.194056 30417 tablet_server.cc:179] TabletServer@127.29.180.65:0 shutting down...
I20260812 06:17:22.323372 30797 maintenance_manager.cc:643] P c5bfa4f7bf9c4918a07bbf692d2ae007: MajorDeltaCompactionOp(cc2b8704ae76449086b3f94478760d0e) complete. Timing: real 0.145s	user 0.092s	sys 0.052s Metrics: {"cfile_cache_hit":632,"cfile_cache_hit_bytes":28836142,"cfile_cache_miss":101,"cfile_cache_miss_bytes":4102531,"cfile_init":3,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":1024,"lbm_read_time_us":1763,"lbm_reads_lt_1ms":113,"lbm_write_time_us":36392,"lbm_writes_lt_1ms":743,"mutex_wait_us":316,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":18816,"thread_start_us":81,"threads_started":1,"update_count":3500}
I20260812 06:17:22.324126 30417 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:22.324380 30417 tablet_replica.cc:333] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007: stopping tablet replica
I20260812 06:17:22.324575 30417 raft_consensus.cc:2243] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:22.324749 30417 raft_consensus.cc:2272] T cc2b8704ae76449086b3f94478760d0e P c5bfa4f7bf9c4918a07bbf692d2ae007 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:22.331257 30417 tablet_server.cc:196] TabletServer@127.29.180.65:0 shutdown complete.
I20260812 06:17:22.382094 30417 master.cc:562] Master@127.29.180.126:46003 shutting down...
I20260812 06:17:22.386066 30417 raft_consensus.cc:2243] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:22.386263 30417 raft_consensus.cc:2272] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:22.386325 30417 tablet_replica.cc:333] T 00000000000000000000000000000000 P bf55950395364bb89f8b546e8a8c0b21: stopping tablet replica
I20260812 06:17:22.398916 30417 master.cc:584] Master@127.29.180.126:46003 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5961 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (16398 ms total)

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