[==========] 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:56.740540 25233 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.24.164.126:33553
I20260812 06:17:56.741533 25233 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:56.742079 25233 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:56.747709 25247 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:56.747761 25242 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:56.747825 25233 server_base.cc:1061] running on GCE node
W20260812 06:17:56.747946 25245 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:56.748365 25233 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:56.748448 25233 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:56.748476 25233 hybrid_clock.cc:648] HybridClock initialized: now 1786515476748475 us; error 0 us; skew 500 ppm
I20260812 06:17:56.749984 25233 webserver.cc:533] Webserver started at http://127.24.164.126:44301/ using document root <none> and password file <none>
I20260812 06:17:56.750411 25233 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:56.750459 25233 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:56.750627 25233 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:56.752023 25233 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/master-0-root/instance:
uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313"
format_stamp: "Formatted at 2026-08-12 06:17:56 on dist-test-slave-2j7r"
I20260812 06:17:56.755038 25233 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.000s	sys 0.004s
I20260812 06:17:56.756749 25253 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:56.757627 25233 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.001s
I20260812 06:17:56.757717 25233 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/master-0-root
uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313"
format_stamp: "Formatted at 2026-08-12 06:17:56 on dist-test-slave-2j7r"
I20260812 06:17:56.757799 25233 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-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:56.786057 25233 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:56.786681 25233 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:56.786837 25233 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:56.794294 25349 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.164.126:33553 every 8 connection(s)
I20260812 06:17:56.794307 25233 rpc_server.cc:307] RPC server started. Bound to: 127.24.164.126:33553
I20260812 06:17:56.796408 25353 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:56.801613 25353 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313: Bootstrap starting.
I20260812 06:17:56.803776 25353 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:56.804591 25353 log.cc:826] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313: Log is configured to *not* fsync() on all Append() calls
I20260812 06:17:56.806159 25353 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313: No bootstrap required, opened a new log
I20260812 06:17:56.808727 25353 raft_consensus.cc:359] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" member_type: VOTER }
I20260812 06:17:56.808874 25353 raft_consensus.cc:385] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:56.808923 25353 raft_consensus.cc:740] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: ffef7ceb9e2f4cbaa0a779b7f4d6d313, State: Initialized, Role: FOLLOWER
I20260812 06:17:56.809523 25353 consensus_queue.cc:260] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [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: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" member_type: VOTER }
I20260812 06:17:56.809664 25353 raft_consensus.cc:399] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:56.809729 25353 raft_consensus.cc:493] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:56.809846 25353 raft_consensus.cc:3060] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:56.810529 25353 raft_consensus.cc:515] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" member_type: VOTER }
I20260812 06:17:56.810907 25353 leader_election.cc:304] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [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: ffef7ceb9e2f4cbaa0a779b7f4d6d313; no voters: 
I20260812 06:17:56.811177 25353 leader_election.cc:290] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:56.811280 25358 raft_consensus.cc:2804] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:56.811470 25358 raft_consensus.cc:697] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 1 LEADER]: Becoming Leader. State: Replica: ffef7ceb9e2f4cbaa0a779b7f4d6d313, State: Running, Role: LEADER
I20260812 06:17:56.811856 25358 consensus_queue.cc:237] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [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: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" member_type: VOTER }
I20260812 06:17:56.812038 25353 sys_catalog.cc:565] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:56.813548 25359 sys_catalog.cc:455] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" member_type: VOTER } }
I20260812 06:17:56.813544 25364 sys_catalog.cc:455] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [sys.catalog]: SysCatalogTable state changed. Reason: New leader ffef7ceb9e2f4cbaa0a779b7f4d6d313. Latest consensus state: current_term: 1 leader_uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ffef7ceb9e2f4cbaa0a779b7f4d6d313" member_type: VOTER } }
I20260812 06:17:56.813696 25364 sys_catalog.cc:458] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:56.813696 25359 sys_catalog.cc:458] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:56.814029 25379 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:56.814205 25233 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:56.816030 25379 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:56.819936 25379 catalog_manager.cc:1383] Generated new cluster ID: e71858504cee49f99ac4a158601b1d57
I20260812 06:17:56.819994 25379 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:56.834371 25379 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:56.835099 25379 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:56.840839 25379 catalog_manager.cc:6092] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313: Generated new TSK 0
I20260812 06:17:56.841368 25379 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:56.846674 25233 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:56.849324 25390 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:56.849377 25391 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:56.849414 25397 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:17:56.849685 25233 server_base.cc:1061] running on GCE node
I20260812 06:17:56.849843 25233 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:56.849891 25233 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:56.849918 25233 hybrid_clock.cc:648] HybridClock initialized: now 1786515476849918 us; error 0 us; skew 500 ppm
I20260812 06:17:56.850754 25233 webserver.cc:533] Webserver started at http://127.24.164.65:40263/ using document root <none> and password file <none>
I20260812 06:17:56.850908 25233 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:56.850960 25233 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:56.851032 25233 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:56.851433 25233 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/instance:
uuid: "db302115fe2c4417847834da66a0f052"
format_stamp: "Formatted at 2026-08-12 06:17:56 on dist-test-slave-2j7r"
I20260812 06:17:56.853116 25233 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:17:56.854146 25407 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:56.854403 25233 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:56.854475 25233 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root
uuid: "db302115fe2c4417847834da66a0f052"
format_stamp: "Formatted at 2026-08-12 06:17:56 on dist-test-slave-2j7r"
I20260812 06:17:56.854539 25233 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-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:56.874486 25233 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:56.874902 25233 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:56.875384 25233 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:56.876334 25233 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:56.876400 25233 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:56.876454 25233 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:56.876484 25233 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:56.882968 25233 rpc_server.cc:307] RPC server started. Bound to: 127.24.164.65:33097
I20260812 06:17:56.882992 25512 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.164.65:33097 every 8 connection(s)
I20260812 06:17:56.895089 25514 heartbeater.cc:344] Connected to a master server at 127.24.164.126:33553
I20260812 06:17:56.895301 25514 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:56.895694 25514 heartbeater.cc:507] Master 127.24.164.126:33553 requested a full tablet report, sending...
I20260812 06:17:56.896981 25289 ts_manager.cc:194] Registered new tserver with Master: db302115fe2c4417847834da66a0f052 (127.24.164.65:33097)
I20260812 06:17:56.897190 25233 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.013585082s
I20260812 06:17:56.898458 25289 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:57660
I20260812 06:17:56.905615 25289 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:57664:
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:56.918512 25459 tablet_service.cc:1511] Processing CreateTablet for tablet 8b9b277e169b49008ecdda99ed4913f6 (DEFAULT_TABLE table=heavy-update-compaction-test [id=2b28407c1bcb4916ab8ca25c2a5b48de]), partition=
I20260812 06:17:56.918902 25459 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 8b9b277e169b49008ecdda99ed4913f6. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:56.921159 25534 tablet_bootstrap.cc:492] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Bootstrap starting.
I20260812 06:17:56.922004 25534 tablet_bootstrap.cc:654] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:56.923240 25534 tablet_bootstrap.cc:492] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: No bootstrap required, opened a new log
I20260812 06:17:56.923321 25534 ts_tablet_manager.cc:1403] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:56.923678 25534 raft_consensus.cc:359] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "db302115fe2c4417847834da66a0f052" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 33097 } }
I20260812 06:17:56.923769 25534 raft_consensus.cc:385] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:56.923792 25534 raft_consensus.cc:740] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: db302115fe2c4417847834da66a0f052, State: Initialized, Role: FOLLOWER
I20260812 06:17:56.923925 25534 consensus_queue.cc:260] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [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: "db302115fe2c4417847834da66a0f052" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 33097 } }
I20260812 06:17:56.924002 25534 raft_consensus.cc:399] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:56.924038 25534 raft_consensus.cc:493] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:56.924086 25534 raft_consensus.cc:3060] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:56.924746 25534 raft_consensus.cc:515] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "db302115fe2c4417847834da66a0f052" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 33097 } }
I20260812 06:17:56.924862 25534 leader_election.cc:304] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [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: db302115fe2c4417847834da66a0f052; no voters: 
I20260812 06:17:56.925052 25534 leader_election.cc:290] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:56.925199 25538 raft_consensus.cc:2804] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:56.925385 25534 ts_tablet_manager.cc:1434] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Time spent starting tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:56.925465 25538 raft_consensus.cc:697] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 1 LEADER]: Becoming Leader. State: Replica: db302115fe2c4417847834da66a0f052, State: Running, Role: LEADER
I20260812 06:17:56.925621 25514 heartbeater.cc:499] Master 127.24.164.126:33553 was elected leader, sending a full tablet report...
I20260812 06:17:56.925640 25538 consensus_queue.cc:237] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [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: "db302115fe2c4417847834da66a0f052" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 33097 } }
I20260812 06:17:56.928143 25289 catalog_manager.cc:5719] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 reported cstate change: term changed from 0 to 1, leader changed from <none> to db302115fe2c4417847834da66a0f052 (127.24.164.65). New cstate: current_term: 1 leader_uuid: "db302115fe2c4417847834da66a0f052" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "db302115fe2c4417847834da66a0f052" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 33097 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:56.985981 25233 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.053s	user 0.022s	sys 0.002s
I20260812 06:17:57.134024 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6): perf score=23.023690
I20260812 06:17:57.327493 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.193s	user 0.142s	sys 0.036s Metrics: {"bytes_written":12307492,"cfile_init":1,"compiler_manager_pool.queue_time_us":192,"delete_count":0,"dirs.queue_time_us":45,"dirs.run_cpu_time_us":200,"dirs.run_wall_time_us":725,"drs_written":1,"lbm_read_time_us":76,"lbm_reads_lt_1ms":4,"lbm_write_time_us":45049,"lbm_writes_lt_1ms":857,"peak_mem_usage":0,"reinsert_count":0,"rows_written":105,"thread_start_us":103,"threads_started":1,"update_count":1500}
I20260812 06:17:57.328563 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling LogGCOp(8b9b277e169b49008ecdda99ed4913f6): free 20743880 bytes of WAL
I20260812 06:17:57.328855 25413 log_reader.cc:385] T 8b9b277e169b49008ecdda99ed4913f6: removed 2 log segments from log reader
I20260812 06:17:57.328923 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000001 (ops 1-6)
I20260812 06:17:57.328979 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000002 (ops 7-11)
I20260812 06:17:57.334074 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: LogGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.005s	user 0.001s	sys 0.003s Metrics: {}
I20260812 06:17:57.334440 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:57.348367 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.014s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4841,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:57.348803 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:57.481930 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.133s	user 0.085s	sys 0.048s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713274,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":428,"lbm_read_time_us":7407,"lbm_reads_lt_1ms":464,"lbm_write_time_us":20126,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1280,"thread_start_us":304,"threads_started":5,"update_count":2000}
I20260812 06:17:57.482443 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6): 20513816 bytes on disk
I20260812 06:17:57.482934 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":63,"lbm_reads_lt_1ms":4}
I20260812 06:17:57.483376 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=10.126437
I20260812 06:17:57.522322 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.039s	user 0.030s	sys 0.008s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16851,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:17:57.522768 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:57.537292 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.014s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5271,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:57.537744 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:57.654263 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.116s	user 0.094s	sys 0.022s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713272,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":171,"lbm_read_time_us":7625,"lbm_reads_lt_1ms":472,"lbm_write_time_us":22339,"lbm_writes_lt_1ms":443,"mutex_wait_us":17,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8960,"update_count":2000}
I20260812 06:17:57.654708 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=10.126437
I20260812 06:17:57.696106 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.041s	user 0.022s	sys 0.017s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":19693,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:17:57.696563 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:57.707016 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3533,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:57.707443 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:57.823963 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.116s	user 0.093s	sys 0.024s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713272,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":166,"lbm_read_time_us":7692,"lbm_reads_lt_1ms":472,"lbm_write_time_us":22655,"lbm_writes_lt_1ms":443,"mutex_wait_us":25,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":12544,"update_count":2000}
I20260812 06:17:57.824550 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=10.126437
I20260812 06:17:57.877481 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.053s	user 0.029s	sys 0.023s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":19655,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:17:57.877974 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:57.888917 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4000,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:57.889464 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:58.032437 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.143s	user 0.109s	sys 0.034s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713272,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":151,"lbm_read_time_us":10321,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26625,"lbm_writes_lt_1ms":443,"mutex_wait_us":23,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2944,"update_count":2000}
I20260812 06:17:58.033043 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=10.126437
I20260812 06:17:58.071763 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.038s	user 0.020s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16673,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:58.072288 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:58.090884 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.018s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6918,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:58.091333 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:58.235773 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.144s	user 0.085s	sys 0.048s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713272,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1031,"lbm_read_time_us":8963,"lbm_reads_lt_1ms":464,"lbm_write_time_us":30322,"lbm_writes_lt_1ms":443,"mutex_wait_us":387,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8192,"update_count":2000}
I20260812 06:17:58.236428 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:17:58.281646 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.045s	user 0.040s	sys 0.004s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19873,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:58.282133 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:58.292011 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.010s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3812,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:58.292821 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:58.433410 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.140s	user 0.091s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":962,"lbm_read_time_us":10145,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30008,"lbm_writes_lt_1ms":543,"mutex_wait_us":24,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":87168,"update_count":2500}
I20260812 06:17:58.433893 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=11.118625
I20260812 06:17:58.470678 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.037s	user 0.019s	sys 0.016s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":16387,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:17:58.471393 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:58.485780 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.014s	user 0.004s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4570,"lbm_writes_lt_1ms":93,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":450}
I20260812 06:17:58.486294 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:58.538589 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.052s	user 0.029s	sys 0.003s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":49,"dirs.run_cpu_time_us":221,"dirs.run_wall_time_us":1030,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1620,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:17:58.539359 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling LogGCOp(8b9b277e169b49008ecdda99ed4913f6): free 116849511 bytes of WAL
I20260812 06:17:58.539572 25413 log_reader.cc:385] T 8b9b277e169b49008ecdda99ed4913f6: removed 12 log segments from log reader
I20260812 06:17:58.539615 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000003 (ops 12-16)
I20260812 06:17:58.539645 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000004 (ops 17-20)
I20260812 06:17:58.539678 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000005 (ops 21-25)
I20260812 06:17:58.539705 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000006 (ops 26-30)
I20260812 06:17:58.539736 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000007 (ops 31-35)
I20260812 06:17:58.539767 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000008 (ops 36-40)
I20260812 06:17:58.539798 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000009 (ops 41-44)
I20260812 06:17:58.539829 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000010 (ops 45-49)
I20260812 06:17:58.539861 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000011 (ops 50-54)
I20260812 06:17:58.539893 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000012 (ops 55-58)
I20260812 06:17:58.539924 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000013 (ops 59-63)
I20260812 06:17:58.539956 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000014 (ops 64-68)
I20260812 06:17:58.559849 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: LogGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.020s	user 0.000s	sys 0.017s Metrics: {}
I20260812 06:17:58.560297 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=7.149875
I20260812 06:17:58.580299 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.020s	user 0.017s	sys 0.000s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":7837,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:17:58.580740 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling LogGCOp(8b9b277e169b49008ecdda99ed4913f6): free 12017876 bytes of WAL
I20260812 06:17:58.580937 25413 log_reader.cc:385] T 8b9b277e169b49008ecdda99ed4913f6: removed 1 log segments from log reader
I20260812 06:17:58.580996 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000015 (ops 69-73)
I20260812 06:17:58.583772 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: LogGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.003s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:17:58.584067 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:58.598256 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.014s	user 0.003s	sys 0.010s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5252,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:58.598686 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6): 472 bytes on disk
I20260812 06:17:58.599255 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":64,"lbm_reads_lt_1ms":4}
I20260812 06:17:58.599819 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:58.779567 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.180s	user 0.148s	sys 0.032s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020729,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":363,"lbm_read_time_us":11397,"lbm_reads_lt_1ms":766,"lbm_write_time_us":37738,"lbm_writes_lt_1ms":743,"mutex_wait_us":89,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":11520,"thread_start_us":75,"threads_started":1,"update_count":3500}
I20260812 06:17:58.780197 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:17:58.836483 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.056s	user 0.034s	sys 0.012s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":23197,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:58.836952 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=3.181125
I20260812 06:17:58.848640 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.011s	user 0.009s	sys 0.001s Metrics: {"bytes_written":4512904,"delete_count":0,"lbm_write_time_us":4454,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:58.849123 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:58.858073 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.009s	user 0.003s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3282,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:58.858584 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:59.015861 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.157s	user 0.117s	sys 0.040s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28918208,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":162,"lbm_read_time_us":13075,"lbm_reads_lt_1ms":673,"lbm_write_time_us":32512,"lbm_writes_lt_1ms":643,"mutex_wait_us":44,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":3000}
I20260812 06:17:59.017144 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:17:59.064848 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.047s	user 0.018s	sys 0.025s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":20408,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:59.065433 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:59.080821 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.015s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5994,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:59.081287 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:59.238086 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.157s	user 0.111s	sys 0.036s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":803,"lbm_read_time_us":9075,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30672,"lbm_writes_lt_1ms":543,"mutex_wait_us":1,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:17:59.238595 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:17:59.279378 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.041s	user 0.024s	sys 0.012s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":17006,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:59.279807 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:59.438756 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.159s	user 0.114s	sys 0.036s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20713153,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":904,"lbm_read_time_us":10097,"lbm_reads_lt_1ms":463,"lbm_write_time_us":25774,"lbm_writes_lt_1ms":443,"mutex_wait_us":323,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":4864,"update_count":2000}
I20260812 06:17:59.439201 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:17:59.485230 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.046s	user 0.019s	sys 0.023s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":18981,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:59.485811 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:59.499536 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.014s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5435,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:59.500160 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:59.666193 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.166s	user 0.104s	sys 0.055s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815680,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":162,"lbm_read_time_us":9997,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26824,"lbm_writes_lt_1ms":543,"mutex_wait_us":23,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11392,"update_count":2500}
I20260812 06:17:59.667052 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=11.118625
I20260812 06:17:59.702062 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.035s	user 0.030s	sys 0.004s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":15188,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:17:59.702682 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:59.725330 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.022s	user 0.001s	sys 0.010s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4140,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:59.725749 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:59.735255 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.009s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3751,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:59.735656 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:59.888967 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.153s	user 0.108s	sys 0.036s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24815795,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":457,"lbm_read_time_us":9058,"lbm_reads_lt_1ms":573,"lbm_write_time_us":27577,"lbm_writes_lt_1ms":543,"mutex_wait_us":23,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":55808,"update_count":2500}
I20260812 06:17:59.889503 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:17:59.931160 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.041s	user 0.028s	sys 0.007s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":16964,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:59.931717 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:17:59.941990 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.010s	user 0.004s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3896,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:59.942548 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:17:59.972736 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.030s	user 0.023s	sys 0.005s Metrics: {"bytes_written":1316419,"cfile_init":1,"dirs.queue_time_us":48,"dirs.run_cpu_time_us":146,"dirs.run_wall_time_us":988,"drs_written":1,"lbm_read_time_us":56,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1958,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32,"spinlock_wait_cycles":13184}
I20260812 06:17:59.973498 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling LogGCOp(8b9b277e169b49008ecdda99ed4913f6): free 124710371 bytes of WAL
I20260812 06:17:59.973716 25413 log_reader.cc:385] T 8b9b277e169b49008ecdda99ed4913f6: removed 12 log segments from log reader
I20260812 06:17:59.973776 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000016 (ops 74-78)
I20260812 06:17:59.973819 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000017 (ops 79-83)
I20260812 06:17:59.973855 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000018 (ops 84-88)
I20260812 06:17:59.973886 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000019 (ops 89-93)
I20260812 06:17:59.973915 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000020 (ops 94-98)
I20260812 06:17:59.973942 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000021 (ops 99-103)
I20260812 06:17:59.973971 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000022 (ops 104-108)
I20260812 06:17:59.974004 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000023 (ops 109-113)
I20260812 06:17:59.974035 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000024 (ops 114-118)
I20260812 06:17:59.974061 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000025 (ops 119-123)
I20260812 06:17:59.974088 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000026 (ops 124-128)
I20260812 06:17:59.974117 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000027 (ops 129-133)
I20260812 06:18:00.001192 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: LogGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.028s	user 0.000s	sys 0.027s Metrics: {}
I20260812 06:18:00.001693 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=4.173312
I20260812 06:18:00.026044 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.024s	user 0.007s	sys 0.016s Metrics: {"bytes_written":5497488,"delete_count":0,"lbm_write_time_us":6773,"lbm_writes_lt_1ms":137,"reinsert_count":0,"update_count":670}
I20260812 06:18:00.026451 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6): 493 bytes on disk
I20260812 06:18:00.026823 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":47,"lbm_reads_lt_1ms":4}
I20260812 06:18:00.027302 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.196750
I20260812 06:18:00.034164 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.007s	user 0.001s	sys 0.004s Metrics: {"bytes_written":2707805,"delete_count":0,"lbm_write_time_us":2394,"lbm_writes_lt_1ms":69,"reinsert_count":0,"update_count":330}
I20260812 06:18:00.034576 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:00.247587 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.213s	user 0.144s	sys 0.067s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020717,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1969,"lbm_read_time_us":13982,"lbm_reads_lt_1ms":774,"lbm_write_time_us":38723,"lbm_writes_lt_1ms":743,"mutex_wait_us":723,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":14208,"thread_start_us":74,"threads_started":1,"update_count":3500}
I20260812 06:18:00.248088 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:18:00.294358 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.046s	user 0.034s	sys 0.011s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":20399,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:00.294857 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:18:00.308542 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.014s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5198,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:00.308992 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:00.469754 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.161s	user 0.124s	sys 0.037s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":125,"lbm_read_time_us":10681,"lbm_reads_lt_1ms":564,"lbm_write_time_us":29986,"lbm_writes_lt_1ms":543,"mutex_wait_us":37,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11776,"update_count":2500}
I20260812 06:18:00.470257 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:18:00.522065 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.052s	user 0.025s	sys 0.024s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":18582,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:00.522535 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:18:00.532073 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3643,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:00.532456 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:00.705905 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.173s	user 0.110s	sys 0.056s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":176,"lbm_read_time_us":11518,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30092,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:00.706533 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:18:00.764046 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.057s	user 0.031s	sys 0.023s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22712,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:00.764663 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:18:00.779039 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4981,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:00.779520 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:00.937253 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.158s	user 0.112s	sys 0.043s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":985,"lbm_read_time_us":9456,"lbm_reads_lt_1ms":568,"lbm_write_time_us":26717,"lbm_writes_lt_1ms":543,"mutex_wait_us":305,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2560,"update_count":2500}
I20260812 06:18:00.937824 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:18:00.986770 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.049s	user 0.030s	sys 0.016s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":21123,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:00.987249 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:18:00.996807 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3581,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:00.997411 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:01.173446 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.176s	user 0.104s	sys 0.059s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815685,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":914,"lbm_read_time_us":9218,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32274,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":542,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:01.173962 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=14.095187
I20260812 06:18:01.218542 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.044s	user 0.023s	sys 0.014s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":17105,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:01.219102 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:18:01.228703 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3663,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:01.229336 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:01.372225 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.143s	user 0.133s	sys 0.008s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":529,"lbm_read_time_us":10666,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27447,"lbm_writes_lt_1ms":543,"mutex_wait_us":278,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7424,"update_count":2500}
I20260812 06:18:01.373457 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=10.126437
I20260812 06:18:01.411697 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.037s	user 0.014s	sys 0.021s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16366,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:01.412273 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=2.188937
I20260812 06:18:01.424263 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.012s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4551,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:01.424687 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:01.452190 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushMRSOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.027s	user 0.026s	sys 0.000s Metrics: {"bytes_written":1275447,"cfile_init":1,"dirs.queue_time_us":48,"dirs.run_cpu_time_us":202,"dirs.run_wall_time_us":1021,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1343,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:18:01.452989 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling LogGCOp(8b9b277e169b49008ecdda99ed4913f6): free 133024653 bytes of WAL
I20260812 06:18:01.453233 25413 log_reader.cc:385] T 8b9b277e169b49008ecdda99ed4913f6: removed 13 log segments from log reader
I20260812 06:18:01.453279 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000028 (ops 134-138)
I20260812 06:18:01.453318 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000029 (ops 139-143)
I20260812 06:18:01.453352 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000030 (ops 144-148)
I20260812 06:18:01.453383 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000031 (ops 149-153)
I20260812 06:18:01.453408 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000032 (ops 154-158)
I20260812 06:18:01.453438 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000033 (ops 159-163)
I20260812 06:18:01.453462 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000034 (ops 164-168)
I20260812 06:18:01.453493 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000035 (ops 169-173)
I20260812 06:18:01.453526 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000036 (ops 174-178)
I20260812 06:18:01.453557 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000037 (ops 179-182)
I20260812 06:18:01.453588 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000038 (ops 183-187)
I20260812 06:18:01.453619 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000039 (ops 188-192)
I20260812 06:18:01.453649 25413 log.cc:1079] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/8b9b277e169b49008ecdda99ed4913f6/wal-000000040 (ops 193-197)
I20260812 06:18:01.478900 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: LogGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.026s	user 0.000s	sys 0.024s Metrics: {}
I20260812 06:18:01.479525 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=4.173312
I20260812 06:18:01.485884 25233 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.500s	user 1.626s	sys 0.131s
I20260812 06:18:01.491147 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.011s	user 0.007s	sys 0.004s Metrics: {"bytes_written":5456465,"delete_count":0,"lbm_write_time_us":4777,"lbm_writes_lt_1ms":136,"reinsert_count":0,"update_count":665}
I20260812 06:18:01.491526 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6): 482 bytes on disk
I20260812 06:18:01.491871 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: UndoDeltaBlockGCOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":45,"lbm_reads_lt_1ms":4}
I20260812 06:18:01.492348 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.196750
I20260812 06:18:01.498903 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: FlushDeltaMemStoresOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.006s	user 0.002s	sys 0.003s Metrics: {"bytes_written":2748830,"delete_count":0,"lbm_write_time_us":2439,"lbm_writes_lt_1ms":70,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":335}
I20260812 06:18:01.499215 25515 maintenance_manager.cc:419] P db302115fe2c4417847834da66a0f052: Scheduling MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6): perf score=1.000000
I20260812 06:18:01.533025 25233 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.047s	user 0.001s	sys 0.000s
I20260812 06:18:01.533654 25233 tablet_server.cc:179] TabletServer@127.24.164.65:0 shutting down...
I20260812 06:18:01.619896 25413 maintenance_manager.cc:643] P db302115fe2c4417847834da66a0f052: MajorDeltaCompactionOp(8b9b277e169b49008ecdda99ed4913f6) complete. Timing: real 0.121s	user 0.104s	sys 0.016s Metrics: {"cfile_cache_hit":334,"cfile_cache_hit_bytes":13621139,"cfile_cache_miss":300,"cfile_cache_miss_bytes":15297166,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":685,"lbm_read_time_us":5200,"lbm_reads_lt_1ms":336,"lbm_write_time_us":26598,"lbm_writes_lt_1ms":643,"mutex_wait_us":37,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":120704,"thread_start_us":92,"threads_started":1,"update_count":3000}
I20260812 06:18:01.620505 25233 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:01.620920 25233 tablet_replica.cc:333] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052: stopping tablet replica
I20260812 06:18:01.621191 25233 raft_consensus.cc:2243] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:01.621424 25233 raft_consensus.cc:2272] T 8b9b277e169b49008ecdda99ed4913f6 P db302115fe2c4417847834da66a0f052 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:01.635670 25233 tablet_server.cc:196] TabletServer@127.24.164.65:0 shutdown complete.
I20260812 06:18:01.670711 25233 master.cc:562] Master@127.24.164.126:33553 shutting down...
I20260812 06:18:01.673902 25233 raft_consensus.cc:2243] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:01.674068 25233 raft_consensus.cc:2272] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:01.674139 25233 tablet_replica.cc:333] T 00000000000000000000000000000000 P ffef7ceb9e2f4cbaa0a779b7f4d6d313: stopping tablet replica
I20260812 06:18:01.686116 25233 master.cc:584] Master@127.24.164.126:33553 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5015 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:18:01.756125 25233 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.24.164.126:43377
I20260812 06:18:01.756500 25233 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:01.758304 25570 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:18:01.758306 25575 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:01.758443 25233 server_base.cc:1061] running on GCE node
W20260812 06:18:01.758493 25571 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:18:01.758692 25233 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:01.758737 25233 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:18:01.758751 25233 hybrid_clock.cc:648] HybridClock initialized: now 1786515481758751 us; error 0 us; skew 500 ppm
I20260812 06:18:01.759461 25233 webserver.cc:533] Webserver started at http://127.24.164.126:46367/ using document root <none> and password file <none>
I20260812 06:18:01.759581 25233 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:01.759616 25233 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:01.759668 25233 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:01.759999 25233 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/master-0-root/instance:
uuid: "0018d73699954295877ce5efd46c2fa0"
format_stamp: "Formatted at 2026-08-12 06:18:01 on dist-test-slave-2j7r"
I20260812 06:18:01.761370 25233 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:18:01.762194 25586 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:18:01.762408 25233 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:18:01.762475 25233 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/master-0-root
uuid: "0018d73699954295877ce5efd46c2fa0"
format_stamp: "Formatted at 2026-08-12 06:18:01 on dist-test-slave-2j7r"
I20260812 06:18:01.762539 25233 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-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:18:01.776322 25233 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:01.776615 25233 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:01.780361 25233 rpc_server.cc:307] RPC server started. Bound to: 127.24.164.126:43377
I20260812 06:18:01.794832 25678 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.164.126:43377 every 8 connection(s)
I20260812 06:18:01.795372 25679 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:18:01.797549 25679 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0: Bootstrap starting.
I20260812 06:18:01.798301 25679 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:01.799234 25679 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0: No bootstrap required, opened a new log
I20260812 06:18:01.799597 25679 raft_consensus.cc:359] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0018d73699954295877ce5efd46c2fa0" member_type: VOTER }
I20260812 06:18:01.799679 25679 raft_consensus.cc:385] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:01.799710 25679 raft_consensus.cc:740] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0018d73699954295877ce5efd46c2fa0, State: Initialized, Role: FOLLOWER
I20260812 06:18:01.799844 25679 consensus_queue.cc:260] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [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: "0018d73699954295877ce5efd46c2fa0" member_type: VOTER }
I20260812 06:18:01.799937 25679 raft_consensus.cc:399] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:01.799978 25679 raft_consensus.cc:493] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:01.800024 25679 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:01.800624 25679 raft_consensus.cc:515] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0018d73699954295877ce5efd46c2fa0" member_type: VOTER }
I20260812 06:18:01.800745 25679 leader_election.cc:304] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [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: 0018d73699954295877ce5efd46c2fa0; no voters: 
I20260812 06:18:01.800920 25679 leader_election.cc:290] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:01.801009 25687 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:01.801218 25687 raft_consensus.cc:697] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 1 LEADER]: Becoming Leader. State: Replica: 0018d73699954295877ce5efd46c2fa0, State: Running, Role: LEADER
I20260812 06:18:01.801338 25679 sys_catalog.cc:565] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:01.801350 25687 consensus_queue.cc:237] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [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: "0018d73699954295877ce5efd46c2fa0" member_type: VOTER }
I20260812 06:18:01.801760 25688 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "0018d73699954295877ce5efd46c2fa0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0018d73699954295877ce5efd46c2fa0" member_type: VOTER } }
I20260812 06:18:01.801775 25689 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 0018d73699954295877ce5efd46c2fa0. Latest consensus state: current_term: 1 leader_uuid: "0018d73699954295877ce5efd46c2fa0" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0018d73699954295877ce5efd46c2fa0" member_type: VOTER } }
I20260812 06:18:01.801913 25688 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:01.801929 25689 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:01.803033 25233 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
W20260812 06:18:01.803452 25707 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:18:01.803510 25707 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:18:01.803596 25693 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:01.804162 25693 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:01.805763 25693 catalog_manager.cc:1383] Generated new cluster ID: 05dcf7c2979a42c08c2f563f565a709a
I20260812 06:18:01.805810 25693 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:01.810144 25693 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:01.810686 25693 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:01.825373 25693 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0: Generated new TSK 0
I20260812 06:18:01.825523 25693 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:01.835083 25233 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:01.836737 25711 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:18:01.836725 25709 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:18:01.836835 25233 server_base.cc:1061] running on GCE node
W20260812 06:18:01.836795 25718 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:01.837175 25233 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:01.837219 25233 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:18:01.837234 25233 hybrid_clock.cc:648] HybridClock initialized: now 1786515481837234 us; error 0 us; skew 500 ppm
I20260812 06:18:01.837996 25233 webserver.cc:533] Webserver started at http://127.24.164.65:43341/ using document root <none> and password file <none>
I20260812 06:18:01.838140 25233 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:01.838191 25233 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:01.838259 25233 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:01.838598 25233 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/instance:
uuid: "622ff665ca574df097e054802c2341d2"
format_stamp: "Formatted at 2026-08-12 06:18:01 on dist-test-slave-2j7r"
I20260812 06:18:01.839900 25233 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:18:01.840691 25727 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:18:01.840874 25233 fs_manager.cc:730] Time spent opening block manager: real 0.000s	user 0.000s	sys 0.001s
I20260812 06:18:01.840937 25233 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root
uuid: "622ff665ca574df097e054802c2341d2"
format_stamp: "Formatted at 2026-08-12 06:18:01 on dist-test-slave-2j7r"
I20260812 06:18:01.840993 25233 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-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:18:01.863926 25233 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:01.864253 25233 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:01.864529 25233 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:01.864979 25233 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:01.865016 25233 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:01.865051 25233 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:01.865078 25233 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:01.869009 25233 rpc_server.cc:307] RPC server started. Bound to: 127.24.164.65:40241
I20260812 06:18:01.869055 25841 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.164.65:40241 every 8 connection(s)
I20260812 06:18:01.873242 25845 heartbeater.cc:344] Connected to a master server at 127.24.164.126:43377
I20260812 06:18:01.873337 25845 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:01.873534 25845 heartbeater.cc:507] Master 127.24.164.126:43377 requested a full tablet report, sending...
I20260812 06:18:01.874182 25623 ts_manager.cc:194] Registered new tserver with Master: 622ff665ca574df097e054802c2341d2 (127.24.164.65:40241)
I20260812 06:18:01.874846 25623 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:60856
I20260812 06:18:01.874995 25233 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.005554214s
I20260812 06:18:01.881160 25623 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:60870:
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:18:01.888785 25779 tablet_service.cc:1511] Processing CreateTablet for tablet a464df0a7820403c927b490999b08e49 (DEFAULT_TABLE table=heavy-update-compaction-test [id=221a2e50a03449bd85f1f1b6501da73f]), partition=
I20260812 06:18:01.889020 25779 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a464df0a7820403c927b490999b08e49. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:01.890797 25863 tablet_bootstrap.cc:492] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Bootstrap starting.
I20260812 06:18:01.891712 25863 tablet_bootstrap.cc:654] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:01.892710 25863 tablet_bootstrap.cc:492] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: No bootstrap required, opened a new log
I20260812 06:18:01.892789 25863 ts_tablet_manager.cc:1403] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:18:01.893168 25863 raft_consensus.cc:359] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "622ff665ca574df097e054802c2341d2" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 40241 } }
I20260812 06:18:01.893250 25863 raft_consensus.cc:385] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:01.893275 25863 raft_consensus.cc:740] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 622ff665ca574df097e054802c2341d2, State: Initialized, Role: FOLLOWER
I20260812 06:18:01.893378 25863 consensus_queue.cc:260] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [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: "622ff665ca574df097e054802c2341d2" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 40241 } }
I20260812 06:18:01.893456 25863 raft_consensus.cc:399] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:01.893484 25863 raft_consensus.cc:493] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:01.893518 25863 raft_consensus.cc:3060] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:01.894227 25863 raft_consensus.cc:515] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "622ff665ca574df097e054802c2341d2" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 40241 } }
I20260812 06:18:01.894362 25863 leader_election.cc:304] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [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: 622ff665ca574df097e054802c2341d2; no voters: 
I20260812 06:18:01.894534 25863 leader_election.cc:290] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:01.894635 25865 raft_consensus.cc:2804] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:01.894789 25865 raft_consensus.cc:697] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 1 LEADER]: Becoming Leader. State: Replica: 622ff665ca574df097e054802c2341d2, State: Running, Role: LEADER
I20260812 06:18:01.894843 25863 ts_tablet_manager.cc:1434] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Time spent starting tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:18:01.894883 25845 heartbeater.cc:499] Master 127.24.164.126:43377 was elected leader, sending a full tablet report...
I20260812 06:18:01.894912 25865 consensus_queue.cc:237] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [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: "622ff665ca574df097e054802c2341d2" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 40241 } }
I20260812 06:18:01.896082 25623 catalog_manager.cc:5719] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 reported cstate change: term changed from 0 to 1, leader changed from <none> to 622ff665ca574df097e054802c2341d2 (127.24.164.65). New cstate: current_term: 1 leader_uuid: "622ff665ca574df097e054802c2341d2" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "622ff665ca574df097e054802c2341d2" member_type: VOTER last_known_addr { host: "127.24.164.65" port: 40241 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:01.946997 25233 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.048s	user 0.013s	sys 0.008s
I20260812 06:18:02.120662 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushMRSOp(a464df0a7820403c927b490999b08e49): perf score=26.000253
I20260812 06:18:02.291723 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushMRSOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.171s	user 0.122s	sys 0.044s Metrics: {"bytes_written":12389540,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":61,"dirs.run_cpu_time_us":193,"dirs.run_wall_time_us":694,"drs_written":1,"lbm_read_time_us":49,"lbm_reads_lt_1ms":4,"lbm_write_time_us":46821,"lbm_writes_lt_1ms":959,"peak_mem_usage":0,"reinsert_count":0,"rows_written":106,"spinlock_wait_cycles":14208,"update_count":1510}
I20260812 06:18:02.292464 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling LogGCOp(a464df0a7820403c927b490999b08e49): free 20743880 bytes of WAL
I20260812 06:18:02.292690 25737 log_reader.cc:385] T a464df0a7820403c927b490999b08e49: removed 2 log segments from log reader
I20260812 06:18:02.292760 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000001 (ops 1-6)
I20260812 06:18:02.292804 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000002 (ops 7-11)
I20260812 06:18:02.296839 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: LogGCOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.004s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:18:02.297174 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49): 24616241 bytes on disk
I20260812 06:18:02.297533 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49) 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:18:02.297900 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=3.181125
I20260812 06:18:02.317763 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.020s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4430858,"delete_count":0,"lbm_write_time_us":5953,"lbm_writes_lt_1ms":111,"reinsert_count":0,"update_count":540}
I20260812 06:18:02.318092 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:02.326587 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.008s	user 0.004s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3234,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:02.326903 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:02.479172 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.152s	user 0.096s	sys 0.049s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24856768,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":535,"lbm_read_time_us":10151,"lbm_reads_lt_1ms":569,"lbm_write_time_us":26761,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2176,"thread_start_us":277,"threads_started":5,"update_count":2500}
I20260812 06:18:02.479655 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:02.522606 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.043s	user 0.023s	sys 0.016s Metrics: {"bytes_written":16409915,"delete_count":0,"lbm_write_time_us":17283,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:02.523097 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:02.532580 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3582,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:02.533036 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:02.674763 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.142s	user 0.093s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24856667,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":630,"lbm_read_time_us":10796,"lbm_reads_lt_1ms":572,"lbm_write_time_us":24936,"lbm_writes_lt_1ms":543,"mutex_wait_us":53,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":16256,"update_count":2500}
I20260812 06:18:02.675570 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=11.118625
I20260812 06:18:02.717124 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.041s	user 0.022s	sys 0.015s Metrics: {"bytes_written":13456167,"delete_count":0,"lbm_write_time_us":18033,"lbm_writes_lt_1ms":331,"mutex_wait_us":833,"reinsert_count":0,"update_count":1640}
I20260812 06:18:02.717614 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=1.196750
I20260812 06:18:02.726555 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.009s	user 0.006s	sys 0.001s Metrics: {"bytes_written":3036009,"delete_count":0,"lbm_write_time_us":2872,"lbm_writes_lt_1ms":77,"reinsert_count":0,"update_count":370}
I20260812 06:18:02.727041 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:02.883812 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.157s	user 0.095s	sys 0.048s Metrics: {"cfile_cache_miss":434,"cfile_cache_miss_bytes":20836269,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1131,"lbm_read_time_us":10587,"lbm_reads_lt_1ms":466,"lbm_write_time_us":25013,"lbm_writes_lt_1ms":445,"mutex_wait_us":367,"peak_mem_usage":50771398,"reinsert_count":0,"spinlock_wait_cycles":15744,"update_count":2010}
I20260812 06:18:02.884444 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:02.932543 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.048s	user 0.030s	sys 0.014s Metrics: {"bytes_written":16327857,"delete_count":0,"lbm_write_time_us":22003,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":1990}
I20260812 06:18:02.933014 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:02.950661 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.017s	user 0.003s	sys 0.013s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3584,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:02.951179 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:03.127888 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.177s	user 0.131s	sys 0.038s Metrics: {"cfile_cache_miss":530,"cfile_cache_miss_bytes":24774608,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":988,"lbm_read_time_us":13918,"lbm_reads_lt_1ms":570,"lbm_write_time_us":28107,"lbm_writes_lt_1ms":541,"mutex_wait_us":366,"peak_mem_usage":61993446,"reinsert_count":0,"spinlock_wait_cycles":5120,"update_count":2490}
I20260812 06:18:03.128580 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:03.178712 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.050s	user 0.027s	sys 0.016s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":20550,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:03.179240 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:03.189116 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3688,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:03.189661 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:03.339915 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.150s	user 0.094s	sys 0.044s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24856656,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":392,"lbm_read_time_us":10081,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26585,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2500}
I20260812 06:18:03.340483 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:03.398574 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.058s	user 0.033s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":28399,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:18:03.399091 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:03.414660 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.015s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4514,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:03.415184 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushMRSOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:03.467940 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushMRSOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.053s	user 0.026s	sys 0.005s Metrics: {"bytes_written":1234474,"cfile_init":1,"dirs.queue_time_us":65,"dirs.run_cpu_time_us":220,"dirs.run_wall_time_us":1181,"drs_written":1,"lbm_read_time_us":64,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1617,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:18:03.468669 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling LogGCOp(a464df0a7820403c927b490999b08e49): free 128867454 bytes of WAL
I20260812 06:18:03.468911 25737 log_reader.cc:385] T a464df0a7820403c927b490999b08e49: removed 13 log segments from log reader
I20260812 06:18:03.468959 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000003 (ops 12-16)
I20260812 06:18:03.468988 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000004 (ops 17-20)
I20260812 06:18:03.469005 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000005 (ops 21-25)
I20260812 06:18:03.469031 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000006 (ops 26-30)
I20260812 06:18:03.469061 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000007 (ops 31-34)
I20260812 06:18:03.469110 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000008 (ops 35-39)
I20260812 06:18:03.469141 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000009 (ops 40-44)
I20260812 06:18:03.469157 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000010 (ops 45-48)
I20260812 06:18:03.469183 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000011 (ops 49-53)
I20260812 06:18:03.469244 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000012 (ops 54-58)
I20260812 06:18:03.469272 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000013 (ops 59-63)
I20260812 06:18:03.469302 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000014 (ops 64-68)
I20260812 06:18:03.469333 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000015 (ops 69-73)
I20260812 06:18:03.492931 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: LogGCOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.024s	user 0.000s	sys 0.020s Metrics: {}
I20260812 06:18:03.498677 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=6.157687
I20260812 06:18:03.525251 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.026s	user 0.018s	sys 0.008s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":11542,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:03.525712 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49): 471 bytes on disk
I20260812 06:18:03.526126 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":51,"lbm_reads_lt_1ms":4}
I20260812 06:18:03.526700 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:03.536365 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3563,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:03.536828 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:03.776345 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.239s	user 0.143s	sys 0.096s Metrics: {"cfile_cache_miss":834,"cfile_cache_miss_bytes":37164126,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":392,"lbm_read_time_us":16378,"lbm_reads_lt_1ms":874,"lbm_write_time_us":41206,"lbm_writes_lt_1ms":843,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":3968,"thread_start_us":76,"threads_started":1,"update_count":4000}
I20260812 06:18:03.776854 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=18.063937
I20260812 06:18:03.840183 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.063s	user 0.040s	sys 0.011s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":24047,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:03.840682 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:03.855886 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.015s	user 0.010s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5604,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:03.856518 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:04.056298 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.200s	user 0.116s	sys 0.080s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28959069,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":995,"lbm_read_time_us":13898,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32033,"lbm_writes_lt_1ms":643,"mutex_wait_us":355,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":11648,"update_count":3000}
I20260812 06:18:04.057504 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=15.087375
I20260812 06:18:04.119553 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.062s	user 0.029s	sys 0.016s Metrics: {"bytes_written":17558579,"delete_count":0,"lbm_write_time_us":22218,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":429,"mutex_wait_us":883,"reinsert_count":0,"update_count":2140}
I20260812 06:18:04.120052 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=5.165500
I20260812 06:18:04.139150 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.019s	user 0.012s	sys 0.005s Metrics: {"bytes_written":7056407,"delete_count":0,"lbm_write_time_us":7295,"lbm_writes_lt_1ms":175,"reinsert_count":0,"update_count":860}
I20260812 06:18:04.139640 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:04.349184 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.209s	user 0.118s	sys 0.082s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28959073,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":4078,"lbm_read_time_us":14325,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32677,"lbm_writes_lt_1ms":643,"mutex_wait_us":3729,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":16384,"update_count":3000}
I20260812 06:18:04.349807 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=18.063937
I20260812 06:18:04.411449 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.061s	user 0.027s	sys 0.024s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":24117,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:04.411959 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:04.421717 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3641,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:04.422479 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:04.622812 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.200s	user 0.123s	sys 0.068s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28959068,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":2547,"lbm_read_time_us":12219,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32532,"lbm_writes_lt_1ms":643,"mutex_wait_us":1868,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":3000}
I20260812 06:18:04.623399 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=18.063937
I20260812 06:18:04.684041 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.060s	user 0.044s	sys 0.007s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":24315,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:04.684517 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:04.699204 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.015s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5556,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:04.699815 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:04.900493 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.200s	user 0.148s	sys 0.047s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28959067,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":168,"lbm_read_time_us":12294,"lbm_reads_lt_1ms":672,"lbm_write_time_us":35064,"lbm_writes_lt_1ms":643,"mutex_wait_us":51,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3968,"update_count":3000}
I20260812 06:18:04.901772 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=16.079562
I20260812 06:18:04.952544 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.051s	user 0.039s	sys 0.005s Metrics: {"bytes_written":17681651,"delete_count":0,"lbm_write_time_us":19896,"lbm_writes_lt_1ms":434,"reinsert_count":0,"update_count":2155}
I20260812 06:18:04.952992 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:04.962795 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.010s	user 0.002s	sys 0.005s Metrics: {"bytes_written":3241134,"delete_count":0,"lbm_write_time_us":3025,"lbm_writes_lt_1ms":82,"reinsert_count":0,"update_count":395}
I20260812 06:18:04.963196 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:04.972046 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.009s	user 0.000s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3412,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:04.972438 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushMRSOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:05.003342 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushMRSOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.031s	user 0.029s	sys 0.000s Metrics: {"bytes_written":1316415,"cfile_init":1,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":218,"dirs.run_wall_time_us":1254,"drs_written":1,"lbm_read_time_us":42,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1439,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:18:05.003994 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling LogGCOp(a464df0a7820403c927b490999b08e49): free 133477481 bytes of WAL
I20260812 06:18:05.004215 25737 log_reader.cc:385] T a464df0a7820403c927b490999b08e49: removed 13 log segments from log reader
I20260812 06:18:05.004268 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000016 (ops 74-78)
I20260812 06:18:05.004297 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000017 (ops 79-83)
I20260812 06:18:05.004329 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000018 (ops 84-88)
I20260812 06:18:05.004361 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000019 (ops 89-93)
I20260812 06:18:05.004395 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000020 (ops 94-98)
I20260812 06:18:05.004427 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000021 (ops 99-103)
I20260812 06:18:05.004458 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000022 (ops 104-108)
I20260812 06:18:05.004490 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000023 (ops 109-113)
I20260812 06:18:05.004522 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000024 (ops 114-118)
I20260812 06:18:05.004554 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000025 (ops 119-123)
I20260812 06:18:05.004585 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000026 (ops 124-128)
I20260812 06:18:05.004617 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000027 (ops 129-133)
I20260812 06:18:05.004649 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000028 (ops 134-138)
I20260812 06:18:05.028512 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: LogGCOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.024s	user 0.000s	sys 0.021s Metrics: {}
I20260812 06:18:05.028862 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=3.181125
I20260812 06:18:05.041275 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.012s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4512902,"delete_count":0,"lbm_write_time_us":3786,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:05.041658 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:05.050216 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.008s	user 0.007s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3195,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:05.050604 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49): 492 bytes on disk
I20260812 06:18:05.050963 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4}
I20260812 06:18:05.051409 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:05.268590 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.217s	user 0.165s	sys 0.052s Metrics: {"cfile_cache_miss":835,"cfile_cache_miss_bytes":37164206,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":544,"lbm_read_time_us":15532,"lbm_reads_lt_1ms":875,"lbm_write_time_us":42622,"lbm_writes_lt_1ms":843,"mutex_wait_us":303,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":4352,"thread_start_us":68,"threads_started":1,"update_count":4000}
I20260812 06:18:05.269152 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=18.063937
I20260812 06:18:05.326520 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.057s	user 0.037s	sys 0.017s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":25785,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":501,"reinsert_count":0,"update_count":2500}
I20260812 06:18:05.327068 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=3.181125
I20260812 06:18:05.344347 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.017s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4512904,"delete_count":0,"lbm_write_time_us":6723,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:05.344762 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:05.353539 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3346,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:05.353896 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:05.518872 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.165s	user 0.125s	sys 0.040s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":33061591,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":163,"lbm_read_time_us":11887,"lbm_reads_lt_1ms":773,"lbm_write_time_us":33834,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":3840,"update_count":3500}
I20260812 06:18:05.519902 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:05.574872 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.054s	user 0.017s	sys 0.034s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":22682,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:05.575374 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:05.591027 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.015s	user 0.003s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4714,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:05.591450 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:05.601197 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3629,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:05.601627 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:05.765007 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.163s	user 0.123s	sys 0.040s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28959182,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":959,"lbm_read_time_us":13333,"lbm_reads_lt_1ms":673,"lbm_write_time_us":32435,"lbm_writes_lt_1ms":643,"mutex_wait_us":293,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":5504,"update_count":3000}
I20260812 06:18:05.765568 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:05.823429 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.058s	user 0.032s	sys 0.019s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23054,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:05.823947 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=3.181125
I20260812 06:18:05.838574 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.014s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":5551,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:05.838994 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:05.852047 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.013s	user 0.008s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5032,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:05.852509 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:06.016480 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.164s	user 0.112s	sys 0.052s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28959172,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":229,"lbm_read_time_us":11695,"lbm_reads_lt_1ms":673,"lbm_write_time_us":33557,"lbm_writes_lt_1ms":643,"mutex_wait_us":56,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2944,"update_count":3000}
I20260812 06:18:06.017124 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:06.063858 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.047s	user 0.030s	sys 0.008s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":17690,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:06.064361 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:06.078163 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.014s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5567,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:06.078689 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:06.239840 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.161s	user 0.112s	sys 0.031s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24856654,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":290,"lbm_read_time_us":11541,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27956,"lbm_writes_lt_1ms":543,"mutex_wait_us":59,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":18304,"update_count":2500}
I20260812 06:18:06.240464 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=14.095187
I20260812 06:18:06.292877 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.052s	user 0.011s	sys 0.023s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":16835,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:06.293462 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:06.303078 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.009s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3518,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:06.303485 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushMRSOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:06.334617 25233 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.388s	user 1.613s	sys 0.121s
I20260812 06:18:06.336254 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushMRSOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.033s	user 0.023s	sys 0.008s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":48,"dirs.run_cpu_time_us":149,"dirs.run_wall_time_us":965,"drs_written":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1869,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:18:06.336869 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling LogGCOp(a464df0a7820403c927b490999b08e49): free 127961371 bytes of WAL
I20260812 06:18:06.337075 25737 log_reader.cc:385] T a464df0a7820403c927b490999b08e49: removed 12 log segments from log reader
I20260812 06:18:06.337148 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000029 (ops 139-143)
I20260812 06:18:06.337179 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000030 (ops 144-148)
I20260812 06:18:06.337211 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000031 (ops 149-153)
I20260812 06:18:06.337245 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000032 (ops 154-158)
I20260812 06:18:06.337285 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000033 (ops 159-163)
I20260812 06:18:06.337317 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000034 (ops 164-168)
I20260812 06:18:06.337348 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000035 (ops 169-173)
I20260812 06:18:06.337380 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000036 (ops 174-178)
I20260812 06:18:06.337409 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000037 (ops 179-183)
I20260812 06:18:06.337440 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000038 (ops 184-188)
I20260812 06:18:06.337471 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000039 (ops 189-193)
I20260812 06:18:06.337503 25737 log.cc:1079] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: Deleting log segment in path: /tmp/dist-test-taskZPjTsQ/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515476730541-25233-0/minicluster-data/ts-0-root/wals/a464df0a7820403c927b490999b08e49/wal-000000040 (ops 194-198)
I20260812 06:18:06.357563 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: LogGCOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.021s	user 0.001s	sys 0.019s Metrics: {}
I20260812 06:18:06.358006 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49): perf score=2.188937
I20260812 06:18:06.367237 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: FlushDeltaMemStoresOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3641,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:06.367606 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49): 483 bytes on disk
I20260812 06:18:06.367991 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: UndoDeltaBlockGCOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":47,"lbm_reads_lt_1ms":4}
I20260812 06:18:06.368467 25846 maintenance_manager.cc:419] P 622ff665ca574df097e054802c2341d2: Scheduling MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49): perf score=1.000000
I20260812 06:18:06.397550 25233 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.063s	user 0.002s	sys 0.000s
I20260812 06:18:06.397979 25233 tablet_server.cc:179] TabletServer@127.24.164.65:0 shutting down...
I20260812 06:18:06.506115 25737 maintenance_manager.cc:643] P 622ff665ca574df097e054802c2341d2: MajorDeltaCompactionOp(a464df0a7820403c927b490999b08e49) complete. Timing: real 0.138s	user 0.102s	sys 0.035s Metrics: {"cfile_cache_hit":508,"cfile_cache_hit_bytes":21381171,"cfile_cache_miss":125,"cfile_cache_miss_bytes":7578015,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":600,"lbm_read_time_us":3613,"lbm_reads_lt_1ms":153,"lbm_write_time_us":27553,"lbm_writes_lt_1ms":643,"mutex_wait_us":27,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":11136,"thread_start_us":72,"threads_started":1,"update_count":3000}
I20260812 06:18:06.507052 25233 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:06.507328 25233 tablet_replica.cc:333] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2: stopping tablet replica
I20260812 06:18:06.507457 25233 raft_consensus.cc:2243] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:06.507623 25233 raft_consensus.cc:2272] T a464df0a7820403c927b490999b08e49 P 622ff665ca574df097e054802c2341d2 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:06.510588 25233 tablet_server.cc:196] TabletServer@127.24.164.65:0 shutdown complete.
I20260812 06:18:06.557608 25233 master.cc:562] Master@127.24.164.126:43377 shutting down...
I20260812 06:18:06.560245 25233 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:06.560416 25233 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:06.560490 25233 tablet_replica.cc:333] T 00000000000000000000000000000000 P 0018d73699954295877ce5efd46c2fa0: stopping tablet replica
I20260812 06:18:06.572582 25233 master.cc:584] Master@127.24.164.126:43377 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (4885 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (9902 ms total)

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