[==========] 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:18:45.858186 29294 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.28.155.190:36591
I20260812 06:18:45.859154 29294 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:18:45.859748 29294 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:18:45.866098 29294 server_base.cc:1061] running on GCE node
W20260812 06:18:45.866123 29308 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:18:45.866503 29304 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:45.866665 29306 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:45.867182 29294 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:45.867267 29294 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:45.867300 29294 hybrid_clock.cc:648] HybridClock initialized: now 1786515525867299 us; error 0 us; skew 500 ppm
I20260812 06:18:45.869000 29294 webserver.cc:533] Webserver started at http://127.28.155.190:43999/ using document root <none> and password file <none>
I20260812 06:18:45.869469 29294 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:45.869522 29294 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:45.869704 29294 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:45.871201 29294 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/master-0-root/instance:
uuid: "46a12ec6348c422dbe2604dd38408959"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zpvx"
I20260812 06:18:45.874440 29294 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.002s	sys 0.003s
I20260812 06:18:45.876272 29318 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:45.877219 29294 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.001s
I20260812 06:18:45.877348 29294 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/master-0-root
uuid: "46a12ec6348c422dbe2604dd38408959"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zpvx"
I20260812 06:18:45.877447 29294 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-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:45.889916 29294 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:45.890451 29294 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:18:45.890625 29294 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:45.897867 29294 rpc_server.cc:307] RPC server started. Bound to: 127.28.155.190:36591
I20260812 06:18:45.897871 29395 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.155.190:36591 every 8 connection(s)
I20260812 06:18:45.899966 29399 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:45.905179 29399 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959: Bootstrap starting.
I20260812 06:18:45.907382 29399 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:45.908242 29399 log.cc:826] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959: Log is configured to *not* fsync() on all Append() calls
I20260812 06:18:45.909852 29399 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959: No bootstrap required, opened a new log
I20260812 06:18:45.912443 29399 raft_consensus.cc:359] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "46a12ec6348c422dbe2604dd38408959" member_type: VOTER }
I20260812 06:18:45.912626 29399 raft_consensus.cc:385] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:45.912735 29399 raft_consensus.cc:740] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 46a12ec6348c422dbe2604dd38408959, State: Initialized, Role: FOLLOWER
I20260812 06:18:45.913345 29399 consensus_queue.cc:260] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [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: "46a12ec6348c422dbe2604dd38408959" member_type: VOTER }
I20260812 06:18:45.913522 29399 raft_consensus.cc:399] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:45.913606 29399 raft_consensus.cc:493] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:45.913738 29399 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:45.914459 29399 raft_consensus.cc:515] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "46a12ec6348c422dbe2604dd38408959" member_type: VOTER }
I20260812 06:18:45.914870 29399 leader_election.cc:304] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [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: 46a12ec6348c422dbe2604dd38408959; no voters: 
I20260812 06:18:45.915189 29399 leader_election.cc:290] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:45.915269 29405 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:45.915573 29405 raft_consensus.cc:697] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 1 LEADER]: Becoming Leader. State: Replica: 46a12ec6348c422dbe2604dd38408959, State: Running, Role: LEADER
I20260812 06:18:45.916023 29405 consensus_queue.cc:237] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [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: "46a12ec6348c422dbe2604dd38408959" member_type: VOTER }
I20260812 06:18:45.916088 29399 sys_catalog.cc:565] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:45.917923 29408 sys_catalog.cc:455] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 46a12ec6348c422dbe2604dd38408959. Latest consensus state: current_term: 1 leader_uuid: "46a12ec6348c422dbe2604dd38408959" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "46a12ec6348c422dbe2604dd38408959" member_type: VOTER } }
I20260812 06:18:45.917951 29406 sys_catalog.cc:455] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "46a12ec6348c422dbe2604dd38408959" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "46a12ec6348c422dbe2604dd38408959" member_type: VOTER } }
I20260812 06:18:45.918049 29406 sys_catalog.cc:458] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:45.918048 29408 sys_catalog.cc:458] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:45.918886 29294 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
W20260812 06:18:45.920846 29431 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:18:45.920931 29431 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:18:45.921000 29424 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:45.921721 29424 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:45.926275 29424 catalog_manager.cc:1383] Generated new cluster ID: 68388756963f40519390b5b7db1fc270
I20260812 06:18:45.926352 29424 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:45.953758 29424 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:45.954631 29424 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:45.961978 29424 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959: Generated new TSK 0
I20260812 06:18:45.962555 29424 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:45.983618 29294 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:45.986490 29440 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:45.986544 29438 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:45.986589 29442 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:45.986768 29294 server_base.cc:1061] running on GCE node
I20260812 06:18:45.986992 29294 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:45.987061 29294 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:45.987142 29294 hybrid_clock.cc:648] HybridClock initialized: now 1786515525987141 us; error 0 us; skew 500 ppm
I20260812 06:18:45.988312 29294 webserver.cc:533] Webserver started at http://127.28.155.129:33989/ using document root <none> and password file <none>
I20260812 06:18:45.988535 29294 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:45.988587 29294 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:45.988694 29294 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:45.989146 29294 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/instance:
uuid: "758cac9463ed4180ad11a367a266badf"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zpvx"
I20260812 06:18:45.990793 29294 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.001s
I20260812 06:18:45.991899 29447 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:45.992192 29294 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:18:45.992269 29294 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root
uuid: "758cac9463ed4180ad11a367a266badf"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zpvx"
I20260812 06:18:45.992394 29294 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-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:46.011613 29294 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:46.012073 29294 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:46.012701 29294 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:46.013716 29294 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:46.013772 29294 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:46.013849 29294 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:46.013896 29294 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:46.021371 29294 rpc_server.cc:307] RPC server started. Bound to: 127.28.155.129:40313
I20260812 06:18:46.021476 29560 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.155.129:40313 every 8 connection(s)
I20260812 06:18:46.031680 29562 heartbeater.cc:344] Connected to a master server at 127.28.155.190:36591
I20260812 06:18:46.031914 29562 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:46.032363 29562 heartbeater.cc:507] Master 127.28.155.190:36591 requested a full tablet report, sending...
I20260812 06:18:46.033809 29341 ts_manager.cc:194] Registered new tserver with Master: 758cac9463ed4180ad11a367a266badf (127.28.155.129:40313)
I20260812 06:18:46.034468 29294 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.012401934s
I20260812 06:18:46.035261 29341 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:54504
I20260812 06:18:46.044179 29341 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:54518:
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:46.058394 29504 tablet_service.cc:1511] Processing CreateTablet for tablet bf70a564b4994931bb165b5521ed7ebd (DEFAULT_TABLE table=heavy-update-compaction-test [id=7612951794d740b9b7e0d1014d0a897b]), partition=
I20260812 06:18:46.058849 29504 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet bf70a564b4994931bb165b5521ed7ebd. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:46.061528 29584 tablet_bootstrap.cc:492] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Bootstrap starting.
I20260812 06:18:46.062803 29584 tablet_bootstrap.cc:654] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:46.064275 29584 tablet_bootstrap.cc:492] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: No bootstrap required, opened a new log
I20260812 06:18:46.064361 29584 ts_tablet_manager.cc:1403] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:18:46.064888 29584 raft_consensus.cc:359] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "758cac9463ed4180ad11a367a266badf" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 40313 } }
I20260812 06:18:46.064991 29584 raft_consensus.cc:385] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:46.065016 29584 raft_consensus.cc:740] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 758cac9463ed4180ad11a367a266badf, State: Initialized, Role: FOLLOWER
I20260812 06:18:46.065219 29584 consensus_queue.cc:260] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [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: "758cac9463ed4180ad11a367a266badf" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 40313 } }
I20260812 06:18:46.065315 29584 raft_consensus.cc:399] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:46.065346 29584 raft_consensus.cc:493] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:46.065431 29584 raft_consensus.cc:3060] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:46.066309 29584 raft_consensus.cc:515] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "758cac9463ed4180ad11a367a266badf" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 40313 } }
I20260812 06:18:46.066428 29584 leader_election.cc:304] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [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: 758cac9463ed4180ad11a367a266badf; no voters: 
I20260812 06:18:46.066684 29584 leader_election.cc:290] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:46.066785 29594 raft_consensus.cc:2804] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:46.066970 29594 raft_consensus.cc:697] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 1 LEADER]: Becoming Leader. State: Replica: 758cac9463ed4180ad11a367a266badf, State: Running, Role: LEADER
I20260812 06:18:46.067124 29594 consensus_queue.cc:237] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [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: "758cac9463ed4180ad11a367a266badf" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 40313 } }
I20260812 06:18:46.067289 29584 ts_tablet_manager.cc:1434] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:18:46.067698 29562 heartbeater.cc:499] Master 127.28.155.190:36591 was elected leader, sending a full tablet report...
I20260812 06:18:46.070114 29341 catalog_manager.cc:5719] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf reported cstate change: term changed from 0 to 1, leader changed from <none> to 758cac9463ed4180ad11a367a266badf (127.28.155.129). New cstate: current_term: 1 leader_uuid: "758cac9463ed4180ad11a367a266badf" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "758cac9463ed4180ad11a367a266badf" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 40313 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:46.137828 29294 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.057s	user 0.024s	sys 0.004s
I20260812 06:18:46.272480 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd): perf score=19.054940
I20260812 06:18:46.439796 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.167s	user 0.112s	sys 0.040s Metrics: {"bytes_written":12307490,"cfile_init":1,"compiler_manager_pool.queue_time_us":190,"delete_count":0,"dirs.queue_time_us":66,"dirs.run_cpu_time_us":203,"dirs.run_wall_time_us":746,"drs_written":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4,"lbm_write_time_us":42145,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"thread_start_us":127,"threads_started":1,"update_count":1500}
I20260812 06:18:46.440845 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling LogGCOp(bf70a564b4994931bb165b5521ed7ebd): free 20743880 bytes of WAL
I20260812 06:18:46.441154 29457 log_reader.cc:385] T bf70a564b4994931bb165b5521ed7ebd: removed 2 log segments from log reader
I20260812 06:18:46.441237 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000001 (ops 1-6)
I20260812 06:18:46.441318 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000002 (ops 7-11)
I20260812 06:18:46.446017 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: LogGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.005s	user 0.000s	sys 0.003s Metrics: {}
I20260812 06:18:46.446393 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:46.466929 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.020s	user 0.011s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":8168,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":102,"reinsert_count":0,"update_count":500}
I20260812 06:18:46.467473 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:46.623664 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.156s	user 0.096s	sys 0.056s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":66,"lbm_read_time_us":9601,"lbm_reads_lt_1ms":464,"lbm_write_time_us":24865,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":3072,"thread_start_us":309,"threads_started":5,"update_count":2000}
I20260812 06:18:46.624312 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd): 16411396 bytes on disk
I20260812 06:18:46.624850 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":86,"lbm_reads_lt_1ms":4}
I20260812 06:18:46.625417 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:46.668654 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.043s	user 0.023s	sys 0.020s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":22111,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":300,"reinsert_count":0,"update_count":1500}
I20260812 06:18:46.669118 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:46.697152 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.028s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5215,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:46.697646 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:46.711643 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.014s	user 0.004s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5476,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:46.712078 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:46.860675 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.148s	user 0.113s	sys 0.035s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774808,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":214,"lbm_read_time_us":10326,"lbm_reads_lt_1ms":573,"lbm_write_time_us":29196,"lbm_writes_lt_1ms":543,"mutex_wait_us":27,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":27008,"update_count":2500}
I20260812 06:18:46.861323 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:46.892340 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.031s	user 0.016s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":13593,"lbm_writes_lt_1ms":303,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":1500}
I20260812 06:18:46.892958 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:46.903364 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.008s	sys 0.001s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4078,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:46.903767 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:47.029145 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.125s	user 0.105s	sys 0.020s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672276,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":228,"lbm_read_time_us":8843,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25623,"lbm_writes_lt_1ms":443,"mutex_wait_us":22,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":19840,"update_count":2000}
I20260812 06:18:47.029706 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:47.080327 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.050s	user 0.016s	sys 0.019s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":13056,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:47.080857 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:47.091867 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.011s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4606,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.092265 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:47.243328 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.151s	user 0.113s	sys 0.031s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":111,"lbm_read_time_us":10718,"lbm_reads_lt_1ms":472,"lbm_write_time_us":23850,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2000}
I20260812 06:18:47.243901 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:47.294786 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.051s	user 0.024s	sys 0.012s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16983,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:47.295279 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:47.305814 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3978,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.306531 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:47.431661 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.125s	user 0.109s	sys 0.016s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":867,"lbm_read_time_us":8618,"lbm_reads_lt_1ms":472,"lbm_write_time_us":23107,"lbm_writes_lt_1ms":443,"mutex_wait_us":297,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":2000}
I20260812 06:18:47.432439 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:47.462652 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.030s	user 0.015s	sys 0.013s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":13342,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:47.463117 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:47.473673 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4224,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.474133 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:47.600023 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.126s	user 0.102s	sys 0.024s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672276,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":269,"lbm_read_time_us":8126,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27163,"lbm_writes_lt_1ms":443,"mutex_wait_us":23,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":9728,"update_count":2000}
I20260812 06:18:47.600569 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:47.650331 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.050s	user 0.026s	sys 0.008s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15841,"lbm_writes_lt_1ms":303,"mutex_wait_us":2,"reinsert_count":0,"update_count":1500}
I20260812 06:18:47.650774 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:47.660703 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3996,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.661088 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:47.692499 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.031s	user 0.027s	sys 0.000s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":62,"dirs.run_cpu_time_us":267,"dirs.run_wall_time_us":1381,"drs_written":1,"lbm_read_time_us":36,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1533,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:18:47.693437 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling LogGCOp(bf70a564b4994931bb165b5521ed7ebd): free 112239257 bytes of WAL
I20260812 06:18:47.693723 29457 log_reader.cc:385] T bf70a564b4994931bb165b5521ed7ebd: removed 11 log segments from log reader
I20260812 06:18:47.693790 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000003 (ops 12-16)
I20260812 06:18:47.693827 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000004 (ops 17-21)
I20260812 06:18:47.693861 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000005 (ops 22-26)
I20260812 06:18:47.693897 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000006 (ops 27-31)
I20260812 06:18:47.693921 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000007 (ops 32-36)
I20260812 06:18:47.693943 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000008 (ops 37-41)
I20260812 06:18:47.693972 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000009 (ops 42-46)
I20260812 06:18:47.694001 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000010 (ops 47-51)
I20260812 06:18:47.694034 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000011 (ops 52-56)
I20260812 06:18:47.694077 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000012 (ops 57-60)
I20260812 06:18:47.694108 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000013 (ops 61-65)
I20260812 06:18:47.722486 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: LogGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.029s	user 0.000s	sys 0.027s Metrics: {}
I20260812 06:18:47.723009 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd): 462 bytes on disk
I20260812 06:18:47.723564 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":84,"lbm_reads_lt_1ms":4}
I20260812 06:18:47.724180 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:47.746237 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.022s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4601,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.746717 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling LogGCOp(bf70a564b4994931bb165b5521ed7ebd): free 12017983 bytes of WAL
I20260812 06:18:47.746941 29457 log_reader.cc:385] T bf70a564b4994931bb165b5521ed7ebd: removed 1 log segments from log reader
I20260812 06:18:47.747009 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000014 (ops 66-70)
I20260812 06:18:47.749374 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: LogGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.002s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:18:47.749701 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:47.760581 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.011s	user 0.004s	sys 0.006s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3832,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.761025 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:47.952692 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.191s	user 0.151s	sys 0.036s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877337,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":396,"lbm_read_time_us":13646,"lbm_reads_lt_1ms":674,"lbm_write_time_us":39274,"lbm_writes_lt_1ms":643,"mutex_wait_us":45,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":9856,"thread_start_us":80,"threads_started":1,"update_count":3000}
I20260812 06:18:47.953493 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:48.008951 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.055s	user 0.036s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23560,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:48.009536 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:48.020438 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3930,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:48.022778 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:48.181123 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.158s	user 0.131s	sys 0.027s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":213,"lbm_read_time_us":10424,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30761,"lbm_writes_lt_1ms":543,"mutex_wait_us":56,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":4608,"update_count":2500}
I20260812 06:18:48.181838 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:48.245714 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.064s	user 0.020s	sys 0.032s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":24878,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:48.246229 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:48.256366 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3899,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:48.257145 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:48.427779 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.170s	user 0.098s	sys 0.067s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":249,"lbm_read_time_us":11656,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28818,"lbm_writes_lt_1ms":543,"mutex_wait_us":45,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":18944,"update_count":2500}
I20260812 06:18:48.428419 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:48.489081 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.060s	user 0.040s	sys 0.012s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":22071,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:48.489631 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:48.505916 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.016s	user 0.011s	sys 0.003s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6425,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:48.506696 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:48.686805 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.180s	user 0.117s	sys 0.056s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"lbm_read_time_us":12939,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31529,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2500}
I20260812 06:18:48.687296 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:48.754218 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.067s	user 0.021s	sys 0.038s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":22133,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:48.754721 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:48.770743 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.016s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6113,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:48.771354 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:48.949477 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.178s	user 0.110s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":234,"lbm_read_time_us":12684,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28230,"lbm_writes_lt_1ms":543,"mutex_wait_us":1,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2500}
I20260812 06:18:48.950163 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:49.009119 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.059s	user 0.033s	sys 0.023s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":21462,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:49.009660 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:49.020176 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4149,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.020577 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:49.191952 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.171s	user 0.154s	sys 0.017s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774691,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":291,"lbm_read_time_us":11952,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30602,"lbm_writes_lt_1ms":543,"mutex_wait_us":29,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":14720,"update_count":2500}
I20260812 06:18:49.192739 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:49.234824 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.042s	user 0.034s	sys 0.005s Metrics: {"bytes_written":12307492,"delete_count":0,"lbm_write_time_us":18149,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:49.235340 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:49.245613 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4162,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.245997 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:49.277187 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.031s	user 0.030s	sys 0.000s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":66,"dirs.run_cpu_time_us":204,"dirs.run_wall_time_us":1348,"drs_written":1,"lbm_read_time_us":54,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1448,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:18:49.277942 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling LogGCOp(bf70a564b4994931bb165b5521ed7ebd): free 121006436 bytes of WAL
I20260812 06:18:49.278148 29457 log_reader.cc:385] T bf70a564b4994931bb165b5521ed7ebd: removed 12 log segments from log reader
I20260812 06:18:49.278199 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000015 (ops 71-75)
I20260812 06:18:49.278234 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000016 (ops 76-80)
I20260812 06:18:49.278270 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000017 (ops 81-84)
I20260812 06:18:49.278303 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000018 (ops 85-89)
I20260812 06:18:49.278332 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000019 (ops 90-94)
I20260812 06:18:49.278358 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000020 (ops 95-99)
I20260812 06:18:49.278388 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000021 (ops 100-104)
I20260812 06:18:49.278415 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000022 (ops 105-109)
I20260812 06:18:49.278448 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000023 (ops 110-114)
I20260812 06:18:49.278481 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000024 (ops 115-119)
I20260812 06:18:49.278509 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000025 (ops 120-124)
I20260812 06:18:49.278548 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000026 (ops 125-129)
I20260812 06:18:49.308341 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: LogGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.030s	user 0.000s	sys 0.026s Metrics: {}
I20260812 06:18:49.308841 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd): 482 bytes on disk
I20260812 06:18:49.309510 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":90,"lbm_reads_lt_1ms":4}
I20260812 06:18:49.310089 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:49.337385 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.027s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5603,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.337811 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:49.347921 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4204,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.348536 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:49.531175 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.182s	user 0.123s	sys 0.057s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877339,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":5449,"lbm_read_time_us":13705,"lbm_reads_lt_1ms":674,"lbm_write_time_us":31271,"lbm_writes_lt_1ms":643,"mutex_wait_us":2632,"peak_mem_usage":75542472,"reinsert_count":0,"thread_start_us":83,"threads_started":1,"update_count":3000}
I20260812 06:18:49.531762 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:49.595783 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.064s	user 0.009s	sys 0.050s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23450,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:49.596489 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:49.610603 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.014s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5180,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.611441 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:49.785421 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.174s	user 0.107s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":501,"lbm_read_time_us":12144,"lbm_reads_lt_1ms":564,"lbm_write_time_us":28237,"lbm_writes_lt_1ms":543,"mutex_wait_us":64,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:49.785873 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:49.843060 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.057s	user 0.023s	sys 0.023s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":18018,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:49.843521 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:49.853956 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4075,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.854426 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:50.033128 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.179s	user 0.119s	sys 0.059s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":615,"lbm_read_time_us":13851,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30440,"lbm_writes_lt_1ms":543,"mutex_wait_us":338,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13824,"update_count":2500}
I20260812 06:18:50.033700 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:50.071859 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.038s	user 0.023s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17220,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:50.072587 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:50.083683 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4406,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:50.084592 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:50.224289 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.139s	user 0.122s	sys 0.017s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672276,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":978,"lbm_read_time_us":9484,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27095,"lbm_writes_lt_1ms":443,"mutex_wait_us":323,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2000}
I20260812 06:18:50.224846 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:50.276998 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.052s	user 0.029s	sys 0.011s Metrics: {"bytes_written":12307492,"delete_count":0,"lbm_write_time_us":17819,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:50.277498 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:50.287849 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3923,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:50.288645 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:50.434796 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.146s	user 0.128s	sys 0.012s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":734,"lbm_read_time_us":9042,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26714,"lbm_writes_lt_1ms":443,"mutex_wait_us":353,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2816,"update_count":2000}
I20260812 06:18:50.435639 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=10.126437
I20260812 06:18:50.487671 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.052s	user 0.023s	sys 0.023s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":21195,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:50.488159 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:50.503353 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.015s	user 0.013s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5914,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:50.503847 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:50.695597 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.192s	user 0.159s	sys 0.027s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672275,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":355,"lbm_read_time_us":11251,"lbm_reads_lt_1ms":472,"lbm_write_time_us":35492,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":6272,"update_count":2000}
I20260812 06:18:50.696225 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=14.095187
I20260812 06:18:50.755363 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.059s	user 0.025s	sys 0.025s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":25027,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:50.755868 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:50.770394 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.014s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5482,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:50.770967 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:50.806110 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushMRSOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.035s	user 0.031s	sys 0.003s Metrics: {"bytes_written":1193507,"cfile_init":1,"dirs.queue_time_us":67,"dirs.run_cpu_time_us":193,"dirs.run_wall_time_us":1219,"drs_written":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2172,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:18:50.806773 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling LogGCOp(bf70a564b4994931bb165b5521ed7ebd): free 124710510 bytes of WAL
I20260812 06:18:50.806993 29457 log_reader.cc:385] T bf70a564b4994931bb165b5521ed7ebd: removed 12 log segments from log reader
I20260812 06:18:50.807039 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000027 (ops 130-134)
I20260812 06:18:50.807068 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000028 (ops 135-139)
I20260812 06:18:50.807132 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000029 (ops 140-144)
I20260812 06:18:50.807164 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000030 (ops 145-149)
I20260812 06:18:50.807207 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000031 (ops 150-154)
I20260812 06:18:50.807266 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000032 (ops 155-159)
I20260812 06:18:50.807296 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000033 (ops 160-164)
I20260812 06:18:50.807339 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000034 (ops 165-169)
I20260812 06:18:50.807381 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000035 (ops 170-174)
I20260812 06:18:50.807421 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000036 (ops 175-179)
I20260812 06:18:50.807461 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000037 (ops 180-184)
I20260812 06:18:50.807500 29457 log.cc:1079] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/bf70a564b4994931bb165b5521ed7ebd/wal-000000038 (ops 185-189)
I20260812 06:18:50.834200 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: LogGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.027s	user 0.002s	sys 0.023s Metrics: {}
I20260812 06:18:50.834589 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd): 462 bytes on disk
I20260812 06:18:50.835282 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: UndoDeltaBlockGCOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":78,"lbm_reads_lt_1ms":4}
I20260812 06:18:50.836103 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:50.857352 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.021s	user 0.011s	sys 0.005s Metrics: {"bytes_written":4266758,"delete_count":0,"lbm_write_time_us":6526,"lbm_writes_lt_1ms":107,"reinsert_count":0,"update_count":520}
I20260812 06:18:50.857821 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=2.188937
I20260812 06:18:50.867453 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.009s	user 0.000s	sys 0.008s Metrics: {"bytes_written":3938559,"delete_count":0,"lbm_write_time_us":3843,"lbm_writes_lt_1ms":99,"reinsert_count":0,"update_count":480}
I20260812 06:18:50.868014 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd): perf score=1.000000
I20260812 06:18:51.079092 29294 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.941s	user 1.892s	sys 0.134s
I20260812 06:18:51.082089 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: MajorDeltaCompactionOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.214s	user 0.160s	sys 0.043s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979747,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":931,"lbm_read_time_us":15357,"lbm_reads_lt_1ms":774,"lbm_write_time_us":37232,"lbm_writes_lt_1ms":743,"mutex_wait_us":293,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":8064,"thread_start_us":82,"threads_started":1,"update_count":3500}
I20260812 06:18:51.082808 29563 maintenance_manager.cc:419] P 758cac9463ed4180ad11a367a266badf: Scheduling FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd): perf score=18.063937
I20260812 06:18:51.106837 29294 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.027s	user 0.002s	sys 0.000s
I20260812 06:18:51.107462 29294 tablet_server.cc:179] TabletServer@127.28.155.129:0 shutting down...
I20260812 06:18:51.137674 29457 maintenance_manager.cc:643] P 758cac9463ed4180ad11a367a266badf: FlushDeltaMemStoresOp(bf70a564b4994931bb165b5521ed7ebd) complete. Timing: real 0.055s	user 0.038s	sys 0.015s Metrics: {"bytes_written":20512319,"delete_count":0,"lbm_write_time_us":24463,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:51.138335 29294 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:51.138783 29294 tablet_replica.cc:333] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf: stopping tablet replica
I20260812 06:18:51.139060 29294 raft_consensus.cc:2243] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:51.139304 29294 raft_consensus.cc:2272] T bf70a564b4994931bb165b5521ed7ebd P 758cac9463ed4180ad11a367a266badf [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:51.154523 29294 tablet_server.cc:196] TabletServer@127.28.155.129:0 shutdown complete.
I20260812 06:18:51.158890 29294 master.cc:562] Master@127.28.155.190:36591 shutting down...
I20260812 06:18:51.162755 29294 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:51.162918 29294 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:51.163003 29294 tablet_replica.cc:333] T 00000000000000000000000000000000 P 46a12ec6348c422dbe2604dd38408959: stopping tablet replica
I20260812 06:18:51.175135 29294 master.cc:584] Master@127.28.155.190:36591 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5405 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:18:51.263518 29294 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.28.155.190:37289
I20260812 06:18:51.263942 29294 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:51.266212 29629 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:51.266227 29631 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:18:51.266220 29628 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:51.266510 29294 server_base.cc:1061] running on GCE node
I20260812 06:18:51.266651 29294 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:51.266685 29294 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:51.266700 29294 hybrid_clock.cc:648] HybridClock initialized: now 1786515531266700 us; error 0 us; skew 500 ppm
I20260812 06:18:51.267496 29294 webserver.cc:533] Webserver started at http://127.28.155.190:36641/ using document root <none> and password file <none>
I20260812 06:18:51.267628 29294 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:51.267670 29294 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:51.267722 29294 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:51.268081 29294 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/master-0-root/instance:
uuid: "6c338cb62fa542968f08d3cef4875274"
format_stamp: "Formatted at 2026-08-12 06:18:51 on dist-test-slave-zpvx"
I20260812 06:18:51.269650 29294 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:18:51.270558 29640 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:51.270812 29294 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:18:51.270879 29294 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/master-0-root
uuid: "6c338cb62fa542968f08d3cef4875274"
format_stamp: "Formatted at 2026-08-12 06:18:51 on dist-test-slave-zpvx"
I20260812 06:18:51.270978 29294 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-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:51.295944 29294 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:51.296300 29294 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:51.300110 29294 rpc_server.cc:307] RPC server started. Bound to: 127.28.155.190:37289
I20260812 06:18:51.303721 29724 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:51.303805 29723 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.155.190:37289 every 8 connection(s)
I20260812 06:18:51.316672 29724 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274: Bootstrap starting.
I20260812 06:18:51.317487 29724 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:51.318445 29724 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274: No bootstrap required, opened a new log
I20260812 06:18:51.318831 29724 raft_consensus.cc:359] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6c338cb62fa542968f08d3cef4875274" member_type: VOTER }
I20260812 06:18:51.318941 29724 raft_consensus.cc:385] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:51.318993 29724 raft_consensus.cc:740] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 6c338cb62fa542968f08d3cef4875274, State: Initialized, Role: FOLLOWER
I20260812 06:18:51.319156 29724 consensus_queue.cc:260] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [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: "6c338cb62fa542968f08d3cef4875274" member_type: VOTER }
I20260812 06:18:51.319249 29724 raft_consensus.cc:399] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:51.319296 29724 raft_consensus.cc:493] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:51.319356 29724 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:51.319972 29724 raft_consensus.cc:515] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6c338cb62fa542968f08d3cef4875274" member_type: VOTER }
I20260812 06:18:51.320163 29724 leader_election.cc:304] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [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: 6c338cb62fa542968f08d3cef4875274; no voters: 
I20260812 06:18:51.320361 29724 leader_election.cc:290] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:51.320516 29730 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:51.320730 29730 raft_consensus.cc:697] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 1 LEADER]: Becoming Leader. State: Replica: 6c338cb62fa542968f08d3cef4875274, State: Running, Role: LEADER
I20260812 06:18:51.320883 29724 sys_catalog.cc:565] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:51.320861 29730 consensus_queue.cc:237] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [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: "6c338cb62fa542968f08d3cef4875274" member_type: VOTER }
I20260812 06:18:51.321329 29733 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 6c338cb62fa542968f08d3cef4875274. Latest consensus state: current_term: 1 leader_uuid: "6c338cb62fa542968f08d3cef4875274" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6c338cb62fa542968f08d3cef4875274" member_type: VOTER } }
I20260812 06:18:51.321316 29732 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "6c338cb62fa542968f08d3cef4875274" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6c338cb62fa542968f08d3cef4875274" member_type: VOTER } }
I20260812 06:18:51.321456 29733 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:51.321530 29732 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:51.322023 29740 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:51.322882 29740 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:51.323098 29294 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:18:51.324855 29740 catalog_manager.cc:1383] Generated new cluster ID: 6237b5989a3c409e96c3995471e716c1
I20260812 06:18:51.324903 29740 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:51.337738 29740 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:51.338223 29740 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:51.342159 29740 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274: Generated new TSK 0
I20260812 06:18:51.342346 29740 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:51.355517 29294 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:51.357378 29761 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:51.357479 29762 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:51.357507 29764 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:51.357530 29294 server_base.cc:1061] running on GCE node
I20260812 06:18:51.357828 29294 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:51.357864 29294 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:51.357880 29294 hybrid_clock.cc:648] HybridClock initialized: now 1786515531357880 us; error 0 us; skew 500 ppm
I20260812 06:18:51.358649 29294 webserver.cc:533] Webserver started at http://127.28.155.129:33337/ using document root <none> and password file <none>
I20260812 06:18:51.358788 29294 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:51.358829 29294 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:51.358883 29294 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:51.359196 29294 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/instance:
uuid: "0488061a2a0a411ca04dd791bc023546"
format_stamp: "Formatted at 2026-08-12 06:18:51 on dist-test-slave-zpvx"
I20260812 06:18:51.360622 29294 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:18:51.361570 29769 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:51.361893 29294 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.000s
I20260812 06:18:51.361961 29294 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root
uuid: "0488061a2a0a411ca04dd791bc023546"
format_stamp: "Formatted at 2026-08-12 06:18:51 on dist-test-slave-zpvx"
I20260812 06:18:51.362057 29294 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-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:51.377561 29294 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:51.377832 29294 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:51.378064 29294 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:51.378571 29294 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:51.378611 29294 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:51.378667 29294 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:51.378703 29294 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:51.382874 29294 rpc_server.cc:307] RPC server started. Bound to: 127.28.155.129:38225
I20260812 06:18:51.382908 29871 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.155.129:38225 every 8 connection(s)
I20260812 06:18:51.392690 29872 heartbeater.cc:344] Connected to a master server at 127.28.155.190:37289
I20260812 06:18:51.392809 29872 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:51.393028 29872 heartbeater.cc:507] Master 127.28.155.190:37289 requested a full tablet report, sending...
I20260812 06:18:51.393684 29666 ts_manager.cc:194] Registered new tserver with Master: 0488061a2a0a411ca04dd791bc023546 (127.28.155.129:38225)
I20260812 06:18:51.394325 29666 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:35492
I20260812 06:18:51.394454 29294 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.011135643s
I20260812 06:18:51.401548 29666 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:35494:
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:51.410036 29809 tablet_service.cc:1511] Processing CreateTablet for tablet 499c2a6e5b8a4dee877fc06218b5c3a5 (DEFAULT_TABLE table=heavy-update-compaction-test [id=f75ebafc5f1548b0bcfb1f27fc4cb159]), partition=
I20260812 06:18:51.410311 29809 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 499c2a6e5b8a4dee877fc06218b5c3a5. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:51.412118 29895 tablet_bootstrap.cc:492] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Bootstrap starting.
I20260812 06:18:51.413074 29895 tablet_bootstrap.cc:654] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:51.414050 29895 tablet_bootstrap.cc:492] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: No bootstrap required, opened a new log
I20260812 06:18:51.414124 29895 ts_tablet_manager.cc:1403] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:18:51.414499 29895 raft_consensus.cc:359] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0488061a2a0a411ca04dd791bc023546" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 38225 } }
I20260812 06:18:51.414582 29895 raft_consensus.cc:385] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:51.414604 29895 raft_consensus.cc:740] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0488061a2a0a411ca04dd791bc023546, State: Initialized, Role: FOLLOWER
I20260812 06:18:51.414700 29895 consensus_queue.cc:260] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [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: "0488061a2a0a411ca04dd791bc023546" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 38225 } }
I20260812 06:18:51.414759 29895 raft_consensus.cc:399] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:51.414783 29895 raft_consensus.cc:493] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:51.414810 29895 raft_consensus.cc:3060] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:51.415684 29895 raft_consensus.cc:515] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0488061a2a0a411ca04dd791bc023546" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 38225 } }
I20260812 06:18:51.415869 29895 leader_election.cc:304] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [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: 0488061a2a0a411ca04dd791bc023546; no voters: 
I20260812 06:18:51.416085 29895 leader_election.cc:290] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:51.416216 29899 raft_consensus.cc:2804] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:51.416461 29895 ts_tablet_manager.cc:1434] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:18:51.416507 29872 heartbeater.cc:499] Master 127.28.155.190:37289 was elected leader, sending a full tablet report...
I20260812 06:18:51.416507 29899 raft_consensus.cc:697] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 1 LEADER]: Becoming Leader. State: Replica: 0488061a2a0a411ca04dd791bc023546, State: Running, Role: LEADER
I20260812 06:18:51.416865 29899 consensus_queue.cc:237] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [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: "0488061a2a0a411ca04dd791bc023546" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 38225 } }
I20260812 06:18:51.418213 29666 catalog_manager.cc:5719] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 reported cstate change: term changed from 0 to 1, leader changed from <none> to 0488061a2a0a411ca04dd791bc023546 (127.28.155.129). New cstate: current_term: 1 leader_uuid: "0488061a2a0a411ca04dd791bc023546" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0488061a2a0a411ca04dd791bc023546" member_type: VOTER last_known_addr { host: "127.28.155.129" port: 38225 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:51.477669 29294 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.052s	user 0.018s	sys 0.004s
I20260812 06:18:51.633826 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushMRSOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=19.054940
I20260812 06:18:51.817818 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushMRSOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.184s	user 0.131s	sys 0.048s Metrics: {"bytes_written":12307490,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":67,"dirs.run_cpu_time_us":199,"dirs.run_wall_time_us":872,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":48853,"lbm_writes_lt_1ms":857,"peak_mem_usage":0,"reinsert_count":0,"rows_written":105,"spinlock_wait_cycles":896,"update_count":1500}
I20260812 06:18:51.818557 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling LogGCOp(499c2a6e5b8a4dee877fc06218b5c3a5): free 20743831 bytes of WAL
I20260812 06:18:51.818917 29776 log_reader.cc:385] T 499c2a6e5b8a4dee877fc06218b5c3a5: removed 2 log segments from log reader
I20260812 06:18:51.819020 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000001 (ops 1-6)
I20260812 06:18:51.819093 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000002 (ops 7-11)
I20260812 06:18:51.824185 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: LogGCOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.005s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:18:51.824558 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling UndoDeltaBlockGCOp(499c2a6e5b8a4dee877fc06218b5c3a5): 20513816 bytes on disk
I20260812 06:18:51.824991 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: UndoDeltaBlockGCOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4}
I20260812 06:18:51.825379 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=2.188937
I20260812 06:18:51.847517 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.022s	user 0.013s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5896,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:51.848081 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=2.188937
I20260812 06:18:51.891062 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.043s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4779,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:51.891631 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=2.188937
I20260812 06:18:51.992491 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.101s	user 0.012s	sys 0.010s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5560,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:51.993149 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=6.157687
I20260812 06:18:52.088320 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.095s	user 0.018s	sys 0.005s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10009,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:52.088827 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=7.149875
I20260812 06:18:52.191711 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.103s	user 0.016s	sys 0.004s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":9051,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:18:52.192358 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=10.126437
I20260812 06:18:52.294166 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.102s	user 0.020s	sys 0.016s Metrics: {"bytes_written":11897249,"delete_count":0,"lbm_write_time_us":15902,"lbm_writes_lt_1ms":293,"reinsert_count":0,"update_count":1450}
I20260812 06:18:52.294724 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=6.157687
I20260812 06:18:52.395608 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.101s	user 0.010s	sys 0.012s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":9709,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:52.396308 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=9.134250
I20260812 06:18:52.494656 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.098s	user 0.009s	sys 0.018s Metrics: {"bytes_written":10461404,"delete_count":0,"lbm_write_time_us":12102,"lbm_writes_lt_1ms":258,"reinsert_count":0,"update_count":1275}
I20260812 06:18:52.495280 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=8.142062
I20260812 06:18:52.600956 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.105s	user 0.028s	sys 0.004s Metrics: {"bytes_written":10051172,"delete_count":0,"lbm_write_time_us":13191,"lbm_writes_lt_1ms":248,"reinsert_count":0,"update_count":1225}
I20260812 06:18:52.601661 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=6.157687
I20260812 06:18:52.706655 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.105s	user 0.019s	sys 0.008s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":12065,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:52.707232 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=7.149875
I20260812 06:18:52.810120 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.103s	user 0.018s	sys 0.004s Metrics: {"bytes_written":8615324,"delete_count":0,"lbm_write_time_us":10206,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:18:52.810654 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=10.126437
I20260812 06:18:52.911513 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.101s	user 0.019s	sys 0.017s Metrics: {"bytes_written":11897249,"delete_count":0,"lbm_write_time_us":16344,"lbm_writes_lt_1ms":293,"reinsert_count":0,"update_count":1450}
I20260812 06:18:52.912050 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=6.157687
I20260812 06:18:53.009492 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.097s	user 0.017s	sys 0.007s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10136,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:53.010051 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=7.149875
I20260812 06:18:53.112574 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.102s	user 0.013s	sys 0.008s Metrics: {"bytes_written":8820441,"delete_count":0,"lbm_write_time_us":8875,"lbm_writes_lt_1ms":218,"reinsert_count":0,"update_count":1075}
I20260812 06:18:53.113319 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=10.126437
I20260812 06:18:53.213099 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.100s	user 0.037s	sys 0.001s Metrics: {"bytes_written":11692138,"delete_count":0,"lbm_write_time_us":16166,"lbm_writes_lt_1ms":288,"reinsert_count":0,"update_count":1425}
I20260812 06:18:53.213642 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=6.157687
I20260812 06:18:53.315658 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.102s	user 0.016s	sys 0.008s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10385,"lbm_writes_lt_1ms":203,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":1000}
I20260812 06:18:53.316329 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=9.134250
I20260812 06:18:53.418318 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.102s	user 0.015s	sys 0.017s Metrics: {"bytes_written":11322911,"delete_count":0,"lbm_write_time_us":15381,"lbm_writes_lt_1ms":279,"reinsert_count":0,"update_count":1380}
I20260812 06:18:53.418838 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=7.149875
I20260812 06:18:53.447245 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.028s	user 0.012s	sys 0.013s Metrics: {"bytes_written":9189661,"delete_count":0,"lbm_write_time_us":10756,"lbm_writes_lt_1ms":227,"reinsert_count":0,"update_count":1120}
I20260812 06:18:53.448463 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushMRSOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=1.000000
I20260812 06:18:53.506093 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushMRSOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.057s	user 0.032s	sys 0.009s Metrics: {"bytes_written":1726345,"cfile_init":1,"dirs.queue_time_us":918,"dirs.run_cpu_time_us":239,"dirs.run_wall_time_us":3929,"drs_written":1,"lbm_read_time_us":114,"lbm_reads_lt_1ms":4,"lbm_write_time_us":3475,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":42,"thread_start_us":135,"threads_started":1}
I20260812 06:18:53.506827 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling LogGCOp(499c2a6e5b8a4dee877fc06218b5c3a5): free 174594527 bytes of WAL
I20260812 06:18:53.507082 29776 log_reader.cc:385] T 499c2a6e5b8a4dee877fc06218b5c3a5: removed 17 log segments from log reader
I20260812 06:18:53.507149 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000003 (ops 12-16)
I20260812 06:18:53.507202 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000004 (ops 17-21)
I20260812 06:18:53.507239 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000005 (ops 22-26)
I20260812 06:18:53.507277 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000006 (ops 27-31)
I20260812 06:18:53.507316 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000007 (ops 32-36)
I20260812 06:18:53.507354 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000008 (ops 37-41)
I20260812 06:18:53.507390 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000009 (ops 42-46)
I20260812 06:18:53.507427 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000010 (ops 47-50)
I20260812 06:18:53.507464 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000011 (ops 51-55)
I20260812 06:18:53.507501 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000012 (ops 56-60)
I20260812 06:18:53.507537 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000013 (ops 61-65)
I20260812 06:18:53.507575 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000014 (ops 66-70)
I20260812 06:18:53.507612 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000015 (ops 71-75)
I20260812 06:18:53.507647 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000016 (ops 76-80)
I20260812 06:18:53.507685 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000017 (ops 81-85)
I20260812 06:18:53.507722 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000018 (ops 86-90)
I20260812 06:18:53.507758 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000019 (ops 91-95)
I20260812 06:18:53.545040 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: LogGCOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.038s	user 0.000s	sys 0.037s Metrics: {}
I20260812 06:18:53.545420 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=10.126437
I20260812 06:18:53.587633 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.042s	user 0.020s	sys 0.009s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":14001,"lbm_writes_lt_1ms":303,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":1500}
I20260812 06:18:53.588047 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling UndoDeltaBlockGCOp(499c2a6e5b8a4dee877fc06218b5c3a5): 607 bytes on disk
I20260812 06:18:53.588485 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: UndoDeltaBlockGCOp(499c2a6e5b8a4dee877fc06218b5c3a5) 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:53.588889 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=2.188937
I20260812 06:18:53.602239 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.013s	user 0.007s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4771,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:53.602811 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling MajorDeltaCompactionOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=1.000000
I20260812 06:18:54.796372 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: MajorDeltaCompactionOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 1.193s	user 0.718s	sys 0.472s Metrics: {"cfile_cache_miss":4551,"cfile_cache_miss_bytes":188914470,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":21,"delta_iterators_relevant":21,"dirs.queue_time_us":1240,"lbm_read_time_us":74368,"lbm_reads_lt_1ms":4587,"lbm_write_time_us":229743,"lbm_writes_lt_1ms":4547,"peak_mem_usage":560178780,"reinsert_count":0,"spinlock_wait_cycles":5376,"thread_start_us":493,"threads_started":7,"update_count":22500}
I20260812 06:18:54.797217 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=81.563937
I20260812 06:18:55.132964 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.336s	user 0.168s	sys 0.065s Metrics: {"bytes_written":86151116,"delete_count":0,"lbm_write_time_us":101680,"lbm_writes_lt_1ms":2105,"reinsert_count":0,"update_count":10500}
I20260812 06:18:55.133684 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=22.032687
I20260812 06:18:55.317622 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.184s	user 0.045s	sys 0.020s Metrics: {"bytes_written":24614724,"delete_count":0,"lbm_write_time_us":29642,"lbm_writes_lt_1ms":603,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":3000}
I20260812 06:18:55.318228 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=14.095187
I20260812 06:18:55.468538 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.150s	user 0.025s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":17843,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:55.469468 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=14.095187
I20260812 06:18:55.529800 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.060s	user 0.030s	sys 0.023s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":23340,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:55.530432 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=3.181125
I20260812 06:18:55.548003 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.017s	user 0.012s	sys 0.004s Metrics: {"bytes_written":5005191,"delete_count":0,"lbm_write_time_us":7451,"lbm_writes_lt_1ms":125,"reinsert_count":0,"update_count":610}
I20260812 06:18:55.548503 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=2.188937
I20260812 06:18:55.557108 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.008s	user 0.006s	sys 0.002s Metrics: {"bytes_written":3200105,"delete_count":0,"lbm_write_time_us":3249,"lbm_writes_lt_1ms":81,"reinsert_count":0,"update_count":390}
I20260812 06:18:55.557475 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushMRSOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=1.000000
I20260812 06:18:55.592599 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushMRSOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.035s	user 0.028s	sys 0.005s Metrics: {"bytes_written":1644372,"cfile_init":1,"dirs.queue_time_us":65,"dirs.run_cpu_time_us":244,"dirs.run_wall_time_us":1252,"drs_written":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1685,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":40}
I20260812 06:18:55.593227 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling LogGCOp(499c2a6e5b8a4dee877fc06218b5c3a5): free 170437710 bytes of WAL
I20260812 06:18:55.593458 29776 log_reader.cc:385] T 499c2a6e5b8a4dee877fc06218b5c3a5: removed 17 log segments from log reader
I20260812 06:18:55.593506 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000020 (ops 96-100)
I20260812 06:18:55.593534 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000021 (ops 101-105)
I20260812 06:18:55.593598 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000022 (ops 106-110)
I20260812 06:18:55.593660 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000023 (ops 111-115)
I20260812 06:18:55.593704 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000024 (ops 116-120)
I20260812 06:18:55.593744 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000025 (ops 121-125)
I20260812 06:18:55.593787 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000026 (ops 126-130)
I20260812 06:18:55.593827 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000027 (ops 131-135)
I20260812 06:18:55.593863 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000028 (ops 136-140)
I20260812 06:18:55.593902 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000029 (ops 141-144)
I20260812 06:18:55.593941 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000030 (ops 145-149)
I20260812 06:18:55.593981 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000031 (ops 150-154)
I20260812 06:18:55.594019 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000032 (ops 155-158)
I20260812 06:18:55.594058 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000033 (ops 159-163)
I20260812 06:18:55.594096 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000034 (ops 164-168)
I20260812 06:18:55.594137 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000035 (ops 169-172)
I20260812 06:18:55.594173 29776 log.cc:1079] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: Deleting log segment in path: /tmp/dist-test-taskbM1aDL/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525847930-29294-0/minicluster-data/ts-0-root/wals/499c2a6e5b8a4dee877fc06218b5c3a5/wal-000000036 (ops 173-177)
I20260812 06:18:55.630705 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: LogGCOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.037s	user 0.004s	sys 0.031s Metrics: {}
I20260812 06:18:55.631115 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling UndoDeltaBlockGCOp(499c2a6e5b8a4dee877fc06218b5c3a5): 583 bytes on disk
I20260812 06:18:55.631577 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: UndoDeltaBlockGCOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4}
I20260812 06:18:55.632050 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=6.157687
I20260812 06:18:55.659644 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: FlushDeltaMemStoresOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.027s	user 0.015s	sys 0.004s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":9127,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:55.660153 29875 maintenance_manager.cc:419] P 0488061a2a0a411ca04dd791bc023546: Scheduling MajorDeltaCompactionOp(499c2a6e5b8a4dee877fc06218b5c3a5): perf score=1.000000
I20260812 06:18:56.230916 29294 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.753s	user 1.734s	sys 0.129s
I20260812 06:18:56.574920 29294 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.344s	user 0.001s	sys 0.000s
I20260812 06:18:56.575536 29294 tablet_server.cc:179] TabletServer@127.28.155.129:0 shutting down...
I20260812 06:18:56.641000 29776 maintenance_manager.cc:643] P 0488061a2a0a411ca04dd791bc023546: MajorDeltaCompactionOp(499c2a6e5b8a4dee877fc06218b5c3a5) complete. Timing: real 0.981s	user 0.596s	sys 0.384s Metrics: {"cfile_cache_miss":3939,"cfile_cache_miss_bytes":164298478,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":7,"delta_iterators_relevant":7,"dirs.queue_time_us":1432,"lbm_read_time_us":71574,"lbm_reads_lt_1ms":3967,"lbm_write_time_us":167880,"lbm_writes_lt_1ms":3946,"mutex_wait_us":69,"peak_mem_usage":485619348,"reinsert_count":0,"spinlock_wait_cycles":45952,"thread_start_us":477,"threads_started":7,"update_count":19500}
I20260812 06:18:56.641644 29294 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:56.641935 29294 tablet_replica.cc:333] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546: stopping tablet replica
I20260812 06:18:56.642107 29294 raft_consensus.cc:2243] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:56.642328 29294 raft_consensus.cc:2272] T 499c2a6e5b8a4dee877fc06218b5c3a5 P 0488061a2a0a411ca04dd791bc023546 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:56.664494 29294 tablet_server.cc:196] TabletServer@127.28.155.129:0 shutdown complete.
I20260812 06:18:57.237439 29294 master.cc:562] Master@127.28.155.190:37289 shutting down...
I20260812 06:18:57.241711 29294 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:57.241909 29294 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:57.242005 29294 tablet_replica.cc:333] T 00000000000000000000000000000000 P 6c338cb62fa542968f08d3cef4875274: stopping tablet replica
I20260812 06:18:57.254385 29294 master.cc:584] Master@127.28.155.190:37289 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (6072 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (11479 ms total)

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